[ 10.851609][ T187] ip (187) used greatest stack depth: 23728 bytes left [ 10.851625][ T187] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.851627][ T187] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 187, name: ip [ 10.851628][ T187] preempt_count: 2, expected: 0 [ 10.851629][ T187] RCU nest depth: 0, expected: 0 [ 10.851630][ T187] locks held by ip/187: 5, last CPU#0: [ 10.851633][ T187] #0: ffffffff962027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.851646][ T187] #1: ffffffff96369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.851651][ T187] #2: ffffffff96369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.851655][ T187] #3: ffffffff96289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.851659][ T187] #4: ffffffff96289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.851663][ T187] irq event stamp: 34116 [ 10.851664][ T187] hardirqs last enabled at (34115): [] __down_trylock_console_sem+0x86/0xa0 [ 10.851667][ T187] hardirqs last disabled at (34116): [] console_emit_next_record+0x3d4/0x4c0 [ 10.851669][ T187] softirqs last enabled at (32836): [] netlink_release+0x17b/0xcf0 [ 10.851672][ T187] softirqs last disabled at (32834): [] netlink_release+0xd2/0xcf0 [ 10.851675][ T187] Preemption disabled at: [ 10.851675][ T187] [<0000000000000000>] 0x0 [ 10.851682][ T187] CPU: 0 UID: 0 PID: 187 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.851685][ T187] Tainted: [W]=WARN [ 10.851686][ T187] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.851688][ T187] Call Trace: [ 10.851690][ T187] [ 10.851691][ T187] dump_stack_lvl+0x6f/0xa0 [ 10.851697][ T187] __might_resched.cold+0x1fe/0x2c1 [ 10.851702][ T187] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.851706][ T187] ? __kmalloc_noprof+0xdb/0x760 [ 10.851712][ T187] __kmalloc_noprof+0x443/0x760 [ 10.851714][ T187] ? alloc_buf.isra.0+0x4b/0x260 [ 10.851720][ T187] ? do_raw_spin_unlock+0x59/0x250 [ 10.851722][ T187] alloc_buf.isra.0+0x4b/0x260 [ 10.851725][ T187] put_chars+0x1e1/0x2f0 [ 10.851728][ T187] ? __send_to_port+0x420/0x420 [ 10.851730][ T187] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.851735][ T187] ? validate_chain+0x38b/0xc20 [ 10.851740][ T187] hvc_console_print+0x292/0x780 [ 10.851746][ T187] ? hvc_write+0x3a0/0x3a0 [ 10.851749][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.851751][ T187] ? lock_acquire+0x13c/0x160 [ 10.851755][ T187] console_emit_next_record+0x22f/0x4c0 [ 10.851759][ T187] ? devkmsg_read+0x4b0/0x4b0 [ 10.851761][ T187] ? console_flush_one_record+0x106/0x710 [ 10.851764][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.851766][ T187] ? lock_acquire+0x13c/0x160 [ 10.851770][ T187] console_flush_one_record+0x46f/0x710 [ 10.851774][ T187] ? console_emit_next_record+0x4c0/0x4c0 [ 10.851776][ T187] ? __lock_acquire+0x518/0xc20 [ 10.851782][ T187] console_unlock+0xee/0x1f0 [ 10.851784][ T187] ? console_flush_one_record+0x710/0x710 [ 10.851786][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.851789][ T187] ? lock_acquire+0x60/0x160 [ 10.851792][ T187] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.851794][ T187] ? vprintk_emit+0x320/0x3e0 [ 10.851797][ T187] vprintk_emit+0x37c/0x3e0 [ 10.851801][ T187] ? wake_up_klogd_work_func+0x90/0x90 [ 10.851805][ T187] ? __lock_acquire+0x518/0xc20 [ 10.851808][ T187] _printk+0xc7/0x100 [ 10.851812][ T187] ? snapshot_read.cold+0x21/0x21 [ 10.851815][ T187] ? do_raw_spin_lock+0x131/0x280 [ 10.851818][ T187] ? __rwlock_init+0x150/0x150 [ 10.851822][ T187] ? do_raw_spin_lock+0x131/0x280 [ 10.851825][ T187] do_exit.cold+0x82/0x9c [ 10.851828][ T187] ? exit_notify+0x890/0x890 [ 10.851830][ T187] ? __lock_release.isra.0+0x69/0x1a0 [ 10.851833][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.851837][ T187] do_group_exit+0xb8/0x370 [ 10.851840][ T187] __x64_sys_exit_group+0x3c/0x50 [ 10.851842][ T187] x64_sys_call+0x1567/0x1570 [ 10.851844][ T187] do_syscall_64+0xff/0x530 [ 10.851847][ T187] ? exc_page_fault+0xee/0x100 [ 10.851850][ T187] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.851852][ T187] RIP: 0033:0x7fd1020df1b8 [ 10.851854][ T187] Code: Unable to access opcode bytes at 0x7fd1020df18e. [ 10.851856][ T187] RSP: 002b:00007fff63b6ebf8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.851858][ T187] RAX: ffffffffffffffda RBX: 00007fd10220ff88 RCX: 00007fd1020df1b8 [ 10.851859][ T187] RDX: 00007fd101e29fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.851860][ T187] RBP: 00007fff63b6ec50 R08: 0000000000000000 R09: 0000000000008010 [ 10.851861][ T187] R10: 00007fff63b6ea10 R11: 0000000000000246 R12: 0000000000000001 [ 10.851862][ T187] R13: 0000000000000000 R14: 00007fd10220e680 R15: 00007fd10220ffa0 [ 10.851869][ T187] [ 12.034035][ T239] br1: port 1(veth1) entered blocking state [ 12.034118][ T239] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.034120][ T239] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 239, name: ip [ 12.034121][ T239] preempt_count: 1, expected: 0 [ 12.034122][ T239] RCU nest depth: 0, expected: 0 [ 12.034123][ T239] locks held by ip/239: 5, last CPU#1: [ 12.034126][ T239] #0: ffffffff96ad2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 12.034138][ T239] #1: ffffffff96369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.034144][ T239] #2: ffffffff96369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.034148][ T239] #3: ffffffff96289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.034152][ T239] #4: ffffffff96289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.034156][ T239] irq event stamp: 15354 [ 12.034157][ T239] hardirqs last enabled at (15353): [] __down_trylock_console_sem+0x86/0xa0 [ 12.034159][ T239] hardirqs last disabled at (15354): [] console_emit_next_record+0x3d4/0x4c0 [ 12.034165][ T239] softirqs last enabled at (15262): [] __alloc_skb+0x4c2/0x5f0 [ 12.034168][ T239] softirqs last disabled at (15260): [] __alloc_skb+0x4c2/0x5f0 [ 12.034171][ T239] Preemption disabled at: [ 12.034172][ T239] [] vprintk_emit+0x31b/0x3e0 [ 12.034178][ T239] CPU: 1 UID: 0 PID: 239 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.034181][ T239] Tainted: [W]=WARN [ 12.034182][ T239] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.034184][ T239] Call Trace: [ 12.034185][ T239] [ 12.034187][ T239] dump_stack_lvl+0x6f/0xa0 [ 12.034192][ T239] ? vprintk_emit+0x31b/0x3e0 [ 12.034195][ T239] __might_resched.cold+0x1fe/0x2c1 [ 12.034199][ T239] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.034203][ T239] ? __kmalloc_noprof+0xdb/0x760 [ 12.034209][ T239] __kmalloc_noprof+0x443/0x760 [ 12.034211][ T239] ? alloc_buf.isra.0+0x4b/0x260 [ 12.034216][ T239] ? do_raw_spin_unlock+0x59/0x250 [ 12.034219][ T239] alloc_buf.isra.0+0x4b/0x260 [ 12.034222][ T239] put_chars+0x1e1/0x2f0 [ 12.034225][ T239] ? __send_to_port+0x420/0x420 [ 12.034229][ T239] ? validate_chain+0x34a/0xc20 [ 12.034232][ T239] hvc_console_print+0x292/0x780 [ 12.034235][ T239] ? mark_usage+0x61/0x170 [ 12.034237][ T239] ? __lock_acquire+0x518/0xc20 [ 12.034239][ T239] ? __lock_acquire+0x518/0xc20 [ 12.034242][ T239] ? hvc_write+0x3a0/0x3a0 [ 12.034244][ T239] ? console_emit_next_record+0x1df/0x4c0 [ 12.034247][ T239] ? rcu_is_watching+0x16/0xd0 [ 12.034252][ T239] ? lock_acquire+0x13c/0x160 [ 12.034256][ T239] console_emit_next_record+0x22f/0x4c0 [ 12.034259][ T239] ? devkmsg_read+0x4b0/0x4b0 [ 12.034261][ T239] ? console_flush_one_record+0x106/0x710 [ 12.034264][ T239] ? rcu_is_watching+0x16/0xd0 [ 12.034266][ T239] ? lock_acquire+0x13c/0x160 [ 12.034270][ T239] console_flush_one_record+0x46f/0x710 [ 12.034274][ T239] ? console_emit_next_record+0x4c0/0x4c0 [ 12.034276][ T239] ? __lock_acquire+0x518/0xc20 [ 12.034281][ T239] console_unlock+0xee/0x1f0 [ 12.034284][ T239] ? console_flush_one_record+0x710/0x710 [ 12.034286][ T239] ? rcu_is_watching+0x16/0xd0 [ 12.034288][ T239] ? lock_acquire+0x60/0x160 [ 12.034291][ T239] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.034293][ T239] ? vprintk_emit+0x320/0x3e0 [ 12.034296][ T239] vprintk_emit+0x37c/0x3e0 [ 12.034300][ T239] ? wake_up_klogd_work_func+0x90/0x90 [ 12.034302][ T239] ? __lock_release.isra.0+0x69/0x1a0 [ 12.034304][ T239] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.034308][ T239] ? mark_held_locks+0x40/0x70 [ 12.034311][ T239] _printk+0xc7/0x100 [ 12.034315][ T239] ? snapshot_read.cold+0x21/0x21 [ 12.034319][ T239] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 12.034331][ T239] ? do_setlink.isra.0+0xa31/0x2750 [ 12.034332][ T239] ? rtnl_newlink+0x9f1/0x11c0 [ 12.034334][ T239] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.034338][ T239] br_set_state+0x22f/0x430 [bridge] [ 12.034348][ T239] br_init_port+0xc4/0x200 [bridge] [ 12.034356][ T239] new_nbp+0x39c/0x580 [bridge] [ 12.034364][ T239] br_add_if+0x212/0x1320 [bridge] [ 12.034371][ T239] ? is_bpf_text_address+0x72/0x110 [ 12.034375][ T239] ? kernel_text_address+0x149/0x170 [ 12.034378][ T239] ? __kernel_text_address+0x12/0x30 [ 12.034382][ T239] do_set_master+0x357/0x580 [ 12.034386][ T239] do_setlink.isra.0+0xa31/0x2750 [ 12.034389][ T239] ? stack_trace_save+0x93/0xc0 [ 12.034393][ T239] ? rtnl_link_get_size+0x350/0x350 [ 12.034394][ T239] ? rcu_read_lock_any_held+0x66/0x90 [ 12.034396][ T239] ? stack_depot_save_flags+0x38e/0x790 [ 12.034400][ T239] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.034402][ T239] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.034404][ T239] ? validate_chain+0x38b/0xc20 [ 12.034407][ T239] ? kasan_save_stack+0x3d/0x50 [ 12.034410][ T239] ? kasan_save_stack+0x2f/0x50 [ 12.034411][ T239] ? kasan_save_track+0x14/0x30 [ 12.034414][ T239] ? __lock_acquire+0x518/0xc20 [ 12.034416][ T239] ? netlink_seq_next+0xe/0x60 [ 12.034419][ T239] ? ___sys_sendmsg+0xb0/0x1d0 [ 12.034423][ T239] ? lock_acquire.part.0+0xd4/0x280 [ 12.034431][ T239] ? rtnl_newlink+0x9a8/0x11c0 [ 12.034434][ T239] ? rcu_is_watching+0x16/0xd0 [ 12.034436][ T239] ? lock_acquire+0x13c/0x160 [ 12.034438][ T239] ? rcu_is_watching+0x16/0xd0 [ 12.034440][ T239] ? rcu_is_watching+0x16/0xd0 [ 12.034442][ T239] ? trace_contention_end+0xb3/0x180 [ 12.034446][ T239] ? __mutex_lock+0x1db/0x1ea0 [ 12.034448][ T239] ? __mutex_lock+0x9a3/0x1ea0 [ 12.034450][ T239] ? rtnl_newlink+0x9a8/0x11c0 [ 12.034453][ T239] ? ww_mutex_lock+0x160/0x160 [ 12.034455][ T239] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.034459][ T239] ? __rtnl_newlink+0x3fa/0xa50 [ 12.034464][ T239] rtnl_newlink+0x9f1/0x11c0 [ 12.034470][ T239] ? rtnl_bridge_getlink+0x850/0x850 [ 12.034471][ T239] ? __lock_acquire+0x518/0xc20 [ 12.034476][ T239] ? lock_acquire.part.0+0xd4/0x280 [ 12.034477][ T239] ? find_held_lock+0x2b/0x80 [ 12.034479][ T239] ? rtnl_bridge_getlink+0x850/0x850 [ 12.034481][ T239] ? __lock_release.isra.0+0x69/0x1a0 [ 12.034485][ T239] ? rtnl_bridge_getlink+0x850/0x850 [ 12.034487][ T239] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.034490][ T239] ? rtnl_link_fill+0x920/0x920 [ 12.034491][ T239] ? __lock_acquire+0x518/0xc20 [ 12.034495][ T239] ? lock_acquire.part.0+0xd4/0x280 [ 12.034497][ T239] ? find_held_lock+0x2b/0x80 [ 12.034500][ T239] netlink_rcv_skb+0x14e/0x3a0 [ 12.034502][ T239] ? rtnl_link_fill+0x920/0x920 [ 12.034505][ T239] ? netlink_ack+0xcf0/0xcf0 [ 12.034511][ T239] ? netlink_deliver_tap+0xc5/0x330 [ 12.034512][ T239] ? netlink_deliver_tap+0x13c/0x330 [ 12.034516][ T239] netlink_unicast+0x486/0x750 [ 12.034520][ T239] ? netlink_attachskb+0x810/0x810 [ 12.034523][ T239] ? __lock_acquire+0x518/0xc20 [ 12.034527][ T239] netlink_sendmsg+0x735/0xc60 [ 12.034530][ T239] ? netlink_unicast+0x750/0x750 [ 12.034534][ T239] ? __might_fault+0x97/0x140 [ 12.034539][ T239] ____sys_sendmsg+0x415/0x880 [ 12.034541][ T239] ? copy_msghdr_from_user+0x279/0x420 [ 12.034543][ T239] ? get_timestamp.constprop.0+0x390/0x390 [ 12.034545][ T239] ? move_addr_to_kernel+0x40/0x40 [ 12.034552][ T239] ___sys_sendmsg+0x14e/0x1d0 [ 12.034555][ T239] ? copy_msghdr_from_user+0x420/0x420 [ 12.034570][ T239] __sys_sendmsg+0x12c/0x1d0 [ 12.034573][ T239] ? __sys_sendmsg_sock+0x20/0x20 [ 12.034579][ T239] ? rcu_is_watching+0x16/0xd0 [ 12.034582][ T239] do_syscall_64+0xff/0x530 [ 12.034584][ T239] ? exc_page_fault+0xee/0x100 [ 12.034587][ T239] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.034590][ T239] RIP: 0033:0x7fdb3c26254e [ 12.034593][ T239] 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.034595][ T239] RSP: 002b:00007fff34b02940 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.034597][ T239] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fdb3c26254e [ 12.034599][ T239] RDX: 0000000000000000 RSI: 00007fff34b029f0 RDI: 0000000000000005 [ 12.034600][ T239] RBP: 00007fff34b02950 R08: 0000000000000000 R09: 0000000000000000 [ 12.034601][ T239] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a918303 [ 12.034601][ T239] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff34b030b0 [ 12.034608][ T239] [ 12.080330][ T239] br1: port 1(veth1) entered disabled state [ 12.080701][ T239] veth1: entered allmulticast mode [ 12.083235][ T239] veth1: entered promiscuous mode [ 12.092139][ T239] ip (239) used greatest stack depth: 23336 bytes left [ 12.124920][ T46] br1: port 1(veth1) entered blocking state [ 12.125563][ T46] br1: port 1(veth1) entered forwarding state [ 12.153854][ T241] br1: port 2(veth2) entered blocking state [ 12.154209][ T241] br1: port 2(veth2) entered disabled state [ 12.154556][ T241] veth2: entered allmulticast mode [ 12.156092][ T241] veth2: entered promiscuous mode [ 12.181431][ T46] br1: port 2(veth2) entered blocking state [ 12.181720][ T46] br1: port 2(veth2) entered forwarding state [ 17.660303][ T266] GACT probability NOT on [ 17.660321][ T266] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 17.660324][ T266] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 266, name: modprobe [ 17.660326][ T266] preempt_count: 1, expected: 0 [ 17.660327][ T266] RCU nest depth: 0, expected: 0 [ 17.660328][ T266] locks held by modprobe/266: 4, last CPU#1: [ 17.660330][ T266] #0: ffffffff96369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 17.660342][ T266] #1: ffffffff96369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 17.660346][ T266] #2: ffffffff96289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 17.660350][ T266] #3: ffffffff96289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 17.660354][ T266] irq event stamp: 28604 [ 17.660355][ T266] hardirqs last enabled at (28603): [] __down_trylock_console_sem+0x86/0xa0 [ 17.660357][ T266] hardirqs last disabled at (28604): [] console_emit_next_record+0x3d4/0x4c0 [ 17.660359][ T266] softirqs last enabled at (26542): [] handle_softirqs+0x67c/0x900 [ 17.660362][ T266] softirqs last disabled at (26531): [] __irq_exit_rcu+0x145/0x1c0 [ 17.660365][ T266] Preemption disabled at: [ 17.660365][ T266] [] vprintk_emit+0x31b/0x3e0 [ 17.660371][ T266] CPU: 1 UID: 0 PID: 266 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 17.660374][ T266] Tainted: [W]=WARN [ 17.660375][ T266] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.660377][ T266] Call Trace: [ 17.660378][ T266] [ 17.660380][ T266] dump_stack_lvl+0x6f/0xa0 [ 17.660385][ T266] ? vprintk_emit+0x31b/0x3e0 [ 17.660387][ T266] __might_resched.cold+0x1fe/0x2c1 [ 17.660392][ T266] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 17.660396][ T266] ? __kmalloc_noprof+0xdb/0x760 [ 17.660401][ T266] __kmalloc_noprof+0x443/0x760 [ 17.660403][ T266] ? alloc_buf.isra.0+0x4b/0x260 [ 17.660409][ T266] ? do_raw_spin_unlock+0x59/0x250 [ 17.660412][ T266] alloc_buf.isra.0+0x4b/0x260 [ 17.660415][ T266] put_chars+0x1e1/0x2f0 [ 17.660417][ T266] ? desc_read_finalized_seq+0x79/0x120 [ 17.660419][ T266] ? __send_to_port+0x420/0x420 [ 17.660422][ T266] ? rcu_read_lock_any_held+0x3c/0x90 [ 17.660425][ T266] ? validate_chain+0x38b/0xc20 [ 17.660429][ T266] hvc_console_print+0x292/0x780 [ 17.660432][ T266] ? __lock_acquire+0x518/0xc20 [ 17.660434][ T266] ? __lock_acquire+0x518/0xc20 [ 17.660438][ T266] ? hvc_write+0x3a0/0x3a0 [ 17.660439][ T266] ? console_emit_next_record+0x1df/0x4c0 [ 17.660442][ T266] ? rcu_is_watching+0x16/0xd0 [ 17.660445][ T266] ? lock_acquire+0x13c/0x160 [ 17.660454][ T266] console_emit_next_record+0x22f/0x4c0 [ 17.660457][ T266] ? devkmsg_read+0x4b0/0x4b0 [ 17.660459][ T266] ? console_flush_one_record+0x106/0x710 [ 17.660462][ T266] ? rcu_is_watching+0x16/0xd0 [ 17.660464][ T266] ? lock_acquire+0x13c/0x160 [ 17.660468][ T266] console_flush_one_record+0x46f/0x710 [ 17.660472][ T266] ? console_emit_next_record+0x4c0/0x4c0 [ 17.660474][ T266] ? __lock_acquire+0x518/0xc20 [ 17.660479][ T266] console_unlock+0xee/0x1f0 [ 17.660482][ T266] ? console_flush_one_record+0x710/0x710 [ 17.660483][ T266] ? rcu_is_watching+0x16/0xd0 [ 17.660485][ T266] ? lock_acquire+0x60/0x160 [ 17.660489][ T266] ? __down_trylock_console_sem+0x5e/0xa0 [ 17.660491][ T266] ? vprintk_emit+0x320/0x3e0 [ 17.660494][ T266] vprintk_emit+0x37c/0x3e0 [ 17.660498][ T266] ? wake_up_klogd_work_func+0x90/0x90 [ 17.660503][ T266] ? llc_exit+0x3e0/0x3e0 [llc] [ 17.660507][ T266] _printk+0xc7/0x100 [ 17.660510][ T266] ? snapshot_read.cold+0x21/0x21 [ 17.660516][ T266] ? do_one_initcall+0x113/0x4f0 [ 17.660519][ T266] gact_init_module+0x15/0x1000 [act_gact] [ 17.660522][ T266] do_one_initcall+0x124/0x4f0 [ 17.660525][ T266] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 17.660529][ T266] ? do_init_module+0xae/0x8a0 [ 17.660532][ T266] ? kasan_unpoison+0x44/0x70 [ 17.660537][ T266] do_init_module+0x2ad/0x8a0 [ 17.660540][ T266] ? lockdep_hardirqs_on+0x91/0x130 [ 17.660545][ T266] ? free_module+0x470/0x470 [ 17.660548][ T266] ? kfree+0x123/0x590 [ 17.660551][ T266] load_module+0x1424/0x1bb0 [ 17.660558][ T266] ? layout_and_allocate.isra.0+0x680/0x680 [ 17.660562][ T266] ? __ia32_sys_fsconfig+0x180/0x180 [ 17.660565][ T266] ? ovl_real_file+0x172/0x1e0 [ 17.660569][ T266] ? backing_file_read_iter+0x541/0x790 [ 17.660572][ T266] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.660576][ T266] init_module_from_file+0x11f/0x1a0 [ 17.660579][ T266] ? __do_sys_init_module+0x220/0x220 [ 17.660586][ T266] ? __rwlock_init+0x150/0x150 [ 17.660590][ T266] ? do_raw_spin_unlock+0x59/0x250 [ 17.660593][ T266] idempotent_init_module+0x37b/0x7c0 [ 17.660596][ T266] ? init_module_from_file+0x1a0/0x1a0 [ 17.660602][ T266] ? rcu_is_watching+0x16/0xd0 [ 17.660604][ T266] ? cap_capable+0x1d7/0x3d0 [ 17.660610][ T266] __x64_sys_finit_module+0xdf/0x190 [ 17.660612][ T266] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 17.660616][ T266] do_syscall_64+0xff/0x530 [ 17.660617][ T266] ? exc_page_fault+0xee/0x100 [ 17.660620][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 17.660622][ T266] RIP: 0033:0x7f6eacc0737d [ 17.660625][ T266] 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 [ 17.660627][ T266] RSP: 002b:00007ffc7c3fbab8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 17.660630][ T266] RAX: ffffffffffffffda RBX: 00005607c5514810 RCX: 00007f6eacc0737d [ 17.660631][ T266] RDX: 0000000000000000 RSI: 00005607ab931ffd RDI: 0000000000000000 [ 17.660632][ T266] RBP: 00007ffc7c3fbb50 R08: 0000000000000000 R09: 0000000000000000 [ 17.660633][ T266] R10: 0000000000000000 R11: 0000000000000246 R12: 00005607ab931ffd [ 17.660634][ T266] R13: 00005607c5514960 R14: 0000000000040000 R15: 0000000000000000 [ 17.660640][ T266] [ 18.420330][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 18.420444][ C0] [ 18.420446][ C0] ======================================================== [ 18.420447][ C0] WARNING: possible irq lock inversion dependency detected [ 18.420456][ C0] 7.2.0-virtme #1 Tainted: G W [ 18.420458][ C0] -------------------------------------------------------- [ 18.420459][ C0] tc_flower_l2_mi/173 just changed the state of lock: [ 18.420461][ C0] ffffffff96289660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 18.420477][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 18.420479][ C0] (fs_reclaim){+.+.}-{0:0} [ 18.420481][ C0] [ 18.420481][ C0] [ 18.420481][ C0] and interrupts could create inverse lock ordering between them. [ 18.420481][ C0] [ 18.420483][ C0] [ 18.420483][ C0] other info that might help us debug this: [ 18.420484][ C0] Possible interrupt unsafe locking scenario: [ 18.420484][ C0] [ 18.420485][ C0] CPU0 CPU1 [ 18.420485][ C0] ---- ---- [ 18.420486][ C0] lock(fs_reclaim); [ 18.420487][ C0] local_irq_disable(); [ 18.420488][ C0] lock(console_owner); [ 18.420490][ C0] lock(fs_reclaim); [ 18.420491][ C0] [ 18.420492][ C0] lock(console_owner); [ 18.420494][ C0] [ 18.420494][ C0] *** DEADLOCK *** [ 18.420494][ C0] [ 18.420494][ C0] locks held by tc_flower_l2_mi/173: 4, last CPU#0: [ 18.420496][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 18.420504][ C0] #1: ffffffff963ce8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 18.420510][ C0] #2: ffffffff96369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 18.420515][ C0] #3: ffffffff96369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 18.420520][ C0] [ 18.420520][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 18.420527][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 18.420530][ C0] HARDIRQ-ON-W at: [ 18.420532][ C0] __lock_acquire+0x388/0xc20 [ 18.420536][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.420539][ C0] fs_reclaim_acquire+0xd5/0x120 [ 18.420542][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 18.420545][ C0] kthread_create_worker_on_node+0xea/0x210 [ 18.420549][ C0] workqueue_init+0x2a/0x680 [ 18.420553][ C0] kernel_init_freeable+0x2fe/0x630 [ 18.420556][ C0] kernel_init+0x21/0x150 [ 18.420561][ C0] ret_from_fork+0x474/0x6b0 [ 18.420564][ C0] ret_from_fork_asm+0x11/0x20 [ 18.420568][ C0] SOFTIRQ-ON-W at: [ 18.420569][ C0] __lock_acquire+0x388/0xc20 [ 18.420571][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.420574][ C0] fs_reclaim_acquire+0xd5/0x120 [ 18.420575][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 18.420577][ C0] kthread_create_worker_on_node+0xea/0x210 [ 18.420580][ C0] workqueue_init+0x2a/0x680 [ 18.420581][ C0] kernel_init_freeable+0x2fe/0x630 [ 18.420583][ C0] kernel_init+0x21/0x150 [ 18.420585][ C0] ret_from_fork+0x474/0x6b0 [ 18.420587][ C0] ret_from_fork_asm+0x11/0x20 [ 18.420589][ C0] INITIAL USE at: [ 18.420590][ C0] __lock_acquire+0x388/0xc20 [ 18.420592][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.420594][ C0] fs_reclaim_acquire+0xd5/0x120 [ 18.420596][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 18.420598][ C0] kthread_create_worker_on_node+0xea/0x210 [ 18.420600][ C0] workqueue_init+0x2a/0x680 [ 18.420602][ C0] kernel_init_freeable+0x2fe/0x630 [ 18.420604][ C0] kernel_init+0x21/0x150 [ 18.420606][ C0] ret_from_fork+0x474/0x6b0 [ 18.420608][ C0] ret_from_fork_asm+0x11/0x20 [ 18.420610][ C0] } [ 18.420610][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 18.420615][ C0] ... acquired at: [ 18.420616][ C0] __lock_acquire+0x518/0xc20 [ 18.420619][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.420621][ C0] fs_reclaim_acquire+0xd5/0x120 [ 18.420622][ C0] __kmalloc_noprof+0xd3/0x760 [ 18.420624][ C0] alloc_buf.isra.0+0x4b/0x260 [ 18.420628][ C0] put_chars+0x1e1/0x2f0 [ 18.420630][ C0] hvc_console_print+0x292/0x780 [ 18.420633][ C0] console_emit_next_record+0x22f/0x4c0 [ 18.420635][ C0] console_flush_one_record+0x46f/0x710 [ 18.420637][ C0] console_unlock+0xee/0x1f0 [ 18.420639][ C0] vprintk_emit+0x37c/0x3e0 [ 18.420642][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 18.420646][ C0] dev_printk_emit+0xb9/0xee [ 18.420648][ C0] _dev_info+0xe2/0x116 [ 18.420650][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 18.420654][ C0] cmos_do_probe+0x73b/0x98a [ 18.420656][ C0] platform_probe+0xfe/0x1f0 [ 18.420660][ C0] call_driver_probe+0x61/0x1c0 [ 18.420662][ C0] really_probe+0x199/0x760 [ 18.420664][ C0] __driver_probe_device+0x24f/0x440 [ 18.420666][ C0] driver_probe_device+0x4a/0xf0 [ 18.420668][ C0] __driver_attach+0x1b8/0x540 [ 18.420670][ C0] bus_for_each_dev+0x130/0x1e0 [ 18.420672][ C0] bus_add_driver+0x2c8/0x530 [ 18.420674][ C0] driver_register+0x1a3/0x390 [ 18.420676][ C0] __platform_driver_probe+0x13f/0x270 [ 18.420679][ C0] cmos_init+0x31/0x40 [ 18.420682][ C0] do_one_initcall+0x124/0x4f0 [ 18.420684][ C0] kernel_init_freeable+0x596/0x630 [ 18.420686][ C0] kernel_init+0x21/0x150 [ 18.420688][ C0] ret_from_fork+0x474/0x6b0 [ 18.420690][ C0] ret_from_fork_asm+0x11/0x20 [ 18.420692][ C0] [ 18.420692][ C0] -> (console_owner){..-.}-{0:0} { [ 18.420695][ C0] IN-SOFTIRQ-W at: [ 18.420696][ C0] __lock_acquire+0x388/0xc20 [ 18.420698][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.420700][ C0] console_lock_spinning_enable+0x5c/0x60 [ 18.420703][ C0] console_emit_next_record+0x1d1/0x4c0 [ 18.420705][ C0] console_flush_one_record+0x46f/0x710 [ 18.420707][ C0] console_unlock+0xee/0x1f0 [ 18.420709][ C0] vprintk_emit+0x37c/0x3e0 [ 18.420712][ C0] _printk+0xc7/0x100 [ 18.420715][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 18.420719][ C0] call_timer_fn+0x160/0x4d0 [ 18.420721][ C0] __run_timers+0x68f/0xaa0 [ 18.420723][ C0] run_timer_softirq+0xf0/0x160 [ 18.420726][ C0] handle_softirqs+0x1d3/0x900 [ 18.420729][ C0] __irq_exit_rcu+0x145/0x1c0 [ 18.420731][ C0] irq_exit_rcu+0xe/0x30 [ 18.420733][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 18.420736][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.420738][ C0] lock_release+0xdc/0x1f0 [ 18.420740][ C0] unwind_next_frame+0x696/0x1ea0 [ 18.420743][ C0] arch_stack_walk+0xd7/0x130 [ 18.420746][ C0] stack_trace_save+0x93/0xc0 [ 18.420748][ C0] kasan_save_stack+0x2f/0x50 [ 18.420752][ C0] kasan_save_track+0x14/0x30 [ 18.420754][ C0] __kasan_slab_alloc+0x60/0x70 [ 18.420756][ C0] kmem_cache_alloc_noprof+0x21f/0x5c0 [ 18.420758][ C0] __alloc_object+0x30/0x260 [ 18.420762][ C0] __create_object+0x30/0x110 [ 18.420764][ C0] kmem_cache_alloc_node_noprof+0x4ce/0x660 [ 18.420766][ C0] alloc_vmap_area+0x429/0xed0 [ 18.420769][ C0] __get_vm_area_node+0x165/0x280 [ 18.420771][ C0] __vmalloc_node_range_noprof+0x21a/0x4e0 [ 18.420773][ C0] __vmalloc_node_noprof+0x87/0x130 [ 18.420775][ C0] alloc_thread_stack_node+0xb7/0x340 [ 18.420778][ C0] dup_task_struct+0x62/0x830 [ 18.420780][ C0] copy_process+0x3f4/0x6240 [ 18.420782][ C0] kernel_clone+0x1a4/0x6c0 [ 18.420784][ C0] __do_sys_clone+0xe3/0x130 [ 18.420786][ C0] do_syscall_64+0xff/0x530 [ 18.420788][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.420790][ C0] INITIAL USE at: [ 18.420791][ C0] } [ 18.420792][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 18.420796][ C0] ... acquired at: [ 18.420796][ C0] mark_lock+0x1d7/0xa00 [ 18.420798][ C0] mark_usage+0x42/0x170 [ 18.420800][ C0] __lock_acquire+0x388/0xc20 [ 18.420802][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.420804][ C0] console_lock_spinning_enable+0x5c/0x60 [ 18.420806][ C0] console_emit_next_record+0x1d1/0x4c0 [ 18.420809][ C0] console_flush_one_record+0x46f/0x710 [ 18.420811][ C0] console_unlock+0xee/0x1f0 [ 18.420813][ C0] vprintk_emit+0x37c/0x3e0 [ 18.420815][ C0] _printk+0xc7/0x100 [ 18.420817][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 18.420819][ C0] call_timer_fn+0x160/0x4d0 [ 18.420821][ C0] __run_timers+0x68f/0xaa0 [ 18.420824][ C0] run_timer_softirq+0xf0/0x160 [ 18.420826][ C0] handle_softirqs+0x1d3/0x900 [ 18.420828][ C0] __irq_exit_rcu+0x145/0x1c0 [ 18.420829][ C0] irq_exit_rcu+0xe/0x30 [ 18.420831][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 18.420833][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.420835][ C0] lock_release+0xdc/0x1f0 [ 18.420837][ C0] unwind_next_frame+0x696/0x1ea0 [ 18.420839][ C0] arch_stack_walk+0xd7/0x130 [ 18.420841][ C0] stack_trace_save+0x93/0xc0 [ 18.420843][ C0] kasan_save_stack+0x2f/0x50 [ 18.420845][ C0] kasan_save_track+0x14/0x30 [ 18.420847][ C0] __kasan_slab_alloc+0x60/0x70 [ 18.420849][ C0] kmem_cache_alloc_noprof+0x21f/0x5c0 [ 18.420851][ C0] __alloc_object+0x30/0x260 [ 18.420853][ C0] __create_object+0x30/0x110 [ 18.420855][ C0] kmem_cache_alloc_node_noprof+0x4ce/0x660 [ 18.420857][ C0] alloc_vmap_area+0x429/0xed0 [ 18.420859][ C0] __get_vm_area_node+0x165/0x280 [ 18.420861][ C0] __vmalloc_node_range_noprof+0x21a/0x4e0 [ 18.420863][ C0] __vmalloc_node_noprof+0x87/0x130 [ 18.420866][ C0] alloc_thread_stack_node+0xb7/0x340 [ 18.420868][ C0] dup_task_struct+0x62/0x830 [ 18.420869][ C0] copy_process+0x3f4/0x6240 [ 18.420871][ C0] kernel_clone+0x1a4/0x6c0 [ 18.420873][ C0] __do_sys_clone+0xe3/0x130 [ 18.420875][ C0] do_syscall_64+0xff/0x530 [ 18.420876][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.420878][ C0] [ 18.420879][ C0] [ 18.420879][ C0] stack backtrace: [ 18.420883][ C0] CPU: 0 UID: 0 PID: 173 Comm: tc_flower_l2_mi Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 18.420887][ C0] Tainted: [W]=WARN [ 18.420888][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.420890][ C0] Call Trace: [ 18.420892][ C0] [ 18.420894][ C0] dump_stack_lvl+0x6f/0xa0 [ 18.420899][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 18.420903][ C0] mark_lock_irq+0x989/0x9c0 [ 18.420905][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 18.420910][ C0] mark_lock+0x1d7/0xa00 [ 18.420912][ C0] mark_usage+0x42/0x170 [ 18.420915][ C0] __lock_acquire+0x388/0xc20 [ 18.420918][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.420921][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 18.420924][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.420928][ C0] ? lock_acquire+0x13c/0x160 [ 18.420931][ C0] console_lock_spinning_enable+0x5c/0x60 [ 18.420933][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 18.420936][ C0] console_emit_next_record+0x1d1/0x4c0 [ 18.420939][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 18.420941][ C0] ? console_flush_one_record+0x106/0x710 [ 18.420944][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.420947][ C0] ? lock_acquire+0x13c/0x160 [ 18.420950][ C0] console_flush_one_record+0x46f/0x710 [ 18.420954][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 18.420956][ C0] ? __lock_acquire+0x518/0xc20 [ 18.420960][ C0] console_unlock+0xee/0x1f0 [ 18.420963][ C0] ? console_flush_one_record+0x710/0x710 [ 18.420965][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.420968][ C0] ? lock_acquire+0x60/0x160 [ 18.420970][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 18.420973][ C0] ? vprintk_emit+0x320/0x3e0 [ 18.420976][ C0] vprintk_emit+0x37c/0x3e0 [ 18.420979][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 18.420983][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 18.420986][ C0] _printk+0xc7/0x100 [ 18.420988][ C0] ? snapshot_read.cold+0x21/0x21 [ 18.420991][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 18.420994][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.420997][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 18.421000][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 18.421002][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 18.421005][ C0] call_timer_fn+0x160/0x4d0 [ 18.421008][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 18.421010][ C0] ? debug_object_active_state+0x430/0x430 [ 18.421015][ C0] ? find_held_lock+0x2b/0x80 [ 18.421017][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.421020][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.421023][ C0] __run_timers+0x68f/0xaa0 [ 18.421026][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 18.421029][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 18.421032][ C0] ? __lock_acquire+0x518/0xc20 [ 18.421036][ C0] ? __rwlock_init+0x150/0x150 [ 18.421040][ C0] run_timer_softirq+0xf0/0x160 [ 18.421043][ C0] ? __run_timers+0xaa0/0xaa0 [ 18.421045][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.421049][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.421051][ C0] handle_softirqs+0x1d3/0x900 [ 18.421054][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.421057][ C0] ? _local_bh_enable+0xc0/0xc0 [ 18.421060][ C0] __irq_exit_rcu+0x145/0x1c0 [ 18.421062][ C0] irq_exit_rcu+0xe/0x30 [ 18.421064][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 18.421067][ C0] [ 18.421068][ C0] [ 18.421070][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.421072][ C0] RIP: 0010:lock_release+0xdc/0x1f0 [ 18.421076][ C0] Code: a0 38 04 83 f8 01 0f 85 fc 00 00 00 9c 58 f6 c4 02 0f 85 11 01 00 00 41 f7 c7 00 02 00 00 0f 84 c7 00 00 00 fb 4c 8b 7c 24 18 <48> 8b 5c 24 08 4c 8b 74 24 10 48 83 c4 20 c3 65 8b 05 36 5b 38 04 [ 18.421078][ C0] RSP: 0018:ffa0000000667218 EFLAGS: 00000206 [ 18.421081][ C0] RAX: 0000000000000046 RBX: ffffffff96380b00 RCX: 0000000000000001 [ 18.421083][ C0] RDX: 0000000000000000 RSI: ffffffff95e21b15 RDI: ffffffff9588d8e0 [ 18.421085][ C0] RBP: ffa0000000667690 R08: ffffffff96fe575c R09: ffffffff96d9586c [ 18.421086][ C0] R10: 0000000000000000 R11: 0000000000000008 R12: ffa0000000667601 [ 18.421087][ C0] R13: ffa0000000667340 R14: ffffffff92f56bb1 R15: ff1100000b065688 [ 18.421089][ C0] ? unwind_next_frame+0x691/0x1ea0 [ 18.421094][ C0] unwind_next_frame+0x696/0x1ea0 [ 18.421096][ C0] ? kasan_save_stack+0x2f/0x50 [ 18.421100][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 18.421102][ C0] ? __lock_acquire+0x518/0xc20 [ 18.421105][ C0] ? __unwind_start+0x42e/0x900 [ 18.421107][ C0] ? write_profile+0xf0/0xf0 [ 18.421110][ C0] arch_stack_walk+0xd7/0x130 [ 18.421114][ C0] ? kasan_save_track+0x14/0x30 [ 18.421117][ C0] stack_trace_save+0x93/0xc0 [ 18.421119][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 18.421121][ C0] ? arch_stack_walk+0xed/0x130 [ 18.421125][ C0] kasan_save_stack+0x2f/0x50 [ 18.421128][ C0] ? kasan_save_stack+0x2f/0x50 [ 18.421137][ C0] kasan_save_track+0x14/0x30 [ 18.421139][ C0] __kasan_slab_alloc+0x60/0x70 [ 18.421142][ C0] kmem_cache_alloc_noprof+0x21f/0x5c0 [ 18.421145][ C0] ? __alloc_object+0x30/0x260 [ 18.421149][ C0] __alloc_object+0x30/0x260 [ 18.421151][ C0] __create_object+0x30/0x110 [ 18.421154][ C0] ? kasan_save_track+0x14/0x30 [ 18.421157][ C0] kmem_cache_alloc_node_noprof+0x4ce/0x660 [ 18.421160][ C0] ? alloc_vmap_area+0x429/0xed0 [ 18.421167][ C0] alloc_vmap_area+0x429/0xed0 [ 18.421171][ C0] ? free_vmap_area+0x1f20/0x1f20 [ 18.421174][ C0] ? kasan_save_track+0x14/0x30 [ 18.421176][ C0] ? __kasan_kmalloc+0x7b/0x90 [ 18.421179][ C0] ? __kmalloc_cache_node_noprof+0x2b3/0x660 [ 18.421182][ C0] ? __get_vm_area_node+0xbc/0x280 [ 18.421185][ C0] __get_vm_area_node+0x165/0x280 [ 18.421188][ C0] __vmalloc_node_range_noprof+0x21a/0x4e0 [ 18.421191][ C0] ? dup_task_struct+0x62/0x830 [ 18.421193][ C0] ? dup_task_struct+0x62/0x830 [ 18.421196][ C0] ? __vmalloc_area_node+0x620/0x620 [ 18.421198][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.421200][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 18.421203][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 18.421205][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 18.421208][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 18.421211][ C0] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 18.421214][ C0] __vmalloc_node_noprof+0x87/0x130 [ 18.421217][ C0] ? dup_task_struct+0x62/0x830 [ 18.421219][ C0] ? __asan_memcpy+0x3c/0x60 [ 18.421222][ C0] ? preempt_count_add+0x84/0x180 [ 18.421225][ C0] ? alloc_thread_stack_node+0x28/0x340 [ 18.421227][ C0] alloc_thread_stack_node+0xb7/0x340 [ 18.421230][ C0] dup_task_struct+0x62/0x830 [ 18.421232][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 18.421235][ C0] copy_process+0x3f4/0x6240 [ 18.421237][ C0] ? find_held_lock+0x2b/0x80 [ 18.421239][ C0] ? find_held_lock+0x2b/0x80 [ 18.421242][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.421245][ C0] ? pidfd_prepare+0x150/0x150 [ 18.421248][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 18.421251][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 18.421254][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 18.421257][ C0] ? validate_chain+0x38b/0xc20 [ 18.421260][ C0] kernel_clone+0x1a4/0x6c0 [ 18.421262][ C0] ? create_io_thread+0x130/0x130 [ 18.421267][ C0] ? find_held_lock+0x2b/0x80 [ 18.421270][ C0] __do_sys_clone+0xe3/0x130 [ 18.421272][ C0] ? kernel_clone+0x6c0/0x6c0 [ 18.421277][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.421280][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.421282][ C0] do_syscall_64+0xff/0x530 [ 18.421284][ C0] ? exc_page_fault+0xee/0x100 [ 18.421287][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.421289][ C0] RIP: 0033:0x7eff6346c126 [ 18.421293][ C0] Code: 7d e0 e8 7d af f5 ff 45 31 c0 31 d2 31 f6 64 48 8b 04 25 10 00 00 00 bf 11 00 20 01 4c 8d 90 d0 02 00 00 b8 38 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 5a 89 c3 85 c0 75 2f 64 48 8b 04 25 10 00 00 [ 18.421294][ C0] RSP: 002b:00007ffe7d9e2310 EFLAGS: 00000246 ORIG_RAX: 0000000000000038 [ 18.421297][ C0] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007eff6346c126 [ 18.421298][ C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011 [ 18.421300][ C0] RBP: 00007ffe7d9e2330 R08: 0000000000000000 R09: 0000000000000200 [ 18.421301][ C0] R10: 00007eff633aae50 R11: 0000000000000246 R12: 00007ffe7d9e2ab0 [ 18.421302][ C0] R13: 00000000ffffffff R14: 000000000000000c R15: 0000000000000000 [ 18.421306][ C0] [ 30.116104][ T484] br1: port 2(veth2) entered disabled state [ 30.116121][ T484] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 30.116123][ T484] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 484, name: ip [ 30.116125][ T484] preempt_count: 202, expected: 0 [ 30.116126][ T484] RCU nest depth: 0, expected: 0 [ 30.116126][ T484] INFO: lockdep is turned off. [ 30.116128][ T484] irq event stamp: 0 [ 30.116128][ T484] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 30.116134][ T484] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 30.116140][ T484] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 30.116142][ T484] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 30.116144][ T484] Preemption disabled at: [ 30.116145][ T484] [<0000000000000000>] 0x0 [ 30.116149][ T484] CPU: 0 UID: 0 PID: 484 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 30.116152][ T484] Tainted: [W]=WARN [ 30.116153][ T484] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 30.116155][ T484] Call Trace: [ 30.116157][ T484] [ 30.116158][ T484] dump_stack_lvl+0x6f/0xa0 [ 30.116166][ T484] __might_resched.cold+0x1fe/0x2c1 [ 30.116170][ T484] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 30.116174][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116178][ T484] __kmalloc_noprof+0x443/0x760 [ 30.116182][ T484] ? __rwlock_init+0x150/0x150 [ 30.116185][ T484] ? alloc_buf.isra.0+0x4b/0x260 [ 30.116189][ T484] ? do_raw_spin_unlock+0x59/0x250 [ 30.116191][ T484] alloc_buf.isra.0+0x4b/0x260 [ 30.116193][ T484] put_chars+0x1e1/0x2f0 [ 30.116195][ T484] ? __send_to_port+0x420/0x420 [ 30.116196][ T484] ? printk_get_next_message+0x240/0x650 [ 30.116200][ T484] hvc_console_print+0x292/0x780 [ 30.116203][ T484] ? hvc_write+0x3a0/0x3a0 [ 30.116205][ T484] ? __rwlock_init+0x150/0x150 [ 30.116207][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116208][ T484] ? lock_acquire+0x13c/0x160 [ 30.116210][ T484] console_emit_next_record+0x22f/0x4c0 [ 30.116213][ T484] ? devkmsg_read+0x4b0/0x4b0 [ 30.116215][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116216][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116218][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116220][ T484] ? lock_acquire+0x13c/0x160 [ 30.116221][ T484] ? console_flush_one_record+0x111/0x710 [ 30.116223][ T484] console_flush_one_record+0x46f/0x710 [ 30.116225][ T484] ? console_emit_next_record+0x4c0/0x4c0 [ 30.116228][ T484] console_unlock+0xee/0x1f0 [ 30.116229][ T484] ? lock_acquire+0x13c/0x160 [ 30.116231][ T484] ? console_flush_one_record+0x710/0x710 [ 30.116233][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116234][ T484] ? lock_release+0x184/0x1f0 [ 30.116235][ T484] ? lock_acquire+0x60/0x160 [ 30.116237][ T484] ? __rcu_read_lock+0x30/0x70 [ 30.116239][ T484] ? __down_trylock_console_sem+0x5e/0xa0 [ 30.116241][ T484] ? vprintk_emit+0x320/0x3e0 [ 30.116243][ T484] vprintk_emit+0x37c/0x3e0 [ 30.116245][ T484] ? wake_up_klogd_work_func+0x90/0x90 [ 30.116247][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116248][ T484] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 30.116251][ T484] ? trace_hardirqs_on+0x36/0x40 [ 30.116253][ T484] _printk+0xc7/0x100 [ 30.116256][ T484] ? snapshot_read.cold+0x21/0x21 [ 30.116258][ T484] ? ipv6_mc_down+0x17e/0x2d0 [ 30.116263][ T484] br_set_state+0x22f/0x430 [bridge] [ 30.116276][ T484] br_stp_disable_port+0x11f/0x390 [bridge] [ 30.116284][ T484] br_device_event+0x7b5/0x8f0 [bridge] [ 30.116292][ T484] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 30.116295][ T484] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 30.116298][ T484] ? addrconf_notify+0x2c8/0xf30 [ 30.116301][ T484] notifier_call_chain+0xae/0x300 [ 30.116304][ T484] __dev_notify_flags+0x23c/0x3c0 [ 30.116308][ T484] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 30.116309][ T484] ? netif_change_name+0x8c0/0x8c0 [ 30.116311][ T484] ? netif_set_allmulti+0x250/0x250 [ 30.116313][ T484] ? __kernel_text_address+0x12/0x30 [ 30.116315][ T484] ? write_profile+0xf0/0xf0 [ 30.116318][ T484] ? unwind_get_return_address+0x67/0xd0 [ 30.116321][ T484] netif_change_flags+0xfe/0x190 [ 30.116323][ T484] do_setlink.isra.0+0x1cc3/0x2750 [ 30.116326][ T484] ? stack_trace_save+0x93/0xc0 [ 30.116327][ T484] ? rtnl_link_get_size+0x350/0x350 [ 30.116328][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116330][ T484] ? stack_depot_save_flags+0x1bc/0x790 [ 30.116333][ T484] ? kasan_save_stack+0x3d/0x50 [ 30.116337][ T484] ? kasan_save_stack+0x2f/0x50 [ 30.116338][ T484] ? kasan_save_track+0x14/0x30 [ 30.116339][ T484] ? __kasan_kmalloc+0x7b/0x90 [ 30.116341][ T484] ? __kmalloc_cache_noprof+0x29a/0x620 [ 30.116342][ T484] ? rtnl_newlink+0x124/0x11c0 [ 30.116343][ T484] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 30.116345][ T484] ? netlink_rcv_skb+0x14e/0x3a0 [ 30.116347][ T484] ? netlink_unicast+0x486/0x750 [ 30.116349][ T484] ? netlink_sendmsg+0x735/0xc60 [ 30.116350][ T484] ? ____sys_sendmsg+0x415/0x880 [ 30.116352][ T484] ? ___sys_sendmsg+0x14e/0x1d0 [ 30.116354][ T484] ? __sys_sendmsg+0x12c/0x1d0 [ 30.116356][ T484] ? do_syscall_64+0xff/0x530 [ 30.116358][ T484] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 30.116360][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116362][ T484] ? lock_acquire+0x13c/0x160 [ 30.116363][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116365][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116366][ T484] ? trace_contention_end+0xb3/0x180 [ 30.116369][ T484] ? __mutex_lock+0x1db/0x1ea0 [ 30.116371][ T484] ? __mutex_lock+0x9a3/0x1ea0 [ 30.116372][ T484] ? rtnl_newlink+0x9a8/0x11c0 [ 30.116374][ T484] ? ww_mutex_lock+0x160/0x160 [ 30.116376][ T484] ? __rtnl_newlink+0x3fa/0xa50 [ 30.116378][ T484] rtnl_newlink+0x9f1/0x11c0 [ 30.116379][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116381][ T484] ? rtnl_bridge_getlink+0x850/0x850 [ 30.116382][ T484] ? lock_acquire+0x13c/0x160 [ 30.116384][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116386][ T484] ? is_bpf_text_address+0x3e/0x110 [ 30.116389][ T484] ? is_bpf_text_address+0x72/0x110 [ 30.116391][ T484] ? kernel_text_address+0x149/0x170 [ 30.116392][ T484] ? __kernel_text_address+0x12/0x30 [ 30.116393][ T484] ? write_profile+0xf0/0xf0 [ 30.116394][ T484] ? unwind_get_return_address+0x67/0xd0 [ 30.116395][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116397][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116399][ T484] ? cap_capable+0x1d7/0x3d0 [ 30.116402][ T484] ? lock_acquire+0x13c/0x160 [ 30.116404][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116405][ T484] ? rtnl_bridge_getlink+0x850/0x850 [ 30.116407][ T484] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 30.116408][ T484] ? rtnl_link_fill+0x920/0x920 [ 30.116409][ T484] ? filter_irq_stacks+0xd0/0xd0 [ 30.116411][ T484] ? stack_depot_save_flags+0x1bc/0x790 [ 30.116413][ T484] netlink_rcv_skb+0x14e/0x3a0 [ 30.116414][ T484] ? rtnl_link_fill+0x920/0x920 [ 30.116416][ T484] ? lock_release+0x184/0x1f0 [ 30.116417][ T484] ? netlink_ack+0xcf0/0xcf0 [ 30.116419][ T484] ? __netlink_lookup+0xb9/0x6b0 [ 30.116421][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116423][ T484] ? lock_release+0x184/0x1f0 [ 30.116425][ T484] netlink_unicast+0x486/0x750 [ 30.116426][ T484] ? netlink_attachskb+0x810/0x810 [ 30.116428][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116430][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116431][ T484] ? lock_acquire+0x13c/0x160 [ 30.116433][ T484] netlink_sendmsg+0x735/0xc60 [ 30.116435][ T484] ? netlink_unicast+0x750/0x750 [ 30.116437][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116438][ T484] ? lock_release+0x184/0x1f0 [ 30.116440][ T484] ____sys_sendmsg+0x415/0x880 [ 30.116442][ T484] ? copy_msghdr_from_user+0x279/0x420 [ 30.116443][ T484] ? get_timestamp.constprop.0+0x390/0x390 [ 30.116445][ T484] ? move_addr_to_kernel+0x40/0x40 [ 30.116446][ T484] ? filter_irq_stacks+0xd0/0xd0 [ 30.116449][ T484] ___sys_sendmsg+0x14e/0x1d0 [ 30.116450][ T484] ? copy_msghdr_from_user+0x420/0x420 [ 30.116455][ T484] __sys_sendmsg+0x12c/0x1d0 [ 30.116457][ T484] ? __sys_sendmsg_sock+0x20/0x20 [ 30.116460][ T484] ? rcu_is_watching+0x16/0xd0 [ 30.116462][ T484] do_syscall_64+0xff/0x530 [ 30.116463][ T484] ? exc_page_fault+0xee/0x100 [ 30.116465][ T484] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 30.116467][ T484] RIP: 0033:0x7f04779cc54e [ 30.116469][ T484] 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 [ 30.116471][ T484] RSP: 002b:00007ffd7455fb80 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 30.116475][ T484] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f04779cc54e [ 30.116476][ T484] RDX: 0000000000000000 RSI: 00007ffd7455fc30 RDI: 0000000000000005 [ 30.116477][ T484] RBP: 00007ffd7455fb90 R08: 0000000000000000 R09: 0000000000000000 [ 30.116478][ T484] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a918315 [ 30.116478][ T484] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd745602f0 [ 30.116481][ T484] [ 30.148333][ T484] ip (484) used greatest stack depth: 23016 bytes left [ 30.158915][ T485] veth2: left allmulticast mode [ 30.159108][ T485] veth2: left promiscuous mode [ 30.159365][ T485] br1: port 2(veth2) entered disabled state [ 30.179690][ T486] br1: port 1(veth1) entered disabled state [ 30.201686][ T487] veth1: left allmulticast mode [ 30.201886][ T487] veth1: left promiscuous mode [ 30.202140][ T487] br1: port 1(veth1) entered disabled state