[ 9.609419][ T187] ip (187) used greatest stack depth: 24560 bytes left [ 9.609436][ T187] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.609438][ T187] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 187, name: ip [ 9.609439][ T187] preempt_count: 2, expected: 0 [ 9.609440][ T187] RCU nest depth: 0, expected: 0 [ 9.609441][ T187] locks held by ip/187: 5, last CPU#2: [ 9.609443][ T187] #0: ffffffff98e027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.609456][ T187] #1: ffffffff98f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.609462][ T187] #2: ffffffff98f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.609466][ T187] #3: ffffffff98e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.609471][ T187] #4: ffffffff98e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.609477][ T187] irq event stamp: 33082 [ 9.609478][ T187] hardirqs last enabled at (33081): [] __down_trylock_console_sem+0x86/0xa0 [ 9.609481][ T187] hardirqs last disabled at (33082): [] console_emit_next_record+0x3d4/0x4c0 [ 9.609484][ T187] softirqs last enabled at (31792): [] netlink_release+0x17b/0xcf0 [ 9.609488][ T187] softirqs last disabled at (31790): [] netlink_release+0xd2/0xcf0 [ 9.609490][ T187] Preemption disabled at: [ 9.609491][ T187] [<0000000000000000>] 0x0 [ 9.609498][ T187] CPU: 2 UID: 0 PID: 187 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.609501][ T187] Tainted: [W]=WARN [ 9.609502][ T187] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.609504][ T187] Call Trace: [ 9.609506][ T187] [ 9.609507][ T187] dump_stack_lvl+0x6f/0xa0 [ 9.609514][ T187] __might_resched.cold+0x1fe/0x2c1 [ 9.609518][ T187] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.609523][ T187] ? __kmalloc_noprof+0xdb/0x760 [ 9.609528][ T187] __kmalloc_noprof+0x443/0x760 [ 9.609530][ T187] ? alloc_buf.isra.0+0x4b/0x260 [ 9.609536][ T187] ? do_raw_spin_unlock+0x59/0x250 [ 9.609539][ T187] alloc_buf.isra.0+0x4b/0x260 [ 9.609542][ T187] put_chars+0x1e1/0x2f0 [ 9.609545][ T187] ? __send_to_port+0x420/0x420 [ 9.609546][ T187] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.609551][ T187] ? validate_chain+0x38b/0xc20 [ 9.609556][ T187] hvc_console_print+0x292/0x780 [ 9.609563][ T187] ? hvc_write+0x3a0/0x3a0 [ 9.609565][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.609567][ T187] ? lock_acquire+0x13c/0x160 [ 9.609571][ T187] console_emit_next_record+0x22f/0x4c0 [ 9.609575][ T187] ? devkmsg_read+0x4b0/0x4b0 [ 9.609577][ T187] ? console_flush_one_record+0x106/0x710 [ 9.609580][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.609583][ T187] ? lock_acquire+0x13c/0x160 [ 9.609587][ T187] console_flush_one_record+0x46f/0x710 [ 9.609591][ T187] ? console_emit_next_record+0x4c0/0x4c0 [ 9.609593][ T187] ? __lock_acquire+0x518/0xc20 [ 9.609598][ T187] console_unlock+0xee/0x1f0 [ 9.609601][ T187] ? console_flush_one_record+0x710/0x710 [ 9.609603][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.609605][ T187] ? lock_acquire+0x60/0x160 [ 9.609609][ T187] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.609610][ T187] ? vprintk_emit+0x320/0x3e0 [ 9.609614][ T187] vprintk_emit+0x37c/0x3e0 [ 9.609618][ T187] ? wake_up_klogd_work_func+0x90/0x90 [ 9.609621][ T187] ? __lock_acquire+0x518/0xc20 [ 9.609625][ T187] _printk+0xc7/0x100 [ 9.609629][ T187] ? snapshot_read.cold+0x21/0x21 [ 9.609632][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.609634][ T187] ? __rwlock_init+0x150/0x150 [ 9.609638][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.609641][ T187] do_exit.cold+0x82/0x9c [ 9.609645][ T187] ? exit_notify+0x890/0x890 [ 9.609647][ T187] ? __lock_release.isra.0+0x69/0x1a0 [ 9.609650][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.609654][ T187] do_group_exit+0xb8/0x370 [ 9.609657][ T187] __x64_sys_exit_group+0x3c/0x50 [ 9.609659][ T187] x64_sys_call+0x1567/0x1570 [ 9.609661][ T187] do_syscall_64+0xff/0x530 [ 9.609664][ T187] ? exc_page_fault+0xee/0x100 [ 9.609667][ T187] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.609670][ T187] RIP: 0033:0x7f4aa9b081b8 [ 9.609672][ T187] Code: Unable to access opcode bytes at 0x7f4aa9b0818e. [ 9.609674][ T187] RSP: 002b:00007ffebdaa5278 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.609676][ T187] RAX: ffffffffffffffda RBX: 00007f4aa9c38f88 RCX: 00007f4aa9b081b8 [ 9.609677][ T187] RDX: 00007f4aa9852fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.609678][ T187] RBP: 00007ffebdaa52d0 R08: 0000000000000000 R09: 0000000000008010 [ 9.609679][ T187] R10: 00007ffebdaa5090 R11: 0000000000000246 R12: 0000000000000001 [ 9.609680][ T187] R13: 0000000000000000 R14: 00007f4aa9c37680 R15: 00007f4aa9c38fa0 [ 9.609687][ T187] [ 11.487715][ T268] br1: port 1(vx1) entered blocking state [ 11.487787][ T268] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.487789][ T268] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 268, name: ip [ 11.487791][ T268] preempt_count: 1, expected: 0 [ 11.487792][ T268] RCU nest depth: 0, expected: 0 [ 11.487793][ T268] locks held by ip/268: 5, last CPU#1: [ 11.487795][ T268] #0: ffffffff996d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.487807][ T268] #1: ffffffff98f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.487813][ T268] #2: ffffffff98f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.487817][ T268] #3: ffffffff98e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.487821][ T268] #4: ffffffff98e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.487824][ T268] irq event stamp: 12550 [ 11.487825][ T268] hardirqs last enabled at (12549): [] __down_trylock_console_sem+0x86/0xa0 [ 11.487828][ T268] hardirqs last disabled at (12550): [] console_emit_next_record+0x3d4/0x4c0 [ 11.487830][ T268] softirqs last enabled at (12448): [] __alloc_skb+0x4c2/0x5f0 [ 11.487833][ T268] softirqs last disabled at (12446): [] __alloc_skb+0x4c2/0x5f0 [ 11.487836][ T268] Preemption disabled at: [ 11.487836][ T268] [] vprintk_emit+0x31b/0x3e0 [ 11.487842][ T268] CPU: 1 UID: 0 PID: 268 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.487845][ T268] Tainted: [W]=WARN [ 11.487846][ T268] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.487848][ T268] Call Trace: [ 11.487849][ T268] [ 11.487851][ T268] dump_stack_lvl+0x6f/0xa0 [ 11.487856][ T268] ? vprintk_emit+0x31b/0x3e0 [ 11.487859][ T268] __might_resched.cold+0x1fe/0x2c1 [ 11.487863][ T268] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.487868][ T268] ? __kmalloc_noprof+0xdb/0x760 [ 11.487873][ T268] __kmalloc_noprof+0x443/0x760 [ 11.487875][ T268] ? alloc_buf.isra.0+0x4b/0x260 [ 11.487881][ T268] ? do_raw_spin_unlock+0x59/0x250 [ 11.487883][ T268] alloc_buf.isra.0+0x4b/0x260 [ 11.487886][ T268] put_chars+0x1e1/0x2f0 [ 11.487889][ T268] ? __send_to_port+0x420/0x420 [ 11.487893][ T268] ? validate_chain+0x34a/0xc20 [ 11.487897][ T268] hvc_console_print+0x292/0x780 [ 11.487900][ T268] ? mark_usage+0x61/0x170 [ 11.487901][ T268] ? __lock_acquire+0x518/0xc20 [ 11.487903][ T268] ? __lock_acquire+0x518/0xc20 [ 11.487907][ T268] ? hvc_write+0x3a0/0x3a0 [ 11.487909][ T268] ? console_emit_next_record+0x1df/0x4c0 [ 11.487912][ T268] ? rcu_is_watching+0x16/0xd0 [ 11.487916][ T268] ? lock_acquire+0x13c/0x160 [ 11.487919][ T268] console_emit_next_record+0x22f/0x4c0 [ 11.487923][ T268] ? devkmsg_read+0x4b0/0x4b0 [ 11.487925][ T268] ? console_flush_one_record+0x106/0x710 [ 11.487928][ T268] ? rcu_is_watching+0x16/0xd0 [ 11.487930][ T268] ? lock_acquire+0x13c/0x160 [ 11.487934][ T268] console_flush_one_record+0x46f/0x710 [ 11.487938][ T268] ? console_emit_next_record+0x4c0/0x4c0 [ 11.487940][ T268] ? __lock_acquire+0x518/0xc20 [ 11.487945][ T268] console_unlock+0xee/0x1f0 [ 11.487947][ T268] ? console_flush_one_record+0x710/0x710 [ 11.487949][ T268] ? rcu_is_watching+0x16/0xd0 [ 11.487951][ T268] ? lock_acquire+0x60/0x160 [ 11.487955][ T268] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.487957][ T268] ? vprintk_emit+0x320/0x3e0 [ 11.487960][ T268] vprintk_emit+0x37c/0x3e0 [ 11.487968][ T268] ? wake_up_klogd_work_func+0x90/0x90 [ 11.487970][ T268] ? __lock_release.isra.0+0x69/0x1a0 [ 11.487972][ T268] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.487976][ T268] ? mark_held_locks+0x40/0x70 [ 11.487979][ T268] _printk+0xc7/0x100 [ 11.487983][ T268] ? snapshot_read.cold+0x21/0x21 [ 11.487986][ T268] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.487999][ T268] ? do_setlink.isra.0+0xa31/0x2750 [ 11.488001][ T268] ? rtnl_newlink+0x9f1/0x11c0 [ 11.488002][ T268] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.488006][ T268] br_set_state+0x22f/0x430 [bridge] [ 11.488016][ T268] br_init_port+0xc4/0x200 [bridge] [ 11.488024][ T268] new_nbp+0x39c/0x580 [bridge] [ 11.488033][ T268] br_add_if+0x212/0x1320 [bridge] [ 11.488040][ T268] ? is_bpf_text_address+0x72/0x110 [ 11.488044][ T268] ? kernel_text_address+0x149/0x170 [ 11.488047][ T268] ? __kernel_text_address+0x12/0x30 [ 11.488050][ T268] do_set_master+0x357/0x580 [ 11.488055][ T268] do_setlink.isra.0+0xa31/0x2750 [ 11.488058][ T268] ? stack_trace_save+0x93/0xc0 [ 11.488061][ T268] ? rtnl_link_get_size+0x350/0x350 [ 11.488062][ T268] ? rcu_read_lock_any_held+0x66/0x90 [ 11.488065][ T268] ? stack_depot_save_flags+0x38e/0x790 [ 11.488068][ T268] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.488071][ T268] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.488073][ T268] ? validate_chain+0x38b/0xc20 [ 11.488075][ T268] ? kasan_save_stack+0x3d/0x50 [ 11.488079][ T268] ? kasan_save_stack+0x2f/0x50 [ 11.488080][ T268] ? kasan_save_track+0x14/0x30 [ 11.488083][ T268] ? __lock_acquire+0x518/0xc20 [ 11.488085][ T268] ? netlink_seq_next+0x4e/0x60 [ 11.488088][ T268] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.488092][ T268] ? lock_acquire.part.0+0xd4/0x280 [ 11.488094][ T268] ? rtnl_newlink+0x9a8/0x11c0 [ 11.488096][ T268] ? rcu_is_watching+0x16/0xd0 [ 11.488099][ T268] ? lock_acquire+0x13c/0x160 [ 11.488100][ T268] ? rcu_is_watching+0x16/0xd0 [ 11.488102][ T268] ? rcu_is_watching+0x16/0xd0 [ 11.488104][ T268] ? trace_contention_end+0xb3/0x180 [ 11.488108][ T268] ? __mutex_lock+0x1db/0x1ea0 [ 11.488110][ T268] ? __mutex_lock+0x9a3/0x1ea0 [ 11.488112][ T268] ? rtnl_newlink+0x9a8/0x11c0 [ 11.488115][ T268] ? ww_mutex_lock+0x160/0x160 [ 11.488117][ T268] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.488122][ T268] ? __rtnl_newlink+0x3fa/0xa50 [ 11.488127][ T268] rtnl_newlink+0x9f1/0x11c0 [ 11.488132][ T268] ? rtnl_bridge_getlink+0x850/0x850 [ 11.488134][ T268] ? __lock_acquire+0x518/0xc20 [ 11.488138][ T268] ? lock_acquire.part.0+0xd4/0x280 [ 11.488140][ T268] ? find_held_lock+0x2b/0x80 [ 11.488142][ T268] ? rtnl_bridge_getlink+0x850/0x850 [ 11.488144][ T268] ? __lock_release.isra.0+0x69/0x1a0 [ 11.488147][ T268] ? rtnl_bridge_getlink+0x850/0x850 [ 11.488149][ T268] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.488152][ T268] ? rtnl_link_fill+0x920/0x920 [ 11.488153][ T268] ? __lock_acquire+0x518/0xc20 [ 11.488158][ T268] ? lock_acquire.part.0+0xd4/0x280 [ 11.488159][ T268] ? find_held_lock+0x2b/0x80 [ 11.488163][ T268] netlink_rcv_skb+0x14e/0x3a0 [ 11.488165][ T268] ? rtnl_link_fill+0x920/0x920 [ 11.488167][ T268] ? netlink_ack+0xcf0/0xcf0 [ 11.488173][ T268] ? netlink_deliver_tap+0xc5/0x330 [ 11.488175][ T268] ? netlink_deliver_tap+0x13c/0x330 [ 11.488179][ T268] netlink_unicast+0x486/0x750 [ 11.488182][ T268] ? netlink_attachskb+0x810/0x810 [ 11.488185][ T268] ? __lock_acquire+0x518/0xc20 [ 11.488189][ T268] netlink_sendmsg+0x735/0xc60 [ 11.488193][ T268] ? netlink_unicast+0x750/0x750 [ 11.488196][ T268] ? __might_fault+0x97/0x140 [ 11.488201][ T268] ____sys_sendmsg+0x415/0x880 [ 11.488204][ T268] ? copy_msghdr_from_user+0x279/0x420 [ 11.488206][ T268] ? get_timestamp.constprop.0+0x390/0x390 [ 11.488208][ T268] ? move_addr_to_kernel+0x40/0x40 [ 11.488215][ T268] ___sys_sendmsg+0x14e/0x1d0 [ 11.488217][ T268] ? copy_msghdr_from_user+0x420/0x420 [ 11.488232][ T268] __sys_sendmsg+0x12c/0x1d0 [ 11.488235][ T268] ? __sys_sendmsg_sock+0x20/0x20 [ 11.488241][ T268] ? rcu_is_watching+0x16/0xd0 [ 11.488245][ T268] do_syscall_64+0xff/0x530 [ 11.488247][ T268] ? exc_page_fault+0xee/0x100 [ 11.488250][ T268] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.488252][ T268] RIP: 0033:0x7f7d5532754e [ 11.488255][ T268] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 11.488257][ T268] RSP: 002b:00007ffe8f700aa0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.488260][ T268] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f7d5532754e [ 11.488262][ T268] RDX: 0000000000000000 RSI: 00007ffe8f700b50 RDI: 0000000000000005 [ 11.488263][ T268] RBP: 00007ffe8f700ab0 R08: 0000000000000000 R09: 0000000000000000 [ 11.488263][ T268] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a912b0e [ 11.488264][ T268] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe8f701210 [ 11.488271][ T268] [ 11.525986][ T268] br1: port 1(vx1) entered disabled state [ 11.526252][ T268] vx1: entered allmulticast mode [ 11.527912][ T268] vx1: entered promiscuous mode [ 11.528610][ T268] br1: port 1(vx1) entered blocking state [ 11.528983][ T268] br1: port 1(vx1) entered forwarding state [ 11.536613][ T268] ip (268) used greatest stack depth: 23336 bytes left [ 11.560550][ T269] br1: port 2(veth1) entered blocking state [ 11.560839][ T269] br1: port 2(veth1) entered disabled state [ 11.561121][ T269] veth1: entered allmulticast mode [ 11.562559][ T269] veth1: entered promiscuous mode [ 11.588287][ T189] br1: port 2(veth1) entered blocking state [ 11.588687][ T189] br1: port 2(veth1) entered forwarding state [ 11.644145][ T272] br1: port 3(veth2) entered blocking state [ 11.644534][ T272] br1: port 3(veth2) entered disabled state [ 11.644904][ T272] veth2: entered allmulticast mode [ 11.647127][ T272] veth2: entered promiscuous mode [ 11.677377][ T189] br1: port 3(veth2) entered blocking state [ 11.677773][ T189] br1: port 3(veth2) entered forwarding state [ 12.803152][ T317] br2: port 1(w1) entered blocking state [ 12.803169][ T317] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.803171][ T317] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 317, name: ip [ 12.803172][ T317] preempt_count: 1, expected: 0 [ 12.803173][ T317] RCU nest depth: 0, expected: 0 [ 12.803175][ T317] locks held by ip/317: 5, last CPU#0: [ 12.803177][ T317] #0: ffffffff996d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 12.803188][ T317] #1: ffffffff98f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.803195][ T317] #2: ffffffff98f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.803199][ T317] #3: ffffffff98e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.803203][ T317] #4: ffffffff98e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.803207][ T317] irq event stamp: 12638 [ 12.803208][ T317] hardirqs last enabled at (12637): [] __down_trylock_console_sem+0x86/0xa0 [ 12.803211][ T317] hardirqs last disabled at (12638): [] console_emit_next_record+0x3d4/0x4c0 [ 12.803213][ T317] softirqs last enabled at (12576): [] __alloc_skb+0x4c2/0x5f0 [ 12.803216][ T317] softirqs last disabled at (12574): [] __alloc_skb+0x4c2/0x5f0 [ 12.803219][ T317] Preemption disabled at: [ 12.803219][ T317] [] vprintk_emit+0x31b/0x3e0 [ 12.803225][ T317] CPU: 0 UID: 0 PID: 317 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.803229][ T317] Tainted: [W]=WARN [ 12.803230][ T317] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.803232][ T317] Call Trace: [ 12.803233][ T317] [ 12.803235][ T317] dump_stack_lvl+0x6f/0xa0 [ 12.803241][ T317] ? vprintk_emit+0x31b/0x3e0 [ 12.803243][ T317] __might_resched.cold+0x1fe/0x2c1 [ 12.803248][ T317] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.803252][ T317] ? __kmalloc_noprof+0xdb/0x760 [ 12.803258][ T317] __kmalloc_noprof+0x443/0x760 [ 12.803260][ T317] ? alloc_buf.isra.0+0x4b/0x260 [ 12.803266][ T317] ? do_raw_spin_unlock+0x59/0x250 [ 12.803269][ T317] alloc_buf.isra.0+0x4b/0x260 [ 12.803272][ T317] put_chars+0x1e1/0x2f0 [ 12.803274][ T317] ? prb_final_commit+0x50/0x50 [ 12.803276][ T317] ? __send_to_port+0x420/0x420 [ 12.803279][ T317] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.803284][ T317] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.803286][ T317] ? validate_chain+0x38b/0xc20 [ 12.803290][ T317] hvc_console_print+0x292/0x780 [ 12.803293][ T317] ? mark_usage+0x61/0x170 [ 12.803295][ T317] ? __lock_acquire+0x518/0xc20 [ 12.803297][ T317] ? __lock_acquire+0x518/0xc20 [ 12.803301][ T317] ? hvc_write+0x3a0/0x3a0 [ 12.803303][ T317] ? console_emit_next_record+0x1df/0x4c0 [ 12.803306][ T317] ? rcu_is_watching+0x16/0xd0 [ 12.803309][ T317] ? lock_acquire+0x13c/0x160 [ 12.803313][ T317] console_emit_next_record+0x22f/0x4c0 [ 12.803317][ T317] ? devkmsg_read+0x4b0/0x4b0 [ 12.803319][ T317] ? console_flush_one_record+0x106/0x710 [ 12.803322][ T317] ? rcu_is_watching+0x16/0xd0 [ 12.803325][ T317] ? lock_acquire+0x13c/0x160 [ 12.803329][ T317] console_flush_one_record+0x46f/0x710 [ 12.803333][ T317] ? console_emit_next_record+0x4c0/0x4c0 [ 12.803335][ T317] ? __lock_acquire+0x518/0xc20 [ 12.803340][ T317] console_unlock+0xee/0x1f0 [ 12.803343][ T317] ? console_flush_one_record+0x710/0x710 [ 12.803345][ T317] ? rcu_is_watching+0x16/0xd0 [ 12.803348][ T317] ? lock_acquire+0x60/0x160 [ 12.803352][ T317] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.803353][ T317] ? vprintk_emit+0x320/0x3e0 [ 12.803357][ T317] vprintk_emit+0x37c/0x3e0 [ 12.803361][ T317] ? wake_up_klogd_work_func+0x90/0x90 [ 12.803364][ T317] ? __lock_release.isra.0+0x69/0x1a0 [ 12.803366][ T317] ? mark_held_locks+0x40/0x70 [ 12.803370][ T317] _printk+0xc7/0x100 [ 12.803374][ T317] ? snapshot_read.cold+0x21/0x21 [ 12.803379][ T317] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 12.803392][ T317] ? do_setlink.isra.0+0xa31/0x2750 [ 12.803393][ T317] ? rtnl_newlink+0x9f1/0x11c0 [ 12.803394][ T317] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.803399][ T317] br_set_state+0x22f/0x430 [bridge] [ 12.803410][ T317] br_init_port+0xc4/0x200 [bridge] [ 12.803419][ T317] new_nbp+0x39c/0x580 [bridge] [ 12.803428][ T317] br_add_if+0x212/0x1320 [bridge] [ 12.803435][ T317] ? is_bpf_text_address+0x72/0x110 [ 12.803440][ T317] ? kernel_text_address+0x149/0x170 [ 12.803443][ T317] ? __kernel_text_address+0x12/0x30 [ 12.803447][ T317] do_set_master+0x357/0x580 [ 12.803452][ T317] do_setlink.isra.0+0xa31/0x2750 [ 12.803455][ T317] ? stack_trace_save+0x93/0xc0 [ 12.803458][ T317] ? rtnl_link_get_size+0x350/0x350 [ 12.803460][ T317] ? rcu_read_lock_any_held+0x66/0x90 [ 12.803462][ T317] ? stack_depot_save_flags+0x38e/0x790 [ 12.803466][ T317] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.803468][ T317] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.803471][ T317] ? validate_chain+0x38b/0xc20 [ 12.803476][ T317] ? kasan_save_stack+0x3d/0x50 [ 12.803480][ T317] ? kasan_save_stack+0x2f/0x50 [ 12.803482][ T317] ? kasan_save_track+0x14/0x30 [ 12.803486][ T317] ? __lock_acquire+0x518/0xc20 [ 12.803489][ T317] ? netlink_seq_next+0x4e/0x60 [ 12.803492][ T317] ? ___sys_sendmsg+0xb0/0x1d0 [ 12.803497][ T317] ? lock_acquire.part.0+0xd4/0x280 [ 12.803499][ T317] ? rtnl_newlink+0x9a8/0x11c0 [ 12.803501][ T317] ? rcu_is_watching+0x16/0xd0 [ 12.803504][ T317] ? lock_acquire+0x13c/0x160 [ 12.803505][ T317] ? rcu_is_watching+0x16/0xd0 [ 12.803507][ T317] ? rcu_is_watching+0x16/0xd0 [ 12.803510][ T317] ? trace_contention_end+0xb3/0x180 [ 12.803514][ T317] ? __mutex_lock+0x1db/0x1ea0 [ 12.803517][ T317] ? __mutex_lock+0x9a3/0x1ea0 [ 12.803519][ T317] ? rtnl_newlink+0x9a8/0x11c0 [ 12.803522][ T317] ? ww_mutex_lock+0x160/0x160 [ 12.803524][ T317] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.803529][ T317] ? __rtnl_newlink+0x3fa/0xa50 [ 12.803535][ T317] rtnl_newlink+0x9f1/0x11c0 [ 12.803540][ T317] ? rtnl_bridge_getlink+0x850/0x850 [ 12.803542][ T317] ? __lock_acquire+0x518/0xc20 [ 12.803546][ T317] ? lock_acquire.part.0+0xd4/0x280 [ 12.803548][ T317] ? find_held_lock+0x2b/0x80 [ 12.803550][ T317] ? rtnl_bridge_getlink+0x850/0x850 [ 12.803552][ T317] ? __lock_release.isra.0+0x69/0x1a0 [ 12.803556][ T317] ? rtnl_bridge_getlink+0x850/0x850 [ 12.803558][ T317] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.803561][ T317] ? rtnl_link_fill+0x920/0x920 [ 12.803563][ T317] ? __lock_acquire+0x518/0xc20 [ 12.803567][ T317] ? lock_acquire.part.0+0xd4/0x280 [ 12.803569][ T317] ? find_held_lock+0x2b/0x80 [ 12.803573][ T317] netlink_rcv_skb+0x14e/0x3a0 [ 12.803575][ T317] ? rtnl_link_fill+0x920/0x920 [ 12.803578][ T317] ? netlink_ack+0xcf0/0xcf0 [ 12.803584][ T317] ? netlink_deliver_tap+0xc5/0x330 [ 12.803586][ T317] ? netlink_deliver_tap+0x13c/0x330 [ 12.803590][ T317] netlink_unicast+0x486/0x750 [ 12.803594][ T317] ? netlink_attachskb+0x810/0x810 [ 12.803597][ T317] ? __lock_acquire+0x518/0xc20 [ 12.803601][ T317] netlink_sendmsg+0x735/0xc60 [ 12.803605][ T317] ? netlink_unicast+0x750/0x750 [ 12.803609][ T317] ? __might_fault+0x97/0x140 [ 12.803614][ T317] ____sys_sendmsg+0x415/0x880 [ 12.803617][ T317] ? copy_msghdr_from_user+0x279/0x420 [ 12.803619][ T317] ? get_timestamp.constprop.0+0x390/0x390 [ 12.803621][ T317] ? move_addr_to_kernel+0x40/0x40 [ 12.803628][ T317] ___sys_sendmsg+0x14e/0x1d0 [ 12.803631][ T317] ? copy_msghdr_from_user+0x420/0x420 [ 12.803647][ T317] __sys_sendmsg+0x12c/0x1d0 [ 12.803650][ T317] ? __sys_sendmsg_sock+0x20/0x20 [ 12.803656][ T317] ? rcu_is_watching+0x16/0xd0 [ 12.803660][ T317] do_syscall_64+0xff/0x530 [ 12.803662][ T317] ? exc_page_fault+0xee/0x100 [ 12.803666][ T317] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.803668][ T317] RIP: 0033:0x7f00d512454e [ 12.803671][ T317] 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.803673][ T317] RSP: 002b:00007fff4d1dfe40 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.803676][ T317] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f00d512454e [ 12.803678][ T317] RDX: 0000000000000000 RSI: 00007fff4d1dfef0 RDI: 0000000000000005 [ 12.803679][ T317] RBP: 00007fff4d1dfe50 R08: 0000000000000000 R09: 0000000000000000 [ 12.803680][ T317] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a912b10 [ 12.803680][ T317] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff4d1e05b0 [ 12.803687][ T317] [ 12.842274][ T317] br2: port 1(w1) entered disabled state [ 12.842579][ T317] w1: entered allmulticast mode [ 12.844170][ T317] w1: entered promiscuous mode [ 13.026978][ T323] br2: port 2(vx2) entered blocking state [ 13.027237][ T323] br2: port 2(vx2) entered disabled state [ 13.027469][ T323] vx2: entered allmulticast mode [ 13.029542][ T323] vx2: entered promiscuous mode [ 13.030179][ T323] br2: port 2(vx2) entered blocking state [ 13.030497][ T323] br2: port 2(vx2) entered forwarding state [ 13.233772][ T39] br2: port 1(w1) entered blocking state [ 13.234125][ T39] br2: port 1(w1) entered forwarding state [ 13.782055][ T355] br2: port 1(w1) entered blocking state [ 13.782325][ T355] br2: port 1(w1) entered disabled state [ 13.782564][ T355] w1: entered allmulticast mode [ 13.784535][ T355] w1: entered promiscuous mode [ 13.961989][ T361] br2: port 2(vx2) entered blocking state [ 13.962004][ T361] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.962007][ T361] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 361, name: ip [ 13.962008][ T361] preempt_count: 1, expected: 0 [ 13.962009][ T361] RCU nest depth: 0, expected: 0 [ 13.962010][ T361] locks held by ip/361: 5, last CPU#1: [ 13.962012][ T361] #0: ffffffff996d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 13.962024][ T361] #1: ffffffff98f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.962031][ T361] #2: ffffffff98f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.962034][ T361] #3: ffffffff98e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.962038][ T361] #4: ffffffff98e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.962043][ T361] irq event stamp: 16622 [ 13.962043][ T361] hardirqs last enabled at (16621): [] __down_trylock_console_sem+0x86/0xa0 [ 13.962046][ T361] hardirqs last disabled at (16622): [] console_emit_next_record+0x3d4/0x4c0 [ 13.962048][ T361] softirqs last enabled at (16614): [] handle_softirqs+0x67c/0x900 [ 13.962051][ T361] softirqs last disabled at (16605): [] __irq_exit_rcu+0x145/0x1c0 [ 13.962054][ T361] Preemption disabled at: [ 13.962054][ T361] [] vprintk_emit+0x31b/0x3e0 [ 13.962060][ T361] CPU: 1 UID: 0 PID: 361 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.962064][ T361] Tainted: [W]=WARN [ 13.962065][ T361] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.962066][ T361] Call Trace: [ 13.962068][ T361] [ 13.962069][ T361] dump_stack_lvl+0x6f/0xa0 [ 13.962075][ T361] ? vprintk_emit+0x31b/0x3e0 [ 13.962078][ T361] __might_resched.cold+0x1fe/0x2c1 [ 13.962082][ T361] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.962087][ T361] ? __kmalloc_noprof+0xdb/0x760 [ 13.962092][ T361] __kmalloc_noprof+0x443/0x760 [ 13.962094][ T361] ? alloc_buf.isra.0+0x4b/0x260 [ 13.962100][ T361] ? do_raw_spin_unlock+0x59/0x250 [ 13.962102][ T361] alloc_buf.isra.0+0x4b/0x260 [ 13.962106][ T361] put_chars+0x1e1/0x2f0 [ 13.962108][ T361] ? prb_final_commit+0x50/0x50 [ 13.962110][ T361] ? __send_to_port+0x420/0x420 [ 13.962113][ T361] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.962117][ T361] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.962120][ T361] ? validate_chain+0x38b/0xc20 [ 13.962123][ T361] hvc_console_print+0x292/0x780 [ 13.962126][ T361] ? mark_usage+0x61/0x170 [ 13.962128][ T361] ? __lock_acquire+0x518/0xc20 [ 13.962130][ T361] ? __lock_acquire+0x518/0xc20 [ 13.962134][ T361] ? hvc_write+0x3a0/0x3a0 [ 13.962136][ T361] ? console_emit_next_record+0x1df/0x4c0 [ 13.962139][ T361] ? rcu_is_watching+0x16/0xd0 [ 13.962142][ T361] ? lock_acquire+0x13c/0x160 [ 13.962146][ T361] console_emit_next_record+0x22f/0x4c0 [ 13.962149][ T361] ? devkmsg_read+0x4b0/0x4b0 [ 13.962151][ T361] ? console_flush_one_record+0x106/0x710 [ 13.962154][ T361] ? rcu_is_watching+0x16/0xd0 [ 13.962157][ T361] ? lock_acquire+0x13c/0x160 [ 13.962161][ T361] console_flush_one_record+0x46f/0x710 [ 13.962165][ T361] ? console_emit_next_record+0x4c0/0x4c0 [ 13.962167][ T361] ? __lock_acquire+0x518/0xc20 [ 13.962172][ T361] console_unlock+0xee/0x1f0 [ 13.962175][ T361] ? console_flush_one_record+0x710/0x710 [ 13.962176][ T361] ? rcu_is_watching+0x16/0xd0 [ 13.962179][ T361] ? lock_acquire+0x60/0x160 [ 13.962182][ T361] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.962184][ T361] ? vprintk_emit+0x320/0x3e0 [ 13.962188][ T361] vprintk_emit+0x37c/0x3e0 [ 13.962191][ T361] ? wake_up_klogd_work_func+0x90/0x90 [ 13.962194][ T361] ? __lock_release.isra.0+0x69/0x1a0 [ 13.962197][ T361] ? mark_held_locks+0x40/0x70 [ 13.962200][ T361] _printk+0xc7/0x100 [ 13.962204][ T361] ? snapshot_read.cold+0x21/0x21 [ 13.962208][ T361] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 13.962221][ T361] ? do_setlink.isra.0+0xa31/0x2750 [ 13.962222][ T361] ? rtnl_newlink+0x9f1/0x11c0 [ 13.962224][ T361] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.962228][ T361] br_set_state+0x22f/0x430 [bridge] [ 13.962239][ T361] br_init_port+0xc4/0x200 [bridge] [ 13.962247][ T361] new_nbp+0x39c/0x580 [bridge] [ 13.962256][ T361] br_add_if+0x212/0x1320 [bridge] [ 13.962263][ T361] ? is_bpf_text_address+0x72/0x110 [ 13.962268][ T361] ? kernel_text_address+0x149/0x170 [ 13.962271][ T361] ? __kernel_text_address+0x12/0x30 [ 13.962275][ T361] do_set_master+0x357/0x580 [ 13.962279][ T361] do_setlink.isra.0+0xa31/0x2750 [ 13.962282][ T361] ? stack_trace_save+0x93/0xc0 [ 13.962286][ T361] ? rtnl_link_get_size+0x350/0x350 [ 13.962287][ T361] ? rcu_read_lock_any_held+0x66/0x90 [ 13.962289][ T361] ? stack_depot_save_flags+0x38e/0x790 [ 13.962293][ T361] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.962295][ T361] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.962297][ T361] ? validate_chain+0x38b/0xc20 [ 13.962300][ T361] ? kasan_save_stack+0x3d/0x50 [ 13.962303][ T361] ? kasan_save_stack+0x2f/0x50 [ 13.962305][ T361] ? kasan_save_track+0x14/0x30 [ 13.962308][ T361] ? __lock_acquire+0x518/0xc20 [ 13.962309][ T361] ? netlink_seq_next+0x4e/0x60 [ 13.962312][ T361] ? ___sys_sendmsg+0xb0/0x1d0 [ 13.962317][ T361] ? lock_acquire.part.0+0xd4/0x280 [ 13.962319][ T361] ? rtnl_newlink+0x9a8/0x11c0 [ 13.962322][ T361] ? rcu_is_watching+0x16/0xd0 [ 13.962324][ T361] ? lock_acquire+0x13c/0x160 [ 13.962326][ T361] ? rcu_is_watching+0x16/0xd0 [ 13.962328][ T361] ? rcu_is_watching+0x16/0xd0 [ 13.962330][ T361] ? trace_contention_end+0xb3/0x180 [ 13.962334][ T361] ? __mutex_lock+0x1db/0x1ea0 [ 13.962337][ T361] ? __mutex_lock+0x9a3/0x1ea0 [ 13.962339][ T361] ? rtnl_newlink+0x9a8/0x11c0 [ 13.962343][ T361] ? ww_mutex_lock+0x160/0x160 [ 13.962345][ T361] ? nla_get_range_signed+0x3d0/0x3d0 [ 13.962350][ T361] ? __rtnl_newlink+0x3fa/0xa50 [ 13.962355][ T361] rtnl_newlink+0x9f1/0x11c0 [ 13.962360][ T361] ? rtnl_bridge_getlink+0x850/0x850 [ 13.962362][ T361] ? __lock_acquire+0x518/0xc20 [ 13.962366][ T361] ? lock_acquire.part.0+0xd4/0x280 [ 13.962368][ T361] ? find_held_lock+0x2b/0x80 [ 13.962371][ T361] ? rtnl_bridge_getlink+0x850/0x850 [ 13.962372][ T361] ? __lock_release.isra.0+0x69/0x1a0 [ 13.962376][ T361] ? rtnl_bridge_getlink+0x850/0x850 [ 13.962378][ T361] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.962381][ T361] ? rtnl_link_fill+0x920/0x920 [ 13.962383][ T361] ? __lock_acquire+0x518/0xc20 [ 13.962387][ T361] ? lock_acquire.part.0+0xd4/0x280 [ 13.962389][ T361] ? find_held_lock+0x2b/0x80 [ 13.962392][ T361] netlink_rcv_skb+0x14e/0x3a0 [ 13.962394][ T361] ? rtnl_link_fill+0x920/0x920 [ 13.962397][ T361] ? netlink_ack+0xcf0/0xcf0 [ 13.962403][ T361] ? netlink_deliver_tap+0xc5/0x330 [ 13.962405][ T361] ? netlink_deliver_tap+0x13c/0x330 [ 13.962409][ T361] netlink_unicast+0x486/0x750 [ 13.962413][ T361] ? netlink_attachskb+0x810/0x810 [ 13.962415][ T361] ? __lock_acquire+0x518/0xc20 [ 13.962420][ T361] netlink_sendmsg+0x735/0xc60 [ 13.962423][ T361] ? netlink_unicast+0x750/0x750 [ 13.962427][ T361] ? __might_fault+0x97/0x140 [ 13.962433][ T361] ____sys_sendmsg+0x415/0x880 [ 13.962435][ T361] ? copy_msghdr_from_user+0x279/0x420 [ 13.962437][ T361] ? get_timestamp.constprop.0+0x390/0x390 [ 13.962439][ T361] ? move_addr_to_kernel+0x40/0x40 [ 13.962446][ T361] ___sys_sendmsg+0x14e/0x1d0 [ 13.962449][ T361] ? copy_msghdr_from_user+0x420/0x420 [ 13.962465][ T361] __sys_sendmsg+0x12c/0x1d0 [ 13.962468][ T361] ? __sys_sendmsg_sock+0x20/0x20 [ 13.962477][ T361] ? rcu_is_watching+0x16/0xd0 [ 13.962483][ T361] do_syscall_64+0xff/0x530 [ 13.962486][ T361] ? exc_page_fault+0xee/0x100 [ 13.962489][ T361] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.962492][ T361] RIP: 0033:0x7f729450454e [ 13.962495][ T361] 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.962497][ T361] RSP: 002b:00007ffc9e81b860 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 13.962500][ T361] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f729450454e [ 13.962502][ T361] RDX: 0000000000000000 RSI: 00007ffc9e81b910 RDI: 0000000000000005 [ 13.962503][ T361] RBP: 00007ffc9e81b870 R08: 0000000000000000 R09: 0000000000000000 [ 13.962503][ T361] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a912b11 [ 13.962504][ T361] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc9e81bfd0 [ 13.962511][ T361] [ 13.998585][ T361] br2: port 2(vx2) entered disabled state [ 13.998837][ T361] vx2: entered allmulticast mode [ 14.000344][ T361] vx2: entered promiscuous mode [ 14.000949][ T361] br2: port 2(vx2) entered blocking state [ 14.001187][ T361] br2: port 2(vx2) entered forwarding state [ 14.197518][ T189] br2: port 1(w1) entered blocking state [ 14.197827][ T189] br2: port 1(w1) entered forwarding state [ 21.224342][ T443] GACT probability NOT on [ 21.224359][ T443] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 21.224361][ T443] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 443, name: modprobe [ 21.224363][ T443] preempt_count: 1, expected: 0 [ 21.224364][ T443] RCU nest depth: 0, expected: 0 [ 21.224365][ T443] locks held by modprobe/443: 4, last CPU#3: [ 21.224367][ T443] #0: ffffffff98f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 21.224380][ T443] #1: ffffffff98f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 21.224384][ T443] #2: ffffffff98e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 21.224388][ T443] #3: ffffffff98e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 21.224392][ T443] irq event stamp: 25318 [ 21.224393][ T443] hardirqs last enabled at (25317): [] __down_trylock_console_sem+0x86/0xa0 [ 21.224396][ T443] hardirqs last disabled at (25318): [] console_emit_next_record+0x3d4/0x4c0 [ 21.224398][ T443] softirqs last enabled at (23914): [] handle_softirqs+0x67c/0x900 [ 21.224401][ T443] softirqs last disabled at (22737): [] __irq_exit_rcu+0x145/0x1c0 [ 21.224404][ T443] Preemption disabled at: [ 21.224404][ T443] [] vprintk_emit+0x31b/0x3e0 [ 21.224410][ T443] CPU: 3 UID: 0 PID: 443 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 21.224414][ T443] Tainted: [W]=WARN [ 21.224415][ T443] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 21.224417][ T443] Call Trace: [ 21.224418][ T443] [ 21.224420][ T443] dump_stack_lvl+0x6f/0xa0 [ 21.224427][ T443] ? vprintk_emit+0x31b/0x3e0 [ 21.224429][ T443] __might_resched.cold+0x1fe/0x2c1 [ 21.224434][ T443] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 21.224438][ T443] ? __kmalloc_noprof+0xdb/0x760 [ 21.224443][ T443] __kmalloc_noprof+0x443/0x760 [ 21.224445][ T443] ? alloc_buf.isra.0+0x4b/0x260 [ 21.224451][ T443] ? do_raw_spin_unlock+0x59/0x250 [ 21.224454][ T443] alloc_buf.isra.0+0x4b/0x260 [ 21.224457][ T443] put_chars+0x1e1/0x2f0 [ 21.224459][ T443] ? desc_read_finalized_seq+0x79/0x120 [ 21.224462][ T443] ? __send_to_port+0x420/0x420 [ 21.224465][ T443] ? rcu_read_lock_any_held+0x3c/0x90 [ 21.224468][ T443] ? validate_chain+0x38b/0xc20 [ 21.224476][ T443] hvc_console_print+0x292/0x780 [ 21.224479][ T443] ? __lock_acquire+0x518/0xc20 [ 21.224482][ T443] ? __lock_acquire+0x518/0xc20 [ 21.224487][ T443] ? hvc_write+0x3a0/0x3a0 [ 21.224488][ T443] ? console_emit_next_record+0x1df/0x4c0 [ 21.224491][ T443] ? rcu_is_watching+0x16/0xd0 [ 21.224495][ T443] ? lock_acquire+0x13c/0x160 [ 21.224498][ T443] console_emit_next_record+0x22f/0x4c0 [ 21.224502][ T443] ? devkmsg_read+0x4b0/0x4b0 [ 21.224504][ T443] ? console_flush_one_record+0x106/0x710 [ 21.224507][ T443] ? rcu_is_watching+0x16/0xd0 [ 21.224510][ T443] ? lock_acquire+0x13c/0x160 [ 21.224514][ T443] console_flush_one_record+0x46f/0x710 [ 21.224518][ T443] ? console_emit_next_record+0x4c0/0x4c0 [ 21.224520][ T443] ? __lock_acquire+0x518/0xc20 [ 21.224525][ T443] console_unlock+0xee/0x1f0 [ 21.224528][ T443] ? console_flush_one_record+0x710/0x710 [ 21.224530][ T443] ? rcu_is_watching+0x16/0xd0 [ 21.224532][ T443] ? lock_acquire+0x60/0x160 [ 21.224536][ T443] ? __down_trylock_console_sem+0x5e/0xa0 [ 21.224538][ T443] ? vprintk_emit+0x320/0x3e0 [ 21.224541][ T443] vprintk_emit+0x37c/0x3e0 [ 21.224545][ T443] ? wake_up_klogd_work_func+0x90/0x90 [ 21.224551][ T443] ? llc_exit+0x3e0/0x3e0 [llc] [ 21.224554][ T443] _printk+0xc7/0x100 [ 21.224558][ T443] ? snapshot_read.cold+0x21/0x21 [ 21.224564][ T443] ? do_one_initcall+0x113/0x4f0 [ 21.224567][ T443] gact_init_module+0x15/0x1000 [act_gact] [ 21.224570][ T443] do_one_initcall+0x124/0x4f0 [ 21.224573][ T443] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 21.224577][ T443] ? do_init_module+0xae/0x8a0 [ 21.224580][ T443] ? kasan_unpoison+0x44/0x70 [ 21.224585][ T443] do_init_module+0x2ad/0x8a0 [ 21.224589][ T443] ? lockdep_hardirqs_on+0x91/0x130 [ 21.224594][ T443] ? free_module+0x470/0x470 [ 21.224597][ T443] ? kfree+0x123/0x590 [ 21.224601][ T443] load_module+0x1424/0x1bb0 [ 21.224608][ T443] ? layout_and_allocate.isra.0+0x680/0x680 [ 21.224612][ T443] ? __ia32_sys_fsconfig+0x180/0x180 [ 21.224616][ T443] ? find_held_lock+0x2b/0x80 [ 21.224619][ T443] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 21.224623][ T443] init_module_from_file+0x11f/0x1a0 [ 21.224626][ T443] ? __do_sys_init_module+0x220/0x220 [ 21.224634][ T443] ? __rwlock_init+0x150/0x150 [ 21.224637][ T443] ? do_raw_spin_unlock+0x59/0x250 [ 21.224640][ T443] idempotent_init_module+0x37b/0x7c0 [ 21.224644][ T443] ? init_module_from_file+0x1a0/0x1a0 [ 21.224650][ T443] ? rcu_is_watching+0x16/0xd0 [ 21.224653][ T443] ? cap_capable+0x1d7/0x3d0 [ 21.224659][ T443] __x64_sys_finit_module+0xdf/0x190 [ 21.224662][ T443] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 21.224665][ T443] do_syscall_64+0xff/0x530 [ 21.224667][ T443] ? exc_page_fault+0xee/0x100 [ 21.224670][ T443] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 21.224672][ T443] RIP: 0033:0x7fc3fd07a37d [ 21.224676][ T443] 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 [ 21.224678][ T443] RSP: 002b:00007ffe0a18ec98 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 21.224681][ T443] RAX: ffffffffffffffda RBX: 000056355c47e810 RCX: 00007fc3fd07a37d [ 21.224682][ T443] RDX: 0000000000000000 RSI: 000056354b931ffd RDI: 0000000000000000 [ 21.224683][ T443] RBP: 00007ffe0a18ed30 R08: 0000000000000000 R09: 0000000000000000 [ 21.224684][ T443] R10: 0000000000000000 R11: 0000000000000246 R12: 000056354b931ffd [ 21.224685][ T443] R13: 000056355c47e960 R14: 0000000000040000 R15: 0000000000000000 [ 21.224692][ T443] [ 27.009021][ C0] [ 27.009037][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.009039][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 27.009041][ C0] preempt_count: 104, expected: 0 [ 27.009042][ C0] RCU nest depth: 0, expected: 0 [ 27.009043][ C0] INFO: lockdep is turned off. [ 27.009044][ C0] irq event stamp: 820884 [ 27.009045][ C0] hardirqs last enabled at (820884): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.009054][ C0] hardirqs last disabled at (820883): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.009056][ C0] softirqs last enabled at (820868): [] handle_softirqs+0x67c/0x900 [ 27.009060][ C0] softirqs last disabled at (820881): [] __irq_exit_rcu+0x145/0x1c0 [ 27.009062][ C0] Preemption disabled at: [ 27.009063][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 27.009069][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.009072][ C0] Tainted: [W]=WARN [ 27.009073][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.009075][ C0] Call Trace: [ 27.009077][ C0] [ 27.009079][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.009085][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 27.009086][ C0] __might_resched.cold+0x1fe/0x2c1 [ 27.009090][ C0] ? find_held_lock+0x2b/0x80 [ 27.009092][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.009096][ C0] ? __asan_memcpy+0x3c/0x60 [ 27.009098][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009102][ C0] __kmalloc_noprof+0x443/0x760 [ 27.009105][ C0] ? __rwlock_init+0x150/0x150 [ 27.009107][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 27.009110][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.009112][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.009114][ C0] put_chars+0x1e1/0x2f0 [ 27.009116][ C0] ? __send_to_port+0x420/0x420 [ 27.009118][ C0] ? console_prepend_replay+0x20/0x20 [ 27.009121][ C0] hvc_console_print+0x292/0x780 [ 27.009125][ C0] ? hvc_write+0x3a0/0x3a0 [ 27.009126][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009128][ C0] ? lock_acquire+0x13c/0x160 [ 27.009130][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.009132][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 27.009134][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009136][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009138][ C0] ? lock_acquire+0x13c/0x160 [ 27.009140][ C0] ? console_flush_one_record+0x111/0x710 [ 27.009141][ C0] console_flush_one_record+0x46f/0x710 [ 27.009144][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 27.009146][ C0] console_unlock+0xee/0x1f0 [ 27.009148][ C0] ? lock_acquire+0x13c/0x160 [ 27.009150][ C0] ? console_flush_one_record+0x710/0x710 [ 27.009151][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009153][ C0] ? lock_release+0x184/0x1f0 [ 27.009154][ C0] ? lock_acquire+0x60/0x160 [ 27.009156][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.009158][ C0] ? vprintk_emit+0x320/0x3e0 [ 27.009160][ C0] vprintk_emit+0x37c/0x3e0 [ 27.009163][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 27.009164][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009166][ C0] ? lock_release+0x184/0x1f0 [ 27.009168][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 27.009173][ C0] _printk+0xc7/0x100 [ 27.009176][ C0] ? snapshot_read.cold+0x21/0x21 [ 27.009178][ C0] ? arch_stack_walk+0xd7/0x130 [ 27.009182][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.009184][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.009186][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.009188][ C0] mark_lock_irq+0x989/0x9c0 [ 27.009191][ C0] mark_lock+0x1d7/0xa00 [ 27.009193][ C0] mark_usage+0x42/0x170 [ 27.009195][ C0] __lock_acquire+0x388/0xc20 [ 27.009197][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.009199][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.009211][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009213][ C0] ? lock_acquire+0x13c/0x160 [ 27.009215][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.009221][ C0] _raw_spin_lock+0x33/0x40 [ 27.009223][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.009229][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.009237][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.009244][ C0] call_timer_fn+0x160/0x4d0 [ 27.009247][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.009249][ C0] ? debug_object_active_state+0x430/0x430 [ 27.009253][ C0] ? find_held_lock+0x2b/0x80 [ 27.009254][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.009256][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009259][ C0] __run_timers+0x68f/0xaa0 [ 27.009260][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.009267][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.009269][ C0] ? __lock_acquire+0x518/0xc20 [ 27.009272][ C0] ? __rwlock_init+0x150/0x150 [ 27.009274][ C0] run_timer_softirq+0xf0/0x160 [ 27.009276][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.009278][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.009280][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009282][ C0] handle_softirqs+0x1d3/0x900 [ 27.009284][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.009286][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.009288][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.009289][ C0] irq_exit_rcu+0xe/0x30 [ 27.009291][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.009293][ C0] [ 27.009294][ C0] [ 27.009294][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.009296][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.009299][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.009301][ C0] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.009303][ C0] RSP: 0018:ffffffff98c07cf8 EFLAGS: 00000296 [ 27.009307][ C0] RAX: 00000000000c868f RBX: ffffffff98c1c600 RCX: ffffffff95cf0307 [ 27.009308][ C0] RDX: ffffffff98c1c600 RSI: ffffffff98a38b01 RDI: ffffffff9848d8e0 [ 27.009309][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.009310][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3180fa2 [ 27.009311][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.009312][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.009316][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.009317][ C0] default_idle+0x9/0x10 [ 27.009319][ C0] default_idle_call+0x6e/0xb0 [ 27.009321][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.009322][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.009324][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.009326][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.009328][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.009330][ C0] do_idle+0xd8/0x190 [ 27.009331][ C0] cpu_startup_entry+0x53/0x70 [ 27.009333][ C0] rest_init+0x279/0x280 [ 27.009335][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.009337][ C0] ? rest_init+0x280/0x280 [ 27.009339][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.009343][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.009345][ C0] ? acpi_enable+0x1e4/0x330 [ 27.009348][ C0] start_kernel+0x3af/0x3b0 [ 27.009350][ C0] x86_64_start_reservations+0x24/0x30 [ 27.009352][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.009354][ C0] common_startup_64+0x13e/0x148 [ 27.009358][ C0] [ 27.033924][ C0] ======================================================== [ 27.034132][ C0] WARNING: possible irq lock inversion dependency detected [ 27.034387][ C0] 7.2.0-virtme #1 Tainted: G W [ 27.034561][ C0] -------------------------------------------------------- [ 27.034815][ C0] swapper/0/0 just changed the state of lock: [ 27.035048][ C0] ff1100000d922e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.035383][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.035645][ C0] (fs_reclaim){+.+.}-{0:0} [ 27.035647][ C0] [ 27.035647][ C0] [ 27.035647][ C0] and interrupts could create inverse lock ordering between them. [ 27.035647][ C0] [ 27.036208][ C0] [ 27.036208][ C0] other info that might help us debug this: [ 27.036462][ C0] Chain exists of: [ 27.036462][ C0] &br->lock --> console_owner --> fs_reclaim [ 27.036462][ C0] [ 27.036826][ C0] Possible interrupt unsafe locking scenario: [ 27.036826][ C0] [ 27.037092][ C0] CPU0 CPU1 [ 27.037283][ C0] ---- ---- [ 27.037424][ C0] lock(fs_reclaim); [ 27.037534][ C0] local_irq_disable(); [ 27.037759][ C0] lock(&br->lock); [ 27.037990][ C0] lock(console_owner); [ 27.038161][ C0] [ 27.038321][ C0] lock(&br->lock); [ 27.038427][ C0] [ 27.038427][ C0] *** DEADLOCK *** [ 27.038427][ C0] [ 27.038686][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 27.038858][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.039199][ C0] [ 27.039199][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 27.039490][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 27.039693][ C0] HARDIRQ-ON-W at: [ 27.039843][ C0] __lock_acquire+0x388/0xc20 [ 27.040067][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.040288][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.040578][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.040932][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.041286][ C0] workqueue_init+0x2a/0x680 [ 27.041539][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.041892][ C0] kernel_init+0x21/0x150 [ 27.042194][ C0] ret_from_fork+0x474/0x6b0 [ 27.042443][ C0] ret_from_fork_asm+0x11/0x20 [ 27.042743][ C0] SOFTIRQ-ON-W at: [ 27.042852][ C0] __lock_acquire+0x388/0xc20 [ 27.043120][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.043354][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.043655][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.044014][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.044349][ C0] workqueue_init+0x2a/0x680 [ 27.044599][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.044954][ C0] kernel_init+0x21/0x150 [ 27.045255][ C0] ret_from_fork+0x474/0x6b0 [ 27.045484][ C0] ret_from_fork_asm+0x11/0x20 [ 27.045783][ C0] INITIAL USE at: [ 27.045936][ C0] __lock_acquire+0x388/0xc20 [ 27.046223][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.046470][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.046748][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.047101][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.047407][ C0] workqueue_init+0x2a/0x680 [ 27.047617][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.047914][ C0] kernel_init+0x21/0x150 [ 27.048130][ C0] ret_from_fork+0x474/0x6b0 [ 27.048418][ C0] ret_from_fork_asm+0x11/0x20 [ 27.048723][ C0] } [ 27.048801][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.049127][ C0] ... acquired at: [ 27.049251][ C0] __lock_acquire+0x518/0xc20 [ 27.049507][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.049694][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.049950][ C0] __kmalloc_noprof+0xd3/0x760 [ 27.050147][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.050344][ C0] put_chars+0x1e1/0x2f0 [ 27.050489][ C0] hvc_console_print+0x292/0x780 [ 27.050680][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.050819][ C0] console_flush_one_record+0x46f/0x710 [ 27.051017][ C0] console_unlock+0xee/0x1f0 [ 27.051160][ C0] vprintk_emit+0x37c/0x3e0 [ 27.051352][ C0] _printk+0xc7/0x100 [ 27.051457][ C0] sched_clock_init_late+0x14c/0x170 [ 27.051598][ C0] do_one_initcall+0x124/0x4f0 [ 27.051787][ C0] kernel_init_freeable+0x596/0x630 [ 27.051928][ C0] kernel_init+0x21/0x150 [ 27.052119][ C0] ret_from_fork+0x474/0x6b0 [ 27.052257][ C0] ret_from_fork_asm+0x11/0x20 [ 27.052445][ C0] [ 27.052521][ C0] -> (console_owner){....}-{0:0} { [ 27.052715][ C0] INITIAL USE at: [ 27.052817][ C0] } [ 27.052892][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.053148][ C0] ... acquired at: [ 27.053253][ C0] __lock_acquire+0x518/0xc20 [ 27.053444][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.053585][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.053812][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.054006][ C0] console_flush_one_record+0x46f/0x710 [ 27.054142][ C0] console_unlock+0xee/0x1f0 [ 27.054333][ C0] vprintk_emit+0x37c/0x3e0 [ 27.054475][ C0] _printk+0xc7/0x100 [ 27.054580][ C0] br_set_state+0x22f/0x430 [bridge] [ 27.054778][ C0] br_init_port+0xc4/0x200 [bridge] [ 27.054927][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 27.055152][ C0] br_add_if+0xde2/0x1320 [bridge] [ 27.055348][ C0] do_set_master+0x357/0x580 [ 27.055490][ C0] do_setlink.isra.0+0xa31/0x2750 [ 27.055681][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.055821][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.056016][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.056157][ C0] netlink_unicast+0x486/0x750 [ 27.056345][ C0] netlink_sendmsg+0x735/0xc60 [ 27.056487][ C0] ____sys_sendmsg+0x415/0x880 [ 27.056679][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.056817][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.057016][ C0] do_syscall_64+0xff/0x530 [ 27.057155][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.057380][ C0] [ 27.057450][ C0] -> (&br->lock){+.-.}-{3:3} { [ 27.057591][ C0] HARDIRQ-ON-W at: [ 27.057747][ C0] __lock_acquire+0x388/0xc20 [ 27.057925][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.058151][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.058377][ C0] br_set_mac_address+0xe6/0x3b0 [bridge] [ 27.058591][ C0] netif_set_mac_address+0x282/0x410 [ 27.058820][ C0] do_setlink.isra.0+0x706/0x2750 [ 27.059049][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.059219][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.059447][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.059675][ C0] netlink_unicast+0x486/0x750 [ 27.059849][ C0] netlink_sendmsg+0x735/0xc60 [ 27.060081][ C0] ____sys_sendmsg+0x415/0x880 [ 27.060254][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.060482][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.060709][ C0] do_syscall_64+0xff/0x530 [ 27.060888][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.061147][ C0] IN-SOFTIRQ-W at: [ 27.061249][ C0] __lock_acquire+0x388/0xc20 [ 27.061481][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.061706][ C0] _raw_spin_lock+0x33/0x40 [ 27.061886][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.062151][ C0] call_timer_fn+0x160/0x4d0 [ 27.062377][ C0] __run_timers+0x68f/0xaa0 [ 27.062550][ C0] run_timer_softirq+0xf0/0x160 [ 27.062777][ C0] handle_softirqs+0x1d3/0x900 [ 27.063008][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.063180][ C0] irq_exit_rcu+0xe/0x30 [ 27.063407][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.063671][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.063880][ C0] pv_native_safe_halt+0xf/0x10 [ 27.064107][ C0] default_idle+0x9/0x10 [ 27.064335][ C0] default_idle_call+0x6e/0xb0 [ 27.064510][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.064773][ C0] do_idle+0xd8/0x190 [ 27.064917][ C0] cpu_startup_entry+0x53/0x70 [ 27.065143][ C0] rest_init+0x279/0x280 [ 27.065368][ C0] start_kernel+0x3af/0x3b0 [ 27.065545][ C0] x86_64_start_reservations+0x24/0x30 [ 27.065770][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.066003][ C0] common_startup_64+0x13e/0x148 [ 27.066176][ C0] INITIAL USE at: [ 27.066330][ C0] __lock_acquire+0x388/0xc20 [ 27.066509][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.066736][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.066911][ C0] br_set_mac_address+0xe6/0x3b0 [bridge] [ 27.067176][ C0] netif_set_mac_address+0x282/0x410 [ 27.067403][ C0] do_setlink.isra.0+0x706/0x2750 [ 27.067578][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.067804][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.068041][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.068217][ C0] netlink_unicast+0x486/0x750 [ 27.068446][ C0] netlink_sendmsg+0x735/0xc60 [ 27.068684][ C0] ____sys_sendmsg+0x415/0x880 [ 27.068858][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.069092][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.069266][ C0] do_syscall_64+0xff/0x530 [ 27.069497][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.069758][ C0] } [ 27.069827][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.070045][ C0] ... acquired at: [ 27.070148][ C0] mark_lock+0x1d7/0xa00 [ 27.070338][ C0] mark_usage+0x42/0x170 [ 27.070477][ C0] __lock_acquire+0x388/0xc20 [ 27.070669][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.070808][ C0] _raw_spin_lock+0x33/0x40 [ 27.071002][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.071178][ C0] call_timer_fn+0x160/0x4d0 [ 27.071374][ C0] __run_timers+0x68f/0xaa0 [ 27.071514][ C0] run_timer_softirq+0xf0/0x160 [ 27.071703][ C0] handle_softirqs+0x1d3/0x900 [ 27.071841][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.072041][ C0] irq_exit_rcu+0xe/0x30 [ 27.072179][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.072401][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.072628][ C0] pv_native_safe_halt+0xf/0x10 [ 27.072768][ C0] default_idle+0x9/0x10 [ 27.072962][ C0] default_idle_call+0x6e/0xb0 [ 27.073100][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.073324][ C0] do_idle+0xd8/0x190 [ 27.073428][ C0] cpu_startup_entry+0x53/0x70 [ 27.073622][ C0] rest_init+0x279/0x280 [ 27.073763][ C0] start_kernel+0x3af/0x3b0 [ 27.073958][ C0] x86_64_start_reservations+0x24/0x30 [ 27.074097][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.074284][ C0] common_startup_64+0x13e/0x148 [ 27.074420][ C0] [ 27.074491][ C0] [ 27.074491][ C0] stack backtrace: [ 27.074719][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.074722][ C0] Tainted: [W]=WARN [ 27.074723][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.074724][ C0] Call Trace: [ 27.074725][ C0] [ 27.074726][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.074730][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.074732][ C0] mark_lock_irq+0x989/0x9c0 [ 27.074735][ C0] mark_lock+0x1d7/0xa00 [ 27.074737][ C0] mark_usage+0x42/0x170 [ 27.074739][ C0] __lock_acquire+0x388/0xc20 [ 27.074741][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.074743][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.074749][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.074753][ C0] ? lock_acquire+0x13c/0x160 [ 27.074755][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.074761][ C0] _raw_spin_lock+0x33/0x40 [ 27.074762][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.074769][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.074776][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.074782][ C0] call_timer_fn+0x160/0x4d0 [ 27.074784][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.074786][ C0] ? debug_object_active_state+0x430/0x430 [ 27.074789][ C0] ? find_held_lock+0x2b/0x80 [ 27.074791][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.074793][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.074795][ C0] __run_timers+0x68f/0xaa0 [ 27.074797][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.074804][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.074806][ C0] ? __lock_acquire+0x518/0xc20 [ 27.074808][ C0] ? __rwlock_init+0x150/0x150 [ 27.074811][ C0] run_timer_softirq+0xf0/0x160 [ 27.074813][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.074814][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.074817][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.074818][ C0] handle_softirqs+0x1d3/0x900 [ 27.074820][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.074822][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.074824][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.074825][ C0] irq_exit_rcu+0xe/0x30 [ 27.074827][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.074829][ C0] [ 27.074829][ C0] [ 27.074830][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.074831][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.074833][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.074835][ C0] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.074837][ C0] RSP: 0018:ffffffff98c07cf8 EFLAGS: 00000296 [ 27.074839][ C0] RAX: 00000000000c868f RBX: ffffffff98c1c600 RCX: ffffffff95cf0307 [ 27.074840][ C0] RDX: ffffffff98c1c600 RSI: ffffffff98a38b01 RDI: ffffffff9848d8e0 [ 27.074841][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.074842][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3180fa2 [ 27.074843][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.074844][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.074847][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.074848][ C0] default_idle+0x9/0x10 [ 27.074850][ C0] default_idle_call+0x6e/0xb0 [ 27.074851][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.074853][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.074855][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.074857][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.074858][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.074866][ C0] do_idle+0xd8/0x190 [ 27.074868][ C0] cpu_startup_entry+0x53/0x70 [ 27.074869][ C0] rest_init+0x279/0x280 [ 27.074871][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.074874][ C0] ? rest_init+0x280/0x280 [ 27.074876][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.074879][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.074881][ C0] ? acpi_enable+0x1e4/0x330 [ 27.074883][ C0] start_kernel+0x3af/0x3b0 [ 27.074885][ C0] x86_64_start_reservations+0x24/0x30 [ 27.074887][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.074889][ C0] common_startup_64+0x13e/0x148 [ 27.074892][ C0] [ 64.516282][ T12] vx2: left allmulticast mode [ 64.516299][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 64.516302][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 64.516303][ T12] preempt_count: 1, expected: 0 [ 64.516304][ T12] RCU nest depth: 0, expected: 0 [ 64.516305][ T12] INFO: lockdep is turned off. [ 64.516306][ T12] irq event stamp: 28696 [ 64.516307][ T12] hardirqs last enabled at (28695): [] _raw_spin_unlock_irq+0x28/0x50 [ 64.516316][ T12] hardirqs last disabled at (28696): [] __schedule+0x1246/0x20e0 [ 64.516318][ T12] softirqs last enabled at (28538): [] __fib6_clean_all+0xff/0x290 [ 64.516321][ T12] softirqs last disabled at (28532): [] __fib6_clean_all+0xd9/0x290 [ 64.516324][ T12] Preemption disabled at: [ 64.516325][ T12] [] vprintk_emit+0x31b/0x3e0 [ 64.516333][ T12] CPU: 3 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 64.516337][ T12] Tainted: [W]=WARN [ 64.516338][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 64.516340][ T12] Workqueue: netns cleanup_net [ 64.516346][ T12] Call Trace: [ 64.516347][ T12] [ 64.516349][ T12] dump_stack_lvl+0x6f/0xa0 [ 64.516354][ T12] ? vprintk_emit+0x31b/0x3e0 [ 64.516356][ T12] __might_resched.cold+0x1fe/0x2c1 [ 64.516360][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 64.516364][ T12] ? rcu_is_watching+0x16/0xd0 [ 64.516368][ T12] __kmalloc_noprof+0x443/0x760 [ 64.516371][ T12] ? __rwlock_init+0x150/0x150 [ 64.516374][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 64.516378][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 64.516379][ T12] alloc_buf.isra.0+0x4b/0x260 [ 64.516381][ T12] put_chars+0x1e1/0x2f0 [ 64.516383][ T12] ? desc_read_finalized_seq+0x79/0x120 [ 64.516385][ T12] ? __send_to_port+0x420/0x420 [ 64.516387][ T12] ? console_prepend_replay+0x20/0x20 [ 64.516389][ T12] hvc_console_print+0x292/0x780 [ 64.516393][ T12] ? hvc_write+0x3a0/0x3a0 [ 64.516394][ T12] ? do_raw_spin_lock+0x131/0x280 [ 64.516396][ T12] ? __rwlock_init+0x150/0x150 [ 64.516397][ T12] ? rcu_is_watching+0x16/0xd0 [ 64.516399][ T12] ? rcu_is_watching+0x16/0xd0 [ 64.516401][ T12] ? lock_acquire+0x13c/0x160 [ 64.516403][ T12] console_emit_next_record+0x22f/0x4c0 [ 64.516405][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 64.516408][ T12] ? rcu_is_watching+0x16/0xd0 [ 64.516409][ T12] ? rcu_is_watching+0x16/0xd0 [ 64.516411][ T12] ? rcu_is_watching+0x16/0xd0 [ 64.516413][ T12] ? lock_acquire+0x13c/0x160 [ 64.516414][ T12] ? console_flush_one_record+0x111/0x710 [ 64.516416][ T12] console_flush_one_record+0x46f/0x710 [ 64.516419][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 64.516421][ T12] console_unlock+0xee/0x1f0 [ 64.516423][ T12] ? lock_acquire+0x13c/0x160 [ 64.516425][ T12] ? console_flush_one_record+0x710/0x710 [ 64.516426][ T12] ? rcu_is_watching+0x16/0xd0 [ 64.516428][ T12] ? lock_release+0x184/0x1f0 [ 64.516429][ T12] ? lock_acquire+0x60/0x160 [ 64.516431][ T12] ? __rcu_read_lock+0x30/0x70 [ 64.516433][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 64.516436][ T12] ? vprintk_emit+0x320/0x3e0 [ 64.516439][ T12] vprintk_emit+0x37c/0x3e0 [ 64.516443][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 64.516445][ T12] ? process_one_work+0xe3e/0x1560 [ 64.516449][ T12] ? kthread+0x367/0x460 [ 64.516451][ T12] ? ret_from_fork+0x474/0x6b0 [ 64.516453][ T12] ? ret_from_fork_asm+0x11/0x20 [ 64.516456][ T12] _printk+0xc7/0x100 [ 64.516459][ T12] ? snapshot_read.cold+0x21/0x21 [ 64.516462][ T12] ? netdev_name+0x43/0x60 [ 64.516465][ T12] ? __netdev_printk+0x17f/0x1d0 [ 64.516467][ T12] netdev_info+0xe2/0x116 [ 64.516468][ T12] ? netdev_notice+0x120/0x120 [ 64.516470][ T12] ? rcu_is_watching+0x16/0xd0 [ 64.516472][ T12] ? __up_read+0x355/0x9e0 [ 64.516474][ T12] ? trace_hardirqs_on+0x36/0x40 [ 64.516477][ T12] ? down_write_nested+0x200/0x200 [ 64.516478][ T12] ? mutex_is_locked+0x1c/0x50 [ 64.516481][ T12] netif_set_allmulti.cold+0x7c/0x166 [ 64.516484][ T12] dev_set_allmulti+0xa2/0x150 [ 64.516487][ T12] del_nbp+0xb7/0xc10 [bridge] [ 64.516500][ T12] br_dev_delete+0x9d/0x1e0 [bridge] [ 64.516508][ T12] br_net_exit_rtnl+0xb8/0x110 [bridge] [ 64.516516][ T12] ops_undo_list+0x5be/0x8f0 [ 64.516518][ T12] ? rtnl_net_dumpid_one+0x2d0/0x2d0 [ 64.516520][ T12] ? idr_destroy+0x121/0x250 [ 64.516524][ T12] cleanup_net+0x431/0x890 [ 64.516526][ T12] ? net_passive_dec+0x230/0x230 [ 64.516528][ T12] ? lock_acquire+0x13c/0x160 [ 64.516529][ T12] ? rcu_is_watching+0x16/0xd0 [ 64.516532][ T12] process_one_work+0xe3e/0x1560 [ 64.516535][ T12] ? __queue_delayed_work+0x470/0x470 [ 64.516537][ T12] ? __rwlock_init+0x150/0x150 [ 64.516540][ T12] worker_thread+0x4f1/0xd60 [ 64.516543][ T12] ? rescuer_thread+0x1340/0x1340 [ 64.516544][ T12] kthread+0x367/0x460 [ 64.516546][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 64.516547][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 64.516549][ T12] ret_from_fork+0x474/0x6b0 [ 64.516551][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 64.516553][ T12] ? __switch_to+0x5a3/0xe00 [ 64.516555][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 64.516557][ T12] ret_from_fork_asm+0x11/0x20 [ 64.516560][ T12] [ 64.540921][ T12] vx2: left promiscuous mode [ 64.541194][ T12] br2: port 2(vx2) entered disabled state [ 64.543652][ T12] w1: left allmulticast mode [ 64.543850][ T12] w1: left promiscuous mode [ 64.544236][ T12] br2: port 1(w1) entered disabled state [ 64.629555][ T12] vx2: left allmulticast mode [ 64.629771][ T12] vx2: left promiscuous mode [ 64.630050][ T12] br2: port 2(vx2) entered disabled state [ 64.630851][ T12] w1: left allmulticast mode [ 64.631951][ T12] w1: left promiscuous mode [ 64.632211][ T12] br2: port 1(w1) entered disabled state [ 65.040190][ T576] br1: port 3(veth2) entered disabled state [ 65.057923][ T577] veth2: left allmulticast mode [ 65.058124][ T577] veth2: left promiscuous mode [ 65.058331][ T577] br1: port 3(veth2) entered disabled state [ 65.104520][ T579] br1: port 2(veth1) entered disabled state [ 65.119425][ T580] veth1: left allmulticast mode [ 65.119591][ T580] veth1: left promiscuous mode [ 65.119786][ T580] br1: port 2(veth1) entered disabled state [ 65.142433][ T581] vx1: left allmulticast mode [ 65.142602][ T581] vx1: left promiscuous mode [ 65.142803][ T581] br1: port 1(vx1) entered disabled state