[ 10.793895][ T188] ip (188) used greatest stack depth: 24272 bytes left [ 10.793915][ T188] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.793918][ T188] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 188, name: ip [ 10.793920][ T188] preempt_count: 2, expected: 0 [ 10.793921][ T188] RCU nest depth: 0, expected: 0 [ 10.793923][ T188] locks held by ip/188: 5, last CPU#2: [ 10.793926][ T188] #0: ffffffff988027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.793942][ T188] #1: ffffffff98969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.793950][ T188] #2: ffffffff98969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.793956][ T188] #3: ffffffff98889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.793961][ T188] #4: ffffffff98889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.793967][ T188] irq event stamp: 34802 [ 10.793969][ T188] hardirqs last enabled at (34801): [] __down_trylock_console_sem+0x86/0xa0 [ 10.793972][ T188] hardirqs last disabled at (34802): [] console_emit_next_record+0x3d4/0x4c0 [ 10.793975][ T188] softirqs last enabled at (34594): [] handle_softirqs+0x67c/0x900 [ 10.793978][ T188] softirqs last disabled at (34589): [] __irq_exit_rcu+0x145/0x1c0 [ 10.793981][ T188] Preemption disabled at: [ 10.793982][ T188] [<0000000000000000>] 0x0 [ 10.793991][ T188] CPU: 2 UID: 0 PID: 188 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.793996][ T188] Tainted: [W]=WARN [ 10.793997][ T188] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.793999][ T188] Call Trace: [ 10.794001][ T188] [ 10.794003][ T188] dump_stack_lvl+0x6f/0xa0 [ 10.794011][ T188] __might_resched.cold+0x1fe/0x2c1 [ 10.794018][ T188] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.794024][ T188] ? __kmalloc_noprof+0xdb/0x760 [ 10.794031][ T188] __kmalloc_noprof+0x443/0x760 [ 10.794035][ T188] ? alloc_buf.isra.0+0x4b/0x260 [ 10.794044][ T188] ? do_raw_spin_unlock+0x59/0x250 [ 10.794047][ T188] alloc_buf.isra.0+0x4b/0x260 [ 10.794052][ T188] put_chars+0x1e1/0x2f0 [ 10.794057][ T188] ? __send_to_port+0x420/0x420 [ 10.794059][ T188] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.794066][ T188] ? validate_chain+0x38b/0xc20 [ 10.794075][ T188] hvc_console_print+0x292/0x780 [ 10.794086][ T188] ? hvc_write+0x3a0/0x3a0 [ 10.794092][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.794096][ T188] ? lock_acquire+0x13c/0x160 [ 10.794103][ T188] console_emit_next_record+0x22f/0x4c0 [ 10.794109][ T188] ? devkmsg_read+0x4b0/0x4b0 [ 10.794112][ T188] ? console_flush_one_record+0x106/0x710 [ 10.794117][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.794121][ T188] ? lock_acquire+0x13c/0x160 [ 10.794128][ T188] console_flush_one_record+0x46f/0x710 [ 10.794135][ T188] ? console_emit_next_record+0x4c0/0x4c0 [ 10.794137][ T188] ? __lock_acquire+0x518/0xc20 [ 10.794147][ T188] console_unlock+0xee/0x1f0 [ 10.794151][ T188] ? console_flush_one_record+0x710/0x710 [ 10.794154][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.794158][ T188] ? lock_acquire+0x60/0x160 [ 10.794164][ T188] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.794166][ T188] ? vprintk_emit+0x320/0x3e0 [ 10.794172][ T188] vprintk_emit+0x37c/0x3e0 [ 10.794178][ T188] ? wake_up_klogd_work_func+0x90/0x90 [ 10.794184][ T188] ? __lock_acquire+0x518/0xc20 [ 10.794190][ T188] _printk+0xc7/0x100 [ 10.794196][ T188] ? snapshot_read.cold+0x21/0x21 [ 10.794200][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.794204][ T188] ? __rwlock_init+0x150/0x150 [ 10.794211][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.794216][ T188] do_exit.cold+0x82/0x9c [ 10.794221][ T188] ? exit_notify+0x890/0x890 [ 10.794223][ T188] ? __lock_release.isra.0+0x69/0x1a0 [ 10.794228][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.794235][ T188] do_group_exit+0xb8/0x370 [ 10.794240][ T188] __x64_sys_exit_group+0x3c/0x50 [ 10.794243][ T188] x64_sys_call+0x1567/0x1570 [ 10.794246][ T188] do_syscall_64+0xff/0x530 [ 10.794250][ T188] ? exc_page_fault+0xee/0x100 [ 10.794255][ T188] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.794258][ T188] RIP: 0033:0x7fbb8ac541b8 [ 10.794261][ T188] Code: Unable to access opcode bytes at 0x7fbb8ac5418e. [ 10.794263][ T188] RSP: 002b:00007fff87e42ca8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.794266][ T188] RAX: ffffffffffffffda RBX: 00007fbb8ad84f88 RCX: 00007fbb8ac541b8 [ 10.794268][ T188] RDX: 00007fbb8a99efc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.794270][ T188] RBP: 00007fff87e42d00 R08: 0000000000000000 R09: 0000000000008010 [ 10.794271][ T188] R10: 00007fff87e42ac0 R11: 0000000000000246 R12: 0000000000000001 [ 10.794272][ T188] R13: 0000000000000000 R14: 00007fbb8ad83680 R15: 00007fbb8ad84fa0 [ 10.794286][ T188] [ 12.960179][ T269] br1: port 1(vx1) entered blocking state [ 12.960295][ T269] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.960297][ T269] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 269, name: ip [ 12.960299][ T269] preempt_count: 1, expected: 0 [ 12.960300][ T269] RCU nest depth: 0, expected: 0 [ 12.960301][ T269] locks held by ip/269: 5, last CPU#1: [ 12.960303][ T269] #0: ffffffff990d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 12.960315][ T269] #1: ffffffff98969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.960322][ T269] #2: ffffffff98969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.960326][ T269] #3: ffffffff98889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.960330][ T269] #4: ffffffff98889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.960334][ T269] irq event stamp: 12504 [ 12.960335][ T269] hardirqs last enabled at (12503): [] __down_trylock_console_sem+0x86/0xa0 [ 12.960337][ T269] hardirqs last disabled at (12504): [] console_emit_next_record+0x3d4/0x4c0 [ 12.960339][ T269] softirqs last enabled at (12410): [] __alloc_skb+0x4c2/0x5f0 [ 12.960343][ T269] softirqs last disabled at (12408): [] __alloc_skb+0x4c2/0x5f0 [ 12.960345][ T269] Preemption disabled at: [ 12.960346][ T269] [] vprintk_emit+0x31b/0x3e0 [ 12.960352][ T269] CPU: 1 UID: 0 PID: 269 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.960355][ T269] Tainted: [W]=WARN [ 12.960356][ T269] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.960358][ T269] Call Trace: [ 12.960360][ T269] [ 12.960361][ T269] dump_stack_lvl+0x6f/0xa0 [ 12.960367][ T269] ? vprintk_emit+0x31b/0x3e0 [ 12.960369][ T269] __might_resched.cold+0x1fe/0x2c1 [ 12.960374][ T269] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.960378][ T269] ? __kmalloc_noprof+0xdb/0x760 [ 12.960383][ T269] __kmalloc_noprof+0x443/0x760 [ 12.960386][ T269] ? alloc_buf.isra.0+0x4b/0x260 [ 12.960391][ T269] ? do_raw_spin_unlock+0x59/0x250 [ 12.960394][ T269] alloc_buf.isra.0+0x4b/0x260 [ 12.960397][ T269] put_chars+0x1e1/0x2f0 [ 12.960400][ T269] ? __send_to_port+0x420/0x420 [ 12.960405][ T269] ? validate_chain+0x34a/0xc20 [ 12.960408][ T269] hvc_console_print+0x292/0x780 [ 12.960411][ T269] ? mark_usage+0x61/0x170 [ 12.960413][ T269] ? __lock_acquire+0x518/0xc20 [ 12.960415][ T269] ? __lock_acquire+0x518/0xc20 [ 12.960419][ T269] ? hvc_write+0x3a0/0x3a0 [ 12.960421][ T269] ? console_emit_next_record+0x1df/0x4c0 [ 12.960424][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.960432][ T269] ? lock_acquire+0x13c/0x160 [ 12.960436][ T269] console_emit_next_record+0x22f/0x4c0 [ 12.960440][ T269] ? devkmsg_read+0x4b0/0x4b0 [ 12.960442][ T269] ? console_flush_one_record+0x106/0x710 [ 12.960445][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.960447][ T269] ? lock_acquire+0x13c/0x160 [ 12.960451][ T269] console_flush_one_record+0x46f/0x710 [ 12.960456][ T269] ? console_emit_next_record+0x4c0/0x4c0 [ 12.960458][ T269] ? __lock_acquire+0x518/0xc20 [ 12.960463][ T269] console_unlock+0xee/0x1f0 [ 12.960466][ T269] ? console_flush_one_record+0x710/0x710 [ 12.960467][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.960470][ T269] ? lock_acquire+0x60/0x160 [ 12.960474][ T269] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.960475][ T269] ? vprintk_emit+0x320/0x3e0 [ 12.960479][ T269] vprintk_emit+0x37c/0x3e0 [ 12.960483][ T269] ? wake_up_klogd_work_func+0x90/0x90 [ 12.960485][ T269] ? __lock_release.isra.0+0x69/0x1a0 [ 12.960487][ T269] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.960491][ T269] ? mark_held_locks+0x40/0x70 [ 12.960495][ T269] _printk+0xc7/0x100 [ 12.960499][ T269] ? snapshot_read.cold+0x21/0x21 [ 12.960503][ T269] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 12.960516][ T269] ? do_setlink.isra.0+0xa31/0x2750 [ 12.960517][ T269] ? rtnl_newlink+0x9f1/0x11c0 [ 12.960519][ T269] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.960523][ T269] br_set_state+0x22f/0x430 [bridge] [ 12.960534][ T269] br_init_port+0xc4/0x200 [bridge] [ 12.960542][ T269] new_nbp+0x39c/0x580 [bridge] [ 12.960552][ T269] br_add_if+0x212/0x1320 [bridge] [ 12.960559][ T269] ? is_bpf_text_address+0x72/0x110 [ 12.960563][ T269] ? kernel_text_address+0x149/0x170 [ 12.960566][ T269] ? __kernel_text_address+0x12/0x30 [ 12.960570][ T269] do_set_master+0x357/0x580 [ 12.960575][ T269] do_setlink.isra.0+0xa31/0x2750 [ 12.960578][ T269] ? stack_trace_save+0x93/0xc0 [ 12.960581][ T269] ? rtnl_link_get_size+0x350/0x350 [ 12.960583][ T269] ? rcu_read_lock_any_held+0x66/0x90 [ 12.960585][ T269] ? stack_depot_save_flags+0x38e/0x790 [ 12.960589][ T269] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.960592][ T269] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.960593][ T269] ? validate_chain+0x38b/0xc20 [ 12.960596][ T269] ? kasan_save_stack+0x3d/0x50 [ 12.960599][ T269] ? kasan_save_stack+0x2f/0x50 [ 12.960601][ T269] ? kasan_save_track+0x14/0x30 [ 12.960604][ T269] ? __lock_acquire+0x518/0xc20 [ 12.960606][ T269] ? netlink_seq_next+0xe/0x60 [ 12.960609][ T269] ? ___sys_sendmsg+0xb0/0x1d0 [ 12.960613][ T269] ? lock_acquire.part.0+0xd4/0x280 [ 12.960615][ T269] ? rtnl_newlink+0x9a8/0x11c0 [ 12.960618][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.960620][ T269] ? lock_acquire+0x13c/0x160 [ 12.960622][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.960624][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.960626][ T269] ? trace_contention_end+0xb3/0x180 [ 12.960630][ T269] ? __mutex_lock+0x1db/0x1ea0 [ 12.960632][ T269] ? __mutex_lock+0x9a3/0x1ea0 [ 12.960634][ T269] ? rtnl_newlink+0x9a8/0x11c0 [ 12.960638][ T269] ? ww_mutex_lock+0x160/0x160 [ 12.960639][ T269] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.960645][ T269] ? __rtnl_newlink+0x3fa/0xa50 [ 12.960650][ T269] rtnl_newlink+0x9f1/0x11c0 [ 12.960655][ T269] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.960658][ T269] ? rtnl_bridge_getlink+0x850/0x850 [ 12.960659][ T269] ? finish_task_switch.isra.0+0x209/0xa70 [ 12.960661][ T269] ? __lock_acquire+0x518/0xc20 [ 12.960666][ T269] ? lock_acquire.part.0+0xd4/0x280 [ 12.960668][ T269] ? find_held_lock+0x2b/0x80 [ 12.960670][ T269] ? rtnl_bridge_getlink+0x850/0x850 [ 12.960672][ T269] ? __lock_release.isra.0+0x69/0x1a0 [ 12.960675][ T269] ? rtnl_bridge_getlink+0x850/0x850 [ 12.960678][ T269] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.960681][ T269] ? rtnl_link_fill+0x920/0x920 [ 12.960682][ T269] ? __lock_acquire+0x518/0xc20 [ 12.960687][ T269] ? lock_acquire.part.0+0xd4/0x280 [ 12.960689][ T269] ? find_held_lock+0x2b/0x80 [ 12.960692][ T269] netlink_rcv_skb+0x14e/0x3a0 [ 12.960694][ T269] ? rtnl_link_fill+0x920/0x920 [ 12.960697][ T269] ? netlink_ack+0xcf0/0xcf0 [ 12.960703][ T269] ? netlink_deliver_tap+0xc5/0x330 [ 12.960705][ T269] ? netlink_deliver_tap+0x13c/0x330 [ 12.960709][ T269] netlink_unicast+0x486/0x750 [ 12.960713][ T269] ? netlink_attachskb+0x810/0x810 [ 12.960716][ T269] ? __lock_acquire+0x518/0xc20 [ 12.960720][ T269] netlink_sendmsg+0x735/0xc60 [ 12.960724][ T269] ? netlink_unicast+0x750/0x750 [ 12.960728][ T269] ? __might_fault+0x97/0x140 [ 12.960733][ T269] ____sys_sendmsg+0x415/0x880 [ 12.960736][ T269] ? copy_msghdr_from_user+0x279/0x420 [ 12.960738][ T269] ? get_timestamp.constprop.0+0x390/0x390 [ 12.960740][ T269] ? move_addr_to_kernel+0x40/0x40 [ 12.960747][ T269] ___sys_sendmsg+0x14e/0x1d0 [ 12.960750][ T269] ? copy_msghdr_from_user+0x420/0x420 [ 12.960766][ T269] __sys_sendmsg+0x12c/0x1d0 [ 12.960769][ T269] ? __sys_sendmsg_sock+0x20/0x20 [ 12.960776][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.960780][ T269] do_syscall_64+0xff/0x530 [ 12.960782][ T269] ? exc_page_fault+0xee/0x100 [ 12.960785][ T269] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.960787][ T269] RIP: 0033:0x7f518059e54e [ 12.960791][ T269] 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.960793][ T269] RSP: 002b:00007fff3bcb6ce0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.960796][ T269] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f518059e54e [ 12.960797][ T269] RDX: 0000000000000000 RSI: 00007fff3bcb6d90 RDI: 0000000000000005 [ 12.960798][ T269] RBP: 00007fff3bcb6cf0 R08: 0000000000000000 R09: 0000000000000000 [ 12.960799][ T269] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a917fe5 [ 12.960800][ T269] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff3bcb7450 [ 12.960807][ T269] [ 13.002383][ T269] br1: port 1(vx1) entered disabled state [ 13.002821][ T269] vx1: entered allmulticast mode [ 13.004513][ T269] vx1: entered promiscuous mode [ 13.005365][ T269] br1: port 1(vx1) entered blocking state [ 13.005879][ T269] br1: port 1(vx1) entered forwarding state [ 13.016473][ T269] ip (269) used greatest stack depth: 23336 bytes left [ 13.036155][ T270] br1: port 2(veth1) entered blocking state [ 13.036760][ T270] br1: port 2(veth1) entered disabled state [ 13.037120][ T270] veth1: entered allmulticast mode [ 13.038725][ T270] veth1: entered promiscuous mode [ 13.064489][ T186] br1: port 2(veth1) entered blocking state [ 13.064955][ T186] br1: port 2(veth1) entered forwarding state [ 13.132955][ T273] br1: port 3(veth2) entered blocking state [ 13.133489][ T273] br1: port 3(veth2) entered disabled state [ 13.133999][ T273] veth2: entered allmulticast mode [ 13.136962][ T273] veth2: entered promiscuous mode [ 13.172818][ T109] br1: port 3(veth2) entered blocking state [ 13.173183][ T109] br1: port 3(veth2) entered forwarding state [ 14.429446][ T319] br2: port 1(w1) entered blocking state [ 14.429464][ T319] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.429466][ T319] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 319, name: ip [ 14.429468][ T319] preempt_count: 1, expected: 0 [ 14.429469][ T319] RCU nest depth: 0, expected: 0 [ 14.429470][ T319] locks held by ip/319: 5, last CPU#0: [ 14.429472][ T319] #0: ffffffff990d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 14.429484][ T319] #1: ffffffff98969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.429491][ T319] #2: ffffffff98969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.429495][ T319] #3: ffffffff98889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.429499][ T319] #4: ffffffff98889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.429503][ T319] irq event stamp: 12538 [ 14.429504][ T319] hardirqs last enabled at (12537): [] __down_trylock_console_sem+0x86/0xa0 [ 14.429507][ T319] hardirqs last disabled at (12538): [] console_emit_next_record+0x3d4/0x4c0 [ 14.429509][ T319] softirqs last enabled at (12476): [] __alloc_skb+0x4c2/0x5f0 [ 14.429512][ T319] softirqs last disabled at (12474): [] __alloc_skb+0x4c2/0x5f0 [ 14.429515][ T319] Preemption disabled at: [ 14.429516][ T319] [] vprintk_emit+0x31b/0x3e0 [ 14.429521][ T319] CPU: 0 UID: 0 PID: 319 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.429525][ T319] Tainted: [W]=WARN [ 14.429526][ T319] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.429528][ T319] Call Trace: [ 14.429530][ T319] [ 14.429531][ T319] dump_stack_lvl+0x6f/0xa0 [ 14.429537][ T319] ? vprintk_emit+0x31b/0x3e0 [ 14.429539][ T319] __might_resched.cold+0x1fe/0x2c1 [ 14.429544][ T319] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.429548][ T319] ? __kmalloc_noprof+0xdb/0x760 [ 14.429554][ T319] __kmalloc_noprof+0x443/0x760 [ 14.429556][ T319] ? alloc_buf.isra.0+0x4b/0x260 [ 14.429562][ T319] ? do_raw_spin_unlock+0x59/0x250 [ 14.429565][ T319] alloc_buf.isra.0+0x4b/0x260 [ 14.429568][ T319] put_chars+0x1e1/0x2f0 [ 14.429570][ T319] ? prb_final_commit+0x50/0x50 [ 14.429572][ T319] ? __send_to_port+0x420/0x420 [ 14.429575][ T319] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.429580][ T319] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.429582][ T319] ? validate_chain+0x38b/0xc20 [ 14.429586][ T319] hvc_console_print+0x292/0x780 [ 14.429589][ T319] ? mark_usage+0x61/0x170 [ 14.429591][ T319] ? __lock_acquire+0x518/0xc20 [ 14.429592][ T319] ? __lock_acquire+0x518/0xc20 [ 14.429596][ T319] ? hvc_write+0x3a0/0x3a0 [ 14.429599][ T319] ? console_emit_next_record+0x1df/0x4c0 [ 14.429602][ T319] ? rcu_is_watching+0x16/0xd0 [ 14.429604][ T319] ? lock_acquire+0x13c/0x160 [ 14.429608][ T319] console_emit_next_record+0x22f/0x4c0 [ 14.429612][ T319] ? devkmsg_read+0x4b0/0x4b0 [ 14.429614][ T319] ? console_flush_one_record+0x106/0x710 [ 14.429618][ T319] ? rcu_is_watching+0x16/0xd0 [ 14.429620][ T319] ? lock_acquire+0x13c/0x160 [ 14.429624][ T319] console_flush_one_record+0x46f/0x710 [ 14.429628][ T319] ? console_emit_next_record+0x4c0/0x4c0 [ 14.429630][ T319] ? __lock_acquire+0x518/0xc20 [ 14.429636][ T319] console_unlock+0xee/0x1f0 [ 14.429639][ T319] ? console_flush_one_record+0x710/0x710 [ 14.429640][ T319] ? rcu_is_watching+0x16/0xd0 [ 14.429643][ T319] ? lock_acquire+0x60/0x160 [ 14.429647][ T319] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.429648][ T319] ? vprintk_emit+0x320/0x3e0 [ 14.429652][ T319] vprintk_emit+0x37c/0x3e0 [ 14.429656][ T319] ? wake_up_klogd_work_func+0x90/0x90 [ 14.429659][ T319] ? __lock_release.isra.0+0x69/0x1a0 [ 14.429662][ T319] ? mark_held_locks+0x40/0x70 [ 14.429665][ T319] _printk+0xc7/0x100 [ 14.429669][ T319] ? snapshot_read.cold+0x21/0x21 [ 14.429673][ T319] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 14.429686][ T319] ? do_setlink.isra.0+0xa31/0x2750 [ 14.429688][ T319] ? rtnl_newlink+0x9f1/0x11c0 [ 14.429689][ T319] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.429694][ T319] br_set_state+0x22f/0x430 [bridge] [ 14.429705][ T319] br_init_port+0xc4/0x200 [bridge] [ 14.429713][ T319] new_nbp+0x39c/0x580 [bridge] [ 14.429724][ T319] br_add_if+0x212/0x1320 [bridge] [ 14.429731][ T319] ? is_bpf_text_address+0x72/0x110 [ 14.429735][ T319] ? kernel_text_address+0x149/0x170 [ 14.429738][ T319] ? __kernel_text_address+0x12/0x30 [ 14.429742][ T319] do_set_master+0x357/0x580 [ 14.429747][ T319] do_setlink.isra.0+0xa31/0x2750 [ 14.429750][ T319] ? stack_trace_save+0x93/0xc0 [ 14.429753][ T319] ? rtnl_link_get_size+0x350/0x350 [ 14.429755][ T319] ? rcu_read_lock_any_held+0x66/0x90 [ 14.429757][ T319] ? stack_depot_save_flags+0x38e/0x790 [ 14.429761][ T319] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.429763][ T319] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.429765][ T319] ? validate_chain+0x38b/0xc20 [ 14.429768][ T319] ? kasan_save_stack+0x3d/0x50 [ 14.429771][ T319] ? kasan_save_stack+0x2f/0x50 [ 14.429772][ T319] ? kasan_save_track+0x14/0x30 [ 14.429775][ T319] ? __lock_acquire+0x518/0xc20 [ 14.429777][ T319] ? netlink_seq_next+0xe/0x60 [ 14.429781][ T319] ? ___sys_sendmsg+0xb0/0x1d0 [ 14.429785][ T319] ? lock_acquire.part.0+0xd4/0x280 [ 14.429787][ T319] ? rtnl_newlink+0x9a8/0x11c0 [ 14.429790][ T319] ? rcu_is_watching+0x16/0xd0 [ 14.429792][ T319] ? lock_acquire+0x13c/0x160 [ 14.429794][ T319] ? rcu_is_watching+0x16/0xd0 [ 14.429796][ T319] ? rcu_is_watching+0x16/0xd0 [ 14.429798][ T319] ? trace_contention_end+0xb3/0x180 [ 14.429802][ T319] ? __mutex_lock+0x1db/0x1ea0 [ 14.429805][ T319] ? __mutex_lock+0x9a3/0x1ea0 [ 14.429807][ T319] ? rtnl_newlink+0x9a8/0x11c0 [ 14.429811][ T319] ? ww_mutex_lock+0x160/0x160 [ 14.429812][ T319] ? nla_get_range_signed+0x3d0/0x3d0 [ 14.429818][ T319] ? __rtnl_newlink+0x3fa/0xa50 [ 14.429823][ T319] rtnl_newlink+0x9f1/0x11c0 [ 14.429828][ T319] ? rtnl_bridge_getlink+0x850/0x850 [ 14.429830][ T319] ? __lock_acquire+0x518/0xc20 [ 14.429835][ T319] ? lock_acquire.part.0+0xd4/0x280 [ 14.429837][ T319] ? find_held_lock+0x2b/0x80 [ 14.429839][ T319] ? rtnl_bridge_getlink+0x850/0x850 [ 14.429840][ T319] ? __lock_release.isra.0+0x69/0x1a0 [ 14.429844][ T319] ? rtnl_bridge_getlink+0x850/0x850 [ 14.429846][ T319] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.429850][ T319] ? rtnl_link_fill+0x920/0x920 [ 14.429851][ T319] ? __lock_acquire+0x518/0xc20 [ 14.429856][ T319] ? lock_acquire.part.0+0xd4/0x280 [ 14.429858][ T319] ? find_held_lock+0x2b/0x80 [ 14.429861][ T319] netlink_rcv_skb+0x14e/0x3a0 [ 14.429863][ T319] ? rtnl_link_fill+0x920/0x920 [ 14.429866][ T319] ? netlink_ack+0xcf0/0xcf0 [ 14.429872][ T319] ? netlink_deliver_tap+0xc5/0x330 [ 14.429873][ T319] ? netlink_deliver_tap+0x13c/0x330 [ 14.429878][ T319] netlink_unicast+0x486/0x750 [ 14.429882][ T319] ? netlink_attachskb+0x810/0x810 [ 14.429885][ T319] ? __lock_acquire+0x518/0xc20 [ 14.429889][ T319] netlink_sendmsg+0x735/0xc60 [ 14.429893][ T319] ? netlink_unicast+0x750/0x750 [ 14.429896][ T319] ? __might_fault+0x97/0x140 [ 14.429902][ T319] ____sys_sendmsg+0x415/0x880 [ 14.429904][ T319] ? copy_msghdr_from_user+0x279/0x420 [ 14.429907][ T319] ? get_timestamp.constprop.0+0x390/0x390 [ 14.429908][ T319] ? move_addr_to_kernel+0x40/0x40 [ 14.429916][ T319] ___sys_sendmsg+0x14e/0x1d0 [ 14.429919][ T319] ? copy_msghdr_from_user+0x420/0x420 [ 14.429935][ T319] __sys_sendmsg+0x12c/0x1d0 [ 14.429938][ T319] ? __sys_sendmsg_sock+0x20/0x20 [ 14.429944][ T319] ? rcu_is_watching+0x16/0xd0 [ 14.429948][ T319] do_syscall_64+0xff/0x530 [ 14.429950][ T319] ? exc_page_fault+0xee/0x100 [ 14.429953][ T319] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.429956][ T319] RIP: 0033:0x7f510b56354e [ 14.429959][ T319] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 14.429961][ T319] RSP: 002b:00007ffd84d63710 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 14.429964][ T319] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f510b56354e [ 14.429966][ T319] RDX: 0000000000000000 RSI: 00007ffd84d637c0 RDI: 0000000000000005 [ 14.429967][ T319] RBP: 00007ffd84d63720 R08: 0000000000000000 R09: 0000000000000000 [ 14.429968][ T319] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a917fe6 [ 14.429968][ T319] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd84d63e80 [ 14.429975][ T319] [ 14.469524][ T319] br2: port 1(w1) entered disabled state [ 14.469822][ T319] w1: entered allmulticast mode [ 14.471509][ T319] w1: entered promiscuous mode [ 14.515802][ T320] br2: port 1(w1) entered blocking state [ 14.516448][ T320] br2: port 1(w1) entered forwarding state [ 14.650442][ T325] br2: port 2(vx2) entered blocking state [ 14.650736][ T325] br2: port 2(vx2) entered disabled state [ 14.650994][ T325] vx2: entered allmulticast mode [ 14.653947][ T325] vx2: entered promiscuous mode [ 14.654929][ T325] br2: port 2(vx2) entered blocking state [ 14.655183][ T325] br2: port 2(vx2) entered forwarding state [ 15.509277][ T357] br2: port 1(w1) entered blocking state [ 15.509296][ T357] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.509299][ T357] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 357, name: ip [ 15.509300][ T357] preempt_count: 1, expected: 0 [ 15.509301][ T357] RCU nest depth: 0, expected: 0 [ 15.509302][ T357] locks held by ip/357: 5, last CPU#3: [ 15.509304][ T357] #0: ffffffff990d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 15.509317][ T357] #1: ffffffff98969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.509323][ T357] #2: ffffffff98969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.509327][ T357] #3: ffffffff98889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.509331][ T357] #4: ffffffff98889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.509335][ T357] irq event stamp: 19302 [ 15.509336][ T357] hardirqs last enabled at (19301): [] __down_trylock_console_sem+0x86/0xa0 [ 15.509339][ T357] hardirqs last disabled at (19302): [] console_emit_next_record+0x3d4/0x4c0 [ 15.509341][ T357] softirqs last enabled at (19244): [] __alloc_skb+0x4c2/0x5f0 [ 15.509344][ T357] softirqs last disabled at (19242): [] __alloc_skb+0x4c2/0x5f0 [ 15.509347][ T357] Preemption disabled at: [ 15.509347][ T357] [] vprintk_emit+0x31b/0x3e0 [ 15.509353][ T357] CPU: 3 UID: 0 PID: 357 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.509357][ T357] Tainted: [W]=WARN [ 15.509358][ T357] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.509360][ T357] Call Trace: [ 15.509361][ T357] [ 15.509363][ T357] dump_stack_lvl+0x6f/0xa0 [ 15.509369][ T357] ? vprintk_emit+0x31b/0x3e0 [ 15.509371][ T357] __might_resched.cold+0x1fe/0x2c1 [ 15.509376][ T357] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.509380][ T357] ? __kmalloc_noprof+0xdb/0x760 [ 15.509385][ T357] __kmalloc_noprof+0x443/0x760 [ 15.509387][ T357] ? alloc_buf.isra.0+0x4b/0x260 [ 15.509393][ T357] ? do_raw_spin_unlock+0x59/0x250 [ 15.509396][ T357] alloc_buf.isra.0+0x4b/0x260 [ 15.509399][ T357] put_chars+0x1e1/0x2f0 [ 15.509401][ T357] ? prb_final_commit+0x50/0x50 [ 15.509403][ T357] ? __send_to_port+0x420/0x420 [ 15.509406][ T357] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.509411][ T357] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.509413][ T357] ? validate_chain+0x38b/0xc20 [ 15.509417][ T357] hvc_console_print+0x292/0x780 [ 15.509420][ T357] ? mark_usage+0x61/0x170 [ 15.509422][ T357] ? __lock_acquire+0x518/0xc20 [ 15.509423][ T357] ? __lock_acquire+0x518/0xc20 [ 15.509427][ T357] ? hvc_write+0x3a0/0x3a0 [ 15.509431][ T357] ? console_emit_next_record+0x1df/0x4c0 [ 15.509435][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.509437][ T357] ? lock_acquire+0x13c/0x160 [ 15.509441][ T357] console_emit_next_record+0x22f/0x4c0 [ 15.509445][ T357] ? devkmsg_read+0x4b0/0x4b0 [ 15.509447][ T357] ? console_flush_one_record+0x106/0x710 [ 15.509450][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.509453][ T357] ? lock_acquire+0x13c/0x160 [ 15.509457][ T357] console_flush_one_record+0x46f/0x710 [ 15.509461][ T357] ? console_emit_next_record+0x4c0/0x4c0 [ 15.509463][ T357] ? __lock_acquire+0x518/0xc20 [ 15.509469][ T357] console_unlock+0xee/0x1f0 [ 15.509472][ T357] ? console_flush_one_record+0x710/0x710 [ 15.509473][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.509476][ T357] ? lock_acquire+0x60/0x160 [ 15.509480][ T357] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.509481][ T357] ? vprintk_emit+0x320/0x3e0 [ 15.509485][ T357] vprintk_emit+0x37c/0x3e0 [ 15.509489][ T357] ? wake_up_klogd_work_func+0x90/0x90 [ 15.509492][ T357] ? __lock_release.isra.0+0x69/0x1a0 [ 15.509494][ T357] ? mark_held_locks+0x40/0x70 [ 15.509498][ T357] _printk+0xc7/0x100 [ 15.509502][ T357] ? snapshot_read.cold+0x21/0x21 [ 15.509506][ T357] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 15.509519][ T357] ? do_setlink.isra.0+0xa31/0x2750 [ 15.509521][ T357] ? rtnl_newlink+0x9f1/0x11c0 [ 15.509522][ T357] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 15.509526][ T357] br_set_state+0x22f/0x430 [bridge] [ 15.509537][ T357] br_init_port+0xc4/0x200 [bridge] [ 15.509546][ T357] new_nbp+0x39c/0x580 [bridge] [ 15.509555][ T357] br_add_if+0x212/0x1320 [bridge] [ 15.509562][ T357] ? is_bpf_text_address+0x72/0x110 [ 15.509566][ T357] ? kernel_text_address+0x149/0x170 [ 15.509569][ T357] ? __kernel_text_address+0x12/0x30 [ 15.509573][ T357] do_set_master+0x357/0x580 [ 15.509578][ T357] do_setlink.isra.0+0xa31/0x2750 [ 15.509581][ T357] ? stack_trace_save+0x93/0xc0 [ 15.509584][ T357] ? rtnl_link_get_size+0x350/0x350 [ 15.509585][ T357] ? rcu_read_lock_any_held+0x66/0x90 [ 15.509587][ T357] ? stack_depot_save_flags+0x38e/0x790 [ 15.509591][ T357] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.509593][ T357] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.509595][ T357] ? validate_chain+0x38b/0xc20 [ 15.509598][ T357] ? kasan_save_stack+0x3d/0x50 [ 15.509601][ T357] ? kasan_save_stack+0x2f/0x50 [ 15.509603][ T357] ? kasan_save_track+0x14/0x30 [ 15.509606][ T357] ? __lock_acquire+0x518/0xc20 [ 15.509607][ T357] ? netlink_seq_next+0xe/0x60 [ 15.509610][ T357] ? ___sys_sendmsg+0xb0/0x1d0 [ 15.509615][ T357] ? lock_acquire.part.0+0xd4/0x280 [ 15.509617][ T357] ? rtnl_newlink+0x9a8/0x11c0 [ 15.509620][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.509622][ T357] ? lock_acquire+0x13c/0x160 [ 15.509624][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.509626][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.509628][ T357] ? trace_contention_end+0xb3/0x180 [ 15.509632][ T357] ? __mutex_lock+0x1db/0x1ea0 [ 15.509635][ T357] ? __mutex_lock+0x9a3/0x1ea0 [ 15.509637][ T357] ? rtnl_newlink+0x9a8/0x11c0 [ 15.509641][ T357] ? ww_mutex_lock+0x160/0x160 [ 15.509642][ T357] ? nla_get_range_signed+0x3d0/0x3d0 [ 15.509647][ T357] ? __rtnl_newlink+0x3fa/0xa50 [ 15.509653][ T357] rtnl_newlink+0x9f1/0x11c0 [ 15.509658][ T357] ? rtnl_bridge_getlink+0x850/0x850 [ 15.509660][ T357] ? __lock_acquire+0x518/0xc20 [ 15.509664][ T357] ? lock_acquire.part.0+0xd4/0x280 [ 15.509666][ T357] ? find_held_lock+0x2b/0x80 [ 15.509668][ T357] ? rtnl_bridge_getlink+0x850/0x850 [ 15.509670][ T357] ? __lock_release.isra.0+0x69/0x1a0 [ 15.509674][ T357] ? rtnl_bridge_getlink+0x850/0x850 [ 15.509676][ T357] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 15.509679][ T357] ? rtnl_link_fill+0x920/0x920 [ 15.509681][ T357] ? __lock_acquire+0x518/0xc20 [ 15.509685][ T357] ? lock_acquire.part.0+0xd4/0x280 [ 15.509687][ T357] ? find_held_lock+0x2b/0x80 [ 15.509690][ T357] netlink_rcv_skb+0x14e/0x3a0 [ 15.509692][ T357] ? rtnl_link_fill+0x920/0x920 [ 15.509695][ T357] ? netlink_ack+0xcf0/0xcf0 [ 15.509701][ T357] ? netlink_deliver_tap+0xc5/0x330 [ 15.509703][ T357] ? netlink_deliver_tap+0x13c/0x330 [ 15.509707][ T357] netlink_unicast+0x486/0x750 [ 15.509711][ T357] ? netlink_attachskb+0x810/0x810 [ 15.509714][ T357] ? __lock_acquire+0x518/0xc20 [ 15.509718][ T357] netlink_sendmsg+0x735/0xc60 [ 15.509722][ T357] ? netlink_unicast+0x750/0x750 [ 15.509726][ T357] ? __might_fault+0x97/0x140 [ 15.509731][ T357] ____sys_sendmsg+0x415/0x880 [ 15.509733][ T357] ? copy_msghdr_from_user+0x279/0x420 [ 15.509736][ T357] ? get_timestamp.constprop.0+0x390/0x390 [ 15.509737][ T357] ? move_addr_to_kernel+0x40/0x40 [ 15.509745][ T357] ___sys_sendmsg+0x14e/0x1d0 [ 15.509748][ T357] ? copy_msghdr_from_user+0x420/0x420 [ 15.509764][ T357] __sys_sendmsg+0x12c/0x1d0 [ 15.509767][ T357] ? __sys_sendmsg_sock+0x20/0x20 [ 15.509773][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.509777][ T357] do_syscall_64+0xff/0x530 [ 15.509779][ T357] ? exc_page_fault+0xee/0x100 [ 15.509782][ T357] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.509784][ T357] RIP: 0033:0x7fbfb36c854e [ 15.509788][ T357] 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 [ 15.509790][ T357] RSP: 002b:00007ffe922d3d30 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 15.509792][ T357] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fbfb36c854e [ 15.509794][ T357] RDX: 0000000000000000 RSI: 00007ffe922d3de0 RDI: 0000000000000005 [ 15.509795][ T357] RBP: 00007ffe922d3d40 R08: 0000000000000000 R09: 0000000000000000 [ 15.509796][ T357] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a917fe7 [ 15.509797][ T357] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe922d44a0 [ 15.509804][ T357] [ 15.551861][ T357] br2: port 1(w1) entered disabled state [ 15.552161][ T357] w1: entered allmulticast mode [ 15.553783][ T357] w1: entered promiscuous mode [ 15.588202][ T358] br2: port 1(w1) entered blocking state [ 15.588521][ T358] br2: port 1(w1) entered forwarding state [ 15.745084][ T363] br2: port 2(vx2) entered blocking state [ 15.745379][ T363] br2: port 2(vx2) entered disabled state [ 15.746012][ T363] vx2: entered allmulticast mode [ 15.747581][ T363] vx2: entered promiscuous mode [ 15.748294][ T363] br2: port 2(vx2) entered blocking state [ 15.748549][ T363] br2: port 2(vx2) entered forwarding state [ 23.078054][ T445] GACT probability NOT on [ 23.078071][ T445] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 23.078074][ T445] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 445, name: modprobe [ 23.078076][ T445] preempt_count: 1, expected: 0 [ 23.078076][ T445] RCU nest depth: 0, expected: 0 [ 23.078077][ T445] locks held by modprobe/445: 4, last CPU#1: [ 23.078080][ T445] #0: ffffffff98969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 23.078093][ T445] #1: ffffffff98969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 23.078097][ T445] #2: ffffffff98889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 23.078101][ T445] #3: ffffffff98889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 23.078105][ T445] irq event stamp: 24882 [ 23.078106][ T445] hardirqs last enabled at (24881): [] __down_trylock_console_sem+0x86/0xa0 [ 23.078108][ T445] hardirqs last disabled at (24882): [] console_emit_next_record+0x3d4/0x4c0 [ 23.078110][ T445] softirqs last enabled at (23746): [] handle_softirqs+0x67c/0x900 [ 23.078113][ T445] softirqs last disabled at (22649): [] __irq_exit_rcu+0x145/0x1c0 [ 23.078116][ T445] Preemption disabled at: [ 23.078117][ T445] [] vprintk_emit+0x31b/0x3e0 [ 23.078122][ T445] CPU: 1 UID: 0 PID: 445 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 23.078126][ T445] Tainted: [W]=WARN [ 23.078127][ T445] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 23.078128][ T445] Call Trace: [ 23.078130][ T445] [ 23.078131][ T445] dump_stack_lvl+0x6f/0xa0 [ 23.078136][ T445] ? vprintk_emit+0x31b/0x3e0 [ 23.078138][ T445] __might_resched.cold+0x1fe/0x2c1 [ 23.078143][ T445] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 23.078147][ T445] ? __kmalloc_noprof+0xdb/0x760 [ 23.078152][ T445] __kmalloc_noprof+0x443/0x760 [ 23.078154][ T445] ? alloc_buf.isra.0+0x4b/0x260 [ 23.078163][ T445] ? do_raw_spin_unlock+0x59/0x250 [ 23.078165][ T445] alloc_buf.isra.0+0x4b/0x260 [ 23.078169][ T445] put_chars+0x1e1/0x2f0 [ 23.078170][ T445] ? desc_read_finalized_seq+0x79/0x120 [ 23.078173][ T445] ? __send_to_port+0x420/0x420 [ 23.078176][ T445] ? rcu_read_lock_any_held+0x3c/0x90 [ 23.078179][ T445] ? validate_chain+0x38b/0xc20 [ 23.078183][ T445] hvc_console_print+0x292/0x780 [ 23.078186][ T445] ? __lock_acquire+0x518/0xc20 [ 23.078187][ T445] ? __lock_acquire+0x518/0xc20 [ 23.078192][ T445] ? hvc_write+0x3a0/0x3a0 [ 23.078193][ T445] ? console_emit_next_record+0x1df/0x4c0 [ 23.078196][ T445] ? rcu_is_watching+0x16/0xd0 [ 23.078199][ T445] ? lock_acquire+0x13c/0x160 [ 23.078203][ T445] console_emit_next_record+0x22f/0x4c0 [ 23.078206][ T445] ? devkmsg_read+0x4b0/0x4b0 [ 23.078208][ T445] ? console_flush_one_record+0x106/0x710 [ 23.078211][ T445] ? rcu_is_watching+0x16/0xd0 [ 23.078213][ T445] ? lock_acquire+0x13c/0x160 [ 23.078217][ T445] console_flush_one_record+0x46f/0x710 [ 23.078221][ T445] ? console_emit_next_record+0x4c0/0x4c0 [ 23.078223][ T445] ? __lock_acquire+0x518/0xc20 [ 23.078228][ T445] console_unlock+0xee/0x1f0 [ 23.078230][ T445] ? console_flush_one_record+0x710/0x710 [ 23.078232][ T445] ? rcu_is_watching+0x16/0xd0 [ 23.078234][ T445] ? lock_acquire+0x60/0x160 [ 23.078238][ T445] ? __down_trylock_console_sem+0x5e/0xa0 [ 23.078240][ T445] ? vprintk_emit+0x320/0x3e0 [ 23.078243][ T445] vprintk_emit+0x37c/0x3e0 [ 23.078247][ T445] ? wake_up_klogd_work_func+0x90/0x90 [ 23.078253][ T445] ? llc_exit+0x3e0/0x3e0 [llc] [ 23.078256][ T445] _printk+0xc7/0x100 [ 23.078260][ T445] ? snapshot_read.cold+0x21/0x21 [ 23.078266][ T445] ? do_one_initcall+0x113/0x4f0 [ 23.078269][ T445] gact_init_module+0x15/0x1000 [act_gact] [ 23.078271][ T445] do_one_initcall+0x124/0x4f0 [ 23.078273][ T445] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 23.078278][ T445] ? do_init_module+0xae/0x8a0 [ 23.078281][ T445] ? kasan_unpoison+0x44/0x70 [ 23.078286][ T445] do_init_module+0x2ad/0x8a0 [ 23.078289][ T445] ? lockdep_hardirqs_on+0x91/0x130 [ 23.078293][ T445] ? free_module+0x470/0x470 [ 23.078296][ T445] ? kfree+0x123/0x590 [ 23.078300][ T445] load_module+0x1424/0x1bb0 [ 23.078307][ T445] ? layout_and_allocate.isra.0+0x680/0x680 [ 23.078311][ T445] ? __ia32_sys_fsconfig+0x180/0x180 [ 23.078314][ T445] ? ovl_real_file+0x172/0x1e0 [ 23.078318][ T445] ? backing_file_read_iter+0x541/0x790 [ 23.078321][ T445] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 23.078326][ T445] init_module_from_file+0x11f/0x1a0 [ 23.078329][ T445] ? __do_sys_init_module+0x220/0x220 [ 23.078337][ T445] ? __rwlock_init+0x150/0x150 [ 23.078340][ T445] ? do_raw_spin_unlock+0x59/0x250 [ 23.078343][ T445] idempotent_init_module+0x37b/0x7c0 [ 23.078347][ T445] ? init_module_from_file+0x1a0/0x1a0 [ 23.078353][ T445] ? rcu_is_watching+0x16/0xd0 [ 23.078355][ T445] ? cap_capable+0x1d7/0x3d0 [ 23.078361][ T445] __x64_sys_finit_module+0xdf/0x190 [ 23.078363][ T445] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 23.078366][ T445] do_syscall_64+0xff/0x530 [ 23.078368][ T445] ? exc_page_fault+0xee/0x100 [ 23.078371][ T445] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 23.078373][ T445] RIP: 0033:0x7f38aa04637d [ 23.078376][ T445] 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 [ 23.078377][ T445] RSP: 002b:00007ffd580beaf8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 23.078380][ T445] RAX: ffffffffffffffda RBX: 0000560a71b39810 RCX: 00007f38aa04637d [ 23.078382][ T445] RDX: 0000000000000000 RSI: 0000560a6db86ffd RDI: 0000000000000000 [ 23.078382][ T445] RBP: 00007ffd580beb90 R08: 0000000000000000 R09: 0000000000000000 [ 23.078383][ T445] R10: 0000000000000000 R11: 0000000000000246 R12: 0000560a6db86ffd [ 23.078384][ T445] R13: 0000560a71b39960 R14: 0000000000040000 R15: 0000000000000000 [ 23.078391][ T445] [ 28.034494][ C2] [ 28.034513][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 28.034515][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 28.034517][ C2] preempt_count: 104, expected: 0 [ 28.034518][ C2] RCU nest depth: 0, expected: 0 [ 28.034519][ C2] INFO: lockdep is turned off. [ 28.034520][ C2] irq event stamp: 869352 [ 28.034521][ C2] hardirqs last enabled at (869352): [] _raw_spin_unlock_irq+0x28/0x50 [ 28.034530][ C2] hardirqs last disabled at (869351): [] _raw_spin_lock_irq+0x4a/0x50 [ 28.034532][ C2] softirqs last enabled at (869306): [] handle_softirqs+0x67c/0x900 [ 28.034535][ C2] softirqs last disabled at (869349): [] __irq_exit_rcu+0x145/0x1c0 [ 28.034537][ C2] Preemption disabled at: [ 28.034538][ C2] [<0000000000000000>] 0x0 [ 28.034545][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.034549][ C2] Tainted: [W]=WARN [ 28.034550][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.034552][ C2] Call Trace: [ 28.034554][ C2] [ 28.034556][ C2] dump_stack_lvl+0x6f/0xa0 [ 28.034561][ C2] __might_resched.cold+0x1fe/0x2c1 [ 28.034565][ C2] ? find_held_lock+0x2b/0x80 [ 28.034568][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 28.034571][ C2] ? __asan_memcpy+0x3c/0x60 [ 28.034574][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.034578][ C2] __kmalloc_noprof+0x443/0x760 [ 28.034581][ C2] ? __rwlock_init+0x150/0x150 [ 28.034583][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 28.034586][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 28.034588][ C2] alloc_buf.isra.0+0x4b/0x260 [ 28.034590][ C2] put_chars+0x1e1/0x2f0 [ 28.034592][ C2] ? __send_to_port+0x420/0x420 [ 28.034594][ C2] ? console_prepend_replay+0x20/0x20 [ 28.034598][ C2] hvc_console_print+0x292/0x780 [ 28.034601][ C2] ? hvc_write+0x3a0/0x3a0 [ 28.034603][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.034605][ C2] ? lock_acquire+0x13c/0x160 [ 28.034607][ C2] console_emit_next_record+0x22f/0x4c0 [ 28.034609][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 28.034611][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.034613][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.034615][ C2] ? lock_acquire+0x13c/0x160 [ 28.034616][ C2] ? console_flush_one_record+0x111/0x710 [ 28.034618][ C2] console_flush_one_record+0x46f/0x710 [ 28.034621][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 28.034623][ C2] console_unlock+0xee/0x1f0 [ 28.034625][ C2] ? lock_acquire+0x13c/0x160 [ 28.034626][ C2] ? console_flush_one_record+0x710/0x710 [ 28.034628][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.034630][ C2] ? lock_release+0x184/0x1f0 [ 28.034631][ C2] ? lock_acquire+0x60/0x160 [ 28.034633][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 28.034635][ C2] ? vprintk_emit+0x320/0x3e0 [ 28.034637][ C2] vprintk_emit+0x37c/0x3e0 [ 28.034639][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 28.034641][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.034654][ C2] ? lock_release+0x184/0x1f0 [ 28.034656][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.034663][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.034669][ C2] ? is_module_text_address+0x154/0x250 [ 28.034673][ C2] _printk+0xc7/0x100 [ 28.034676][ C2] ? snapshot_read.cold+0x21/0x21 [ 28.034677][ C2] ? arch_stack_walk+0xd7/0x130 [ 28.034681][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 28.034684][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 28.034685][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 28.034687][ C2] mark_lock_irq+0x989/0x9c0 [ 28.034690][ C2] mark_lock+0x1d7/0xa00 [ 28.034692][ C2] mark_usage+0x42/0x170 [ 28.034694][ C2] __lock_acquire+0x388/0xc20 [ 28.034696][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.034698][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.034704][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.034706][ C2] ? lock_acquire+0x13c/0x160 [ 28.034708][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.034714][ C2] _raw_spin_lock+0x33/0x40 [ 28.034716][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.034722][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.034729][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.034736][ C2] call_timer_fn+0x160/0x4d0 [ 28.034739][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 28.034740][ C2] ? debug_object_active_state+0x430/0x430 [ 28.034744][ C2] ? find_held_lock+0x2b/0x80 [ 28.034746][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 28.034747][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.034750][ C2] __run_timers+0x68f/0xaa0 [ 28.034752][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.034759][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 28.034760][ C2] ? __lock_acquire+0x518/0xc20 [ 28.034763][ C2] ? __rwlock_init+0x150/0x150 [ 28.034765][ C2] run_timer_softirq+0xf0/0x160 [ 28.034768][ C2] ? __run_timers+0xaa0/0xaa0 [ 28.034769][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.034772][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.034773][ C2] handle_softirqs+0x1d3/0x900 [ 28.034775][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 28.034777][ C2] ? _local_bh_enable+0xc0/0xc0 [ 28.034779][ C2] __irq_exit_rcu+0x145/0x1c0 [ 28.034780][ C2] irq_exit_rcu+0xe/0x30 [ 28.034782][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.034785][ C2] [ 28.034786][ C2] [ 28.034786][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 28.034788][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.034790][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 28.034793][ C2] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 28.034795][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 28.034798][ C2] RAX: 00000000000d43e3 RBX: ff11000001bec6c0 RCX: ffffffff956f0307 [ 28.034800][ C2] RDX: ff11000001bec6c0 RSI: ffffffff98438af6 RDI: ffffffff97e8d8e0 [ 28.034800][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 28.034801][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 28.034802][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 28.034804][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.034807][ C2] default_idle+0x9/0x10 [ 28.034809][ C2] default_idle_call+0x6e/0xb0 [ 28.034811][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.034812][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 28.034814][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 28.034817][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.034818][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.034820][ C2] do_idle+0xd8/0x190 [ 28.034821][ C2] cpu_startup_entry+0x53/0x70 [ 28.034823][ C2] start_secondary+0x204/0x2b0 [ 28.034825][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 28.034827][ C2] common_startup_64+0x13e/0x148 [ 28.034831][ C2] [ 28.054272][ C2] ======================================================== [ 28.054528][ C2] WARNING: possible irq lock inversion dependency detected [ 28.054783][ C2] 7.2.0-virtme #1 Tainted: G W [ 28.054960][ C2] -------------------------------------------------------- [ 28.055220][ C2] swapper/2/0 just changed the state of lock: [ 28.055442][ C2] ff11000013d12e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.055781][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 28.056034][ C2] (fs_reclaim){+.+.}-{0:0} [ 28.056037][ C2] [ 28.056037][ C2] [ 28.056037][ C2] and interrupts could create inverse lock ordering between them. [ 28.056037][ C2] [ 28.056606][ C2] [ 28.056606][ C2] other info that might help us debug this: [ 28.056860][ C2] Chain exists of: [ 28.056860][ C2] &br->lock --> console_owner --> fs_reclaim [ 28.056860][ C2] [ 28.057230][ C2] Possible interrupt unsafe locking scenario: [ 28.057230][ C2] [ 28.057490][ C2] CPU0 CPU1 [ 28.057682][ C2] ---- ---- [ 28.057824][ C2] lock(fs_reclaim); [ 28.057931][ C2] local_irq_disable(); [ 28.058153][ C2] lock(&br->lock); [ 28.058381][ C2] lock(console_owner); [ 28.058558][ C2] [ 28.058715][ C2] lock(&br->lock); [ 28.058828][ C2] [ 28.058828][ C2] *** DEADLOCK *** [ 28.058828][ C2] [ 28.059088][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 28.059265][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 28.059599][ C2] [ 28.059599][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 28.059893][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 28.060092][ C2] HARDIRQ-ON-W at: [ 28.060208][ C2] __lock_acquire+0x388/0xc20 [ 28.060443][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.060621][ C2] fs_reclaim_acquire+0xd5/0x120 [ 28.060848][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 28.061116][ C2] kthread_create_worker_on_node+0xea/0x210 [ 28.061383][ C2] workqueue_init+0x2a/0x680 [ 28.061563][ C2] kernel_init_freeable+0x2fe/0x630 [ 28.061826][ C2] kernel_init+0x21/0x150 [ 28.062054][ C2] ret_from_fork+0x474/0x6b0 [ 28.062236][ C2] ret_from_fork_asm+0x11/0x20 [ 28.062465][ C2] SOFTIRQ-ON-W at: [ 28.062571][ C2] __lock_acquire+0x388/0xc20 [ 28.062796][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.062971][ C2] fs_reclaim_acquire+0xd5/0x120 [ 28.063199][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 28.063462][ C2] kthread_create_worker_on_node+0xea/0x210 [ 28.063721][ C2] workqueue_init+0x2a/0x680 [ 28.063898][ C2] kernel_init_freeable+0x2fe/0x630 [ 28.064156][ C2] kernel_init+0x21/0x150 [ 28.064389][ C2] ret_from_fork+0x474/0x6b0 [ 28.064568][ C2] ret_from_fork_asm+0x11/0x20 [ 28.064793][ C2] INITIAL USE at: [ 28.064899][ C2] __lock_acquire+0x388/0xc20 [ 28.065126][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.065307][ C2] fs_reclaim_acquire+0xd5/0x120 [ 28.065532][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 28.065792][ C2] kthread_create_worker_on_node+0xea/0x210 [ 28.066051][ C2] workqueue_init+0x2a/0x680 [ 28.066235][ C2] kernel_init_freeable+0x2fe/0x630 [ 28.066462][ C2] kernel_init+0x21/0x150 [ 28.066642][ C2] ret_from_fork+0x474/0x6b0 [ 28.066866][ C2] ret_from_fork_asm+0x11/0x20 [ 28.067093][ C2] } [ 28.067166][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 28.067430][ C2] ... acquired at: [ 28.067538][ C2] __lock_acquire+0x518/0xc20 [ 28.067733][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.067875][ C2] fs_reclaim_acquire+0xd5/0x120 [ 28.068059][ C2] __kmalloc_noprof+0xd3/0x760 [ 28.068206][ C2] alloc_buf.isra.0+0x4b/0x260 [ 28.068397][ C2] put_chars+0x1e1/0x2f0 [ 28.068537][ C2] hvc_console_print+0x292/0x780 [ 28.068743][ C2] console_emit_next_record+0x22f/0x4c0 [ 28.068885][ C2] console_flush_one_record+0x46f/0x710 [ 28.069075][ C2] console_unlock+0xee/0x1f0 [ 28.069223][ C2] vprintk_emit+0x37c/0x3e0 [ 28.069412][ C2] _printk+0xc7/0x100 [ 28.069520][ C2] loop_init+0x12a/0x130 [ 28.069664][ C2] do_one_initcall+0x124/0x4f0 [ 28.069856][ C2] kernel_init_freeable+0x596/0x630 [ 28.069997][ C2] kernel_init+0x21/0x150 [ 28.070193][ C2] ret_from_fork+0x474/0x6b0 [ 28.070336][ C2] ret_from_fork_asm+0x11/0x20 [ 28.070528][ C2] [ 28.070601][ C2] -> (console_owner){....}-{0:0} { [ 28.070794][ C2] INITIAL USE at: [ 28.070902][ C2] } [ 28.070973][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 28.071238][ C2] ... acquired at: [ 28.071346][ C2] __lock_acquire+0x518/0xc20 [ 28.071532][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.071672][ C2] console_lock_spinning_enable+0x5c/0x60 [ 28.071902][ C2] console_emit_next_record+0x1d1/0x4c0 [ 28.072092][ C2] console_flush_one_record+0x46f/0x710 [ 28.072240][ C2] console_unlock+0xee/0x1f0 [ 28.072431][ C2] vprintk_emit+0x37c/0x3e0 [ 28.072575][ C2] _printk+0xc7/0x100 [ 28.072683][ C2] br_set_state+0x22f/0x430 [bridge] [ 28.072879][ C2] br_init_port+0xc4/0x200 [bridge] [ 28.073028][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 28.073263][ C2] br_add_if+0xde2/0x1320 [bridge] [ 28.073458][ C2] do_set_master+0x357/0x580 [ 28.073600][ C2] do_setlink.isra.0+0xa31/0x2750 [ 28.073792][ C2] rtnl_newlink+0x9f1/0x11c0 [ 28.073934][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.074122][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 28.074271][ C2] netlink_unicast+0x486/0x750 [ 28.074465][ C2] netlink_sendmsg+0x735/0xc60 [ 28.074607][ C2] ____sys_sendmsg+0x415/0x880 [ 28.074798][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 28.074941][ C2] __sys_sendmsg+0x12c/0x1d0 [ 28.075132][ C2] do_syscall_64+0xff/0x530 [ 28.075281][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.075507][ C2] [ 28.075578][ C2] -> (&br->lock){+.-.}-{3:3} { [ 28.075725][ C2] HARDIRQ-ON-W at: [ 28.075879][ C2] __lock_acquire+0x388/0xc20 [ 28.076060][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.076292][ C2] _raw_spin_lock_bh+0x38/0x50 [ 28.076523][ C2] br_set_mac_address+0xe6/0x3b0 [bridge] [ 28.076746][ C2] netif_set_mac_address+0x282/0x410 [ 28.076973][ C2] do_setlink.isra.0+0x706/0x2750 [ 28.077211][ C2] rtnl_newlink+0x9f1/0x11c0 [ 28.077389][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.077571][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 28.077755][ C2] netlink_unicast+0x486/0x750 [ 28.077938][ C2] netlink_sendmsg+0x735/0xc60 [ 28.078166][ C2] ____sys_sendmsg+0x415/0x880 [ 28.078347][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 28.078572][ C2] __sys_sendmsg+0x12c/0x1d0 [ 28.078799][ C2] do_syscall_64+0xff/0x530 [ 28.078982][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.079252][ C2] IN-SOFTIRQ-W at: [ 28.079357][ C2] __lock_acquire+0x388/0xc20 [ 28.079583][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.079808][ C2] _raw_spin_lock+0x33/0x40 [ 28.079993][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.080264][ C2] call_timer_fn+0x160/0x4d0 [ 28.080494][ C2] __run_timers+0x68f/0xaa0 [ 28.080674][ C2] run_timer_softirq+0xf0/0x160 [ 28.080900][ C2] handle_softirqs+0x1d3/0x900 [ 28.081132][ C2] __irq_exit_rcu+0x145/0x1c0 [ 28.081316][ C2] irq_exit_rcu+0xe/0x30 [ 28.081545][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.081807][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.082023][ C2] pv_native_safe_halt+0xf/0x10 [ 28.082255][ C2] default_idle+0x9/0x10 [ 28.082488][ C2] default_idle_call+0x6e/0xb0 [ 28.082671][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.082936][ C2] do_idle+0xd8/0x190 [ 28.083079][ C2] cpu_startup_entry+0x53/0x70 [ 28.083313][ C2] start_secondary+0x204/0x2b0 [ 28.083493][ C2] common_startup_64+0x13e/0x148 [ 28.083674][ C2] INITIAL USE at: [ 28.083829][ C2] __lock_acquire+0x388/0xc20 [ 28.084012][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.084245][ C2] _raw_spin_lock_bh+0x38/0x50 [ 28.084471][ C2] br_set_mac_address+0xe6/0x3b0 [bridge] [ 28.084695][ C2] netif_set_mac_address+0x282/0x410 [ 28.084920][ C2] do_setlink.isra.0+0x706/0x2750 [ 28.085147][ C2] rtnl_newlink+0x9f1/0x11c0 [ 28.085329][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.085554][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 28.085731][ C2] netlink_unicast+0x486/0x750 [ 28.085963][ C2] netlink_sendmsg+0x735/0xc60 [ 28.086195][ C2] ____sys_sendmsg+0x415/0x880 [ 28.086375][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 28.086551][ C2] __sys_sendmsg+0x12c/0x1d0 [ 28.086772][ C2] do_syscall_64+0xff/0x530 [ 28.086944][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.087209][ C2] } [ 28.087280][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 28.087531][ C2] ... acquired at: [ 28.087631][ C2] mark_lock+0x1d7/0xa00 [ 28.087813][ C2] mark_usage+0x42/0x170 [ 28.087951][ C2] __lock_acquire+0x388/0xc20 [ 28.088141][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.088287][ C2] _raw_spin_lock+0x33/0x40 [ 28.088481][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.088664][ C2] call_timer_fn+0x160/0x4d0 [ 28.088850][ C2] __run_timers+0x68f/0xaa0 [ 28.088989][ C2] run_timer_softirq+0xf0/0x160 [ 28.089177][ C2] handle_softirqs+0x1d3/0x900 [ 28.089320][ C2] __irq_exit_rcu+0x145/0x1c0 [ 28.089503][ C2] irq_exit_rcu+0xe/0x30 [ 28.089645][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.089869][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.090087][ C2] pv_native_safe_halt+0xf/0x10 [ 28.090226][ C2] default_idle+0x9/0x10 [ 28.090411][ C2] default_idle_call+0x6e/0xb0 [ 28.090549][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.090768][ C2] do_idle+0xd8/0x190 [ 28.090872][ C2] cpu_startup_entry+0x53/0x70 [ 28.091057][ C2] start_secondary+0x204/0x2b0 [ 28.091198][ C2] common_startup_64+0x13e/0x148 [ 28.091380][ C2] [ 28.091448][ C2] [ 28.091448][ C2] stack backtrace: [ 28.091668][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.091671][ C2] Tainted: [W]=WARN [ 28.091672][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.091673][ C2] Call Trace: [ 28.091674][ C2] [ 28.091675][ C2] dump_stack_lvl+0x6f/0xa0 [ 28.091679][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 28.091682][ C2] mark_lock_irq+0x989/0x9c0 [ 28.091685][ C2] mark_lock+0x1d7/0xa00 [ 28.091687][ C2] mark_usage+0x42/0x170 [ 28.091688][ C2] __lock_acquire+0x388/0xc20 [ 28.091691][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.091692][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.091700][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.091703][ C2] ? lock_acquire+0x13c/0x160 [ 28.091705][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.091711][ C2] _raw_spin_lock+0x33/0x40 [ 28.091713][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.091719][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.091726][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.091733][ C2] call_timer_fn+0x160/0x4d0 [ 28.091735][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 28.091737][ C2] ? debug_object_active_state+0x430/0x430 [ 28.091740][ C2] ? find_held_lock+0x2b/0x80 [ 28.091741][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 28.091743][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.091745][ C2] __run_timers+0x68f/0xaa0 [ 28.091747][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.091754][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 28.091756][ C2] ? __lock_acquire+0x518/0xc20 [ 28.091759][ C2] ? __rwlock_init+0x150/0x150 [ 28.091761][ C2] run_timer_softirq+0xf0/0x160 [ 28.091763][ C2] ? __run_timers+0xaa0/0xaa0 [ 28.091765][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.091767][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.091769][ C2] handle_softirqs+0x1d3/0x900 [ 28.091771][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 28.091772][ C2] ? _local_bh_enable+0xc0/0xc0 [ 28.091774][ C2] __irq_exit_rcu+0x145/0x1c0 [ 28.091776][ C2] irq_exit_rcu+0xe/0x30 [ 28.091777][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.091779][ C2] [ 28.091779][ C2] [ 28.091780][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 28.091782][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.091783][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 28.091785][ C2] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 28.091787][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 28.091788][ C2] RAX: 00000000000d43e3 RBX: ff11000001bec6c0 RCX: ffffffff956f0307 [ 28.091790][ C2] RDX: ff11000001bec6c0 RSI: ffffffff98438af6 RDI: ffffffff97e8d8e0 [ 28.091791][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 28.091791][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 28.091792][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 28.091794][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.091796][ C2] default_idle+0x9/0x10 [ 28.091798][ C2] default_idle_call+0x6e/0xb0 [ 28.091799][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.091801][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 28.091802][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 28.091804][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.091805][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.091807][ C2] do_idle+0xd8/0x190 [ 28.091809][ C2] cpu_startup_entry+0x53/0x70 [ 28.091810][ C2] start_secondary+0x204/0x2b0 [ 28.091812][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 28.091814][ C2] common_startup_64+0x13e/0x148 [ 28.091817][ C2] [ 66.360024][ T12] vx2: left allmulticast mode [ 66.360042][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 66.360045][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 66.360048][ T12] preempt_count: 1, expected: 0 [ 66.360049][ T12] RCU nest depth: 0, expected: 0 [ 66.360050][ T12] INFO: lockdep is turned off. [ 66.360052][ T12] irq event stamp: 30210 [ 66.360053][ T12] hardirqs last enabled at (30209): [] _raw_spin_unlock_irq+0x28/0x50 [ 66.360063][ T12] hardirqs last disabled at (30210): [] __schedule+0x1246/0x20e0 [ 66.360066][ T12] softirqs last enabled at (30204): [] handle_softirqs+0x67c/0x900 [ 66.360071][ T12] softirqs last disabled at (30065): [] __irq_exit_rcu+0x145/0x1c0 [ 66.360074][ T12] Preemption disabled at: [ 66.360075][ T12] [] vprintk_emit+0x31b/0x3e0 [ 66.360084][ T12] CPU: 3 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 66.360090][ T12] Tainted: [W]=WARN [ 66.360091][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 66.360093][ T12] Workqueue: netns cleanup_net [ 66.360100][ T12] Call Trace: [ 66.360103][ T12] [ 66.360104][ T12] dump_stack_lvl+0x6f/0xa0 [ 66.360111][ T12] ? vprintk_emit+0x31b/0x3e0 [ 66.360114][ T12] __might_resched.cold+0x1fe/0x2c1 [ 66.360119][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 66.360123][ T12] ? rcu_is_watching+0x16/0xd0 [ 66.360128][ T12] __kmalloc_noprof+0x443/0x760 [ 66.360132][ T12] ? __rwlock_init+0x150/0x150 [ 66.360136][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 66.360141][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 66.360144][ T12] alloc_buf.isra.0+0x4b/0x260 [ 66.360147][ T12] put_chars+0x1e1/0x2f0 [ 66.360149][ T12] ? desc_read_finalized_seq+0x79/0x120 [ 66.360152][ T12] ? __send_to_port+0x420/0x420 [ 66.360155][ T12] ? console_prepend_replay+0x20/0x20 [ 66.360159][ T12] hvc_console_print+0x292/0x780 [ 66.360164][ T12] ? hvc_write+0x3a0/0x3a0 [ 66.360166][ T12] ? do_raw_spin_lock+0x131/0x280 [ 66.360168][ T12] ? __rwlock_init+0x150/0x150 [ 66.360171][ T12] ? rcu_is_watching+0x16/0xd0 [ 66.360173][ T12] ? rcu_is_watching+0x16/0xd0 [ 66.360176][ T12] ? lock_acquire+0x13c/0x160 [ 66.360179][ T12] console_emit_next_record+0x22f/0x4c0 [ 66.360183][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 66.360186][ T12] ? rcu_is_watching+0x16/0xd0 [ 66.360188][ T12] ? rcu_is_watching+0x16/0xd0 [ 66.360191][ T12] ? rcu_is_watching+0x16/0xd0 [ 66.360193][ T12] ? lock_acquire+0x13c/0x160 [ 66.360197][ T12] ? console_flush_one_record+0x111/0x710 [ 66.360199][ T12] console_flush_one_record+0x46f/0x710 [ 66.360203][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 66.360207][ T12] console_unlock+0xee/0x1f0 [ 66.360209][ T12] ? lock_acquire+0x13c/0x160 [ 66.360212][ T12] ? console_flush_one_record+0x710/0x710 [ 66.360214][ T12] ? rcu_is_watching+0x16/0xd0 [ 66.360217][ T12] ? lock_release+0x184/0x1f0 [ 66.360219][ T12] ? lock_acquire+0x60/0x160 [ 66.360222][ T12] ? __rcu_read_lock+0x30/0x70 [ 66.360225][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 66.360227][ T12] ? vprintk_emit+0x320/0x3e0 [ 66.360230][ T12] vprintk_emit+0x37c/0x3e0 [ 66.360234][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 66.360236][ T12] ? process_one_work+0xe3e/0x1560 [ 66.360240][ T12] ? kthread+0x367/0x460 [ 66.360242][ T12] ? ret_from_fork+0x474/0x6b0 [ 66.360246][ T12] ? ret_from_fork_asm+0x11/0x20 [ 66.360250][ T12] _printk+0xc7/0x100 [ 66.360254][ T12] ? snapshot_read.cold+0x21/0x21 [ 66.360257][ T12] ? netdev_name+0x43/0x60 [ 66.360261][ T12] ? __netdev_printk+0x17f/0x1d0 [ 66.360264][ T12] netdev_info+0xe2/0x116 [ 66.360267][ T12] ? netdev_notice+0x120/0x120 [ 66.360270][ T12] ? rcu_is_watching+0x16/0xd0 [ 66.360273][ T12] ? __up_read+0x355/0x9e0 [ 66.360275][ T12] ? trace_hardirqs_on+0x36/0x40 [ 66.360279][ T12] ? down_write_nested+0x200/0x200 [ 66.360281][ T12] ? mutex_is_locked+0x1c/0x50 [ 66.360285][ T12] netif_set_allmulti.cold+0x7c/0x166 [ 66.360289][ T12] dev_set_allmulti+0xa2/0x150 [ 66.360293][ T12] del_nbp+0xb7/0xc10 [bridge] [ 66.360312][ T12] br_dev_delete+0x9d/0x1e0 [bridge] [ 66.360329][ T12] br_net_exit_rtnl+0xb8/0x110 [bridge] [ 66.360342][ T12] ops_undo_list+0x5be/0x8f0 [ 66.360345][ T12] ? rtnl_net_dumpid_one+0x2d0/0x2d0 [ 66.360348][ T12] ? idr_destroy+0x121/0x250 [ 66.360353][ T12] cleanup_net+0x431/0x890 [ 66.360356][ T12] ? net_passive_dec+0x230/0x230 [ 66.360359][ T12] ? lock_acquire+0x13c/0x160 [ 66.360361][ T12] ? rcu_is_watching+0x16/0xd0 [ 66.360365][ T12] process_one_work+0xe3e/0x1560 [ 66.360369][ T12] ? __queue_delayed_work+0x470/0x470 [ 66.360372][ T12] ? find_held_lock+0x2b/0x80 [ 66.360375][ T12] ? __rwlock_init+0x150/0x150 [ 66.360379][ T12] worker_thread+0x4f1/0xd60 [ 66.360384][ T12] ? rescuer_thread+0x1340/0x1340 [ 66.360392][ T12] kthread+0x367/0x460 [ 66.360394][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 66.360396][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 66.360399][ T12] ret_from_fork+0x474/0x6b0 [ 66.360402][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 66.360405][ T12] ? __switch_to+0x5a3/0xe00 [ 66.360408][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 66.360411][ T12] ret_from_fork_asm+0x11/0x20 [ 66.360416][ T12] [ 66.396704][ T12] vx2: left promiscuous mode [ 66.397037][ T12] br2: port 2(vx2) entered disabled state [ 66.401678][ T12] w1: left allmulticast mode [ 66.401920][ T12] w1: left promiscuous mode [ 66.402231][ T12] br2: port 1(w1) entered disabled state [ 66.465146][ T12] vx2: left allmulticast mode [ 66.465342][ T12] vx2: left promiscuous mode [ 66.465696][ T12] br2: port 2(vx2) entered disabled state [ 66.466926][ T12] w1: left allmulticast mode [ 66.467082][ T12] w1: left promiscuous mode [ 66.467276][ T12] br2: port 1(w1) entered disabled state [ 66.920117][ T577] br1: port 3(veth2) entered disabled state [ 66.941200][ T578] veth2: left allmulticast mode [ 66.942007][ T578] veth2: left promiscuous mode [ 66.949777][ T578] br1: port 3(veth2) entered disabled state [ 66.989476][ T580] br1: port 2(veth1) entered disabled state [ 67.009620][ T581] veth1: left allmulticast mode [ 67.009871][ T581] veth1: left promiscuous mode [ 67.010205][ T581] br1: port 2(veth1) entered disabled state [ 67.038377][ T582] vx1: left allmulticast mode [ 67.038588][ T582] vx1: left promiscuous mode [ 67.038849][ T582] br1: port 1(vx1) entered disabled state