[ 10.015894][ T188] ip (188) used greatest stack depth: 23728 bytes left [ 10.015913][ T188] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.015915][ T188] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 188, name: ip [ 10.015917][ T188] preempt_count: 2, expected: 0 [ 10.015919][ T188] RCU nest depth: 0, expected: 0 [ 10.015920][ T188] locks held by ip/188: 5, last CPU#2: [ 10.015923][ T188] #0: ffffffffa86027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.015939][ T188] #1: ffffffffa8769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.015946][ T188] #2: ffffffffa8769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.015952][ T188] #3: ffffffffa8689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.015958][ T188] #4: ffffffffa8689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.015964][ T188] irq event stamp: 36152 [ 10.015965][ T188] hardirqs last enabled at (36151): [] __down_trylock_console_sem+0x86/0xa0 [ 10.015969][ T188] hardirqs last disabled at (36152): [] console_emit_next_record+0x3d4/0x4c0 [ 10.015972][ T188] softirqs last enabled at (35902): [] handle_softirqs+0x67c/0x900 [ 10.015975][ T188] softirqs last disabled at (35897): [] __irq_exit_rcu+0x145/0x1c0 [ 10.015978][ T188] Preemption disabled at: [ 10.015979][ T188] [<0000000000000000>] 0x0 [ 10.015987][ T188] CPU: 2 UID: 0 PID: 188 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.015991][ T188] Tainted: [W]=WARN [ 10.015993][ T188] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.015995][ T188] Call Trace: [ 10.015997][ T188] [ 10.015999][ T188] dump_stack_lvl+0x6f/0xa0 [ 10.016007][ T188] __might_resched.cold+0x1fe/0x2c1 [ 10.016013][ T188] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.016019][ T188] ? __kmalloc_noprof+0xdb/0x760 [ 10.016026][ T188] __kmalloc_noprof+0x443/0x760 [ 10.016029][ T188] ? alloc_buf.isra.0+0x4b/0x260 [ 10.016038][ T188] ? do_raw_spin_unlock+0x59/0x250 [ 10.016042][ T188] alloc_buf.isra.0+0x4b/0x260 [ 10.016046][ T188] put_chars+0x1e1/0x2f0 [ 10.016051][ T188] ? __send_to_port+0x420/0x420 [ 10.016053][ T188] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.016059][ T188] ? validate_chain+0x38b/0xc20 [ 10.016068][ T188] hvc_console_print+0x292/0x780 [ 10.016079][ T188] ? hvc_write+0x3a0/0x3a0 [ 10.016082][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.016086][ T188] ? lock_acquire+0x13c/0x160 [ 10.016092][ T188] console_emit_next_record+0x22f/0x4c0 [ 10.016098][ T188] ? devkmsg_read+0x4b0/0x4b0 [ 10.016101][ T188] ? console_flush_one_record+0x106/0x710 [ 10.016106][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.016109][ T188] ? lock_acquire+0x13c/0x160 [ 10.016115][ T188] console_flush_one_record+0x46f/0x710 [ 10.016122][ T188] ? console_emit_next_record+0x4c0/0x4c0 [ 10.016125][ T188] ? __lock_acquire+0x518/0xc20 [ 10.016134][ T188] console_unlock+0xee/0x1f0 [ 10.016138][ T188] ? console_flush_one_record+0x710/0x710 [ 10.016141][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.016144][ T188] ? lock_acquire+0x60/0x160 [ 10.016151][ T188] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.016153][ T188] ? vprintk_emit+0x320/0x3e0 [ 10.016159][ T188] vprintk_emit+0x37c/0x3e0 [ 10.016164][ T188] ? wake_up_klogd_work_func+0x90/0x90 [ 10.016170][ T188] ? __lock_acquire+0x518/0xc20 [ 10.016176][ T188] _printk+0xc7/0x100 [ 10.016181][ T188] ? snapshot_read.cold+0x21/0x21 [ 10.016186][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.016190][ T188] ? __rwlock_init+0x150/0x150 [ 10.016196][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.016200][ T188] do_exit.cold+0x82/0x9c [ 10.016206][ T188] ? exit_notify+0x890/0x890 [ 10.016209][ T188] ? __lock_release.isra.0+0x69/0x1a0 [ 10.016213][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.016220][ T188] do_group_exit+0xb8/0x370 [ 10.016225][ T188] __x64_sys_exit_group+0x3c/0x50 [ 10.016228][ T188] x64_sys_call+0x1567/0x1570 [ 10.016231][ T188] do_syscall_64+0xff/0x530 [ 10.016235][ T188] ? exc_page_fault+0xee/0x100 [ 10.016240][ T188] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.016243][ T188] RIP: 0033:0x7ff421bd71b8 [ 10.016247][ T188] Code: Unable to access opcode bytes at 0x7ff421bd718e. [ 10.016248][ T188] RSP: 002b:00007ffdf9ee4388 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.016252][ T188] RAX: ffffffffffffffda RBX: 00007ff421d07f88 RCX: 00007ff421bd71b8 [ 10.016254][ T188] RDX: 00007ff421921fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.016256][ T188] RBP: 00007ffdf9ee43e0 R08: 0000000000000000 R09: 0000000000008010 [ 10.016257][ T188] R10: 00007ffdf9ee41a0 R11: 0000000000000246 R12: 0000000000000001 [ 10.016258][ T188] R13: 0000000000000000 R14: 00007ff421d06680 R15: 00007ff421d07fa0 [ 10.016271][ T188] [ 11.010546][ T240] 8021q: 802.1Q VLAN Support v1.8 [ 13.063097][ T372] br10: port 1(veth1.10) entered blocking state [ 13.063260][ T372] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.063262][ T372] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 372, name: ip [ 13.063264][ T372] preempt_count: 1, expected: 0 [ 13.063264][ T372] RCU nest depth: 0, expected: 0 [ 13.063265][ T372] locks held by ip/372: 5, last CPU#2: [ 13.063268][ T372] #0: ffffffffa8ed2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 13.063280][ T372] #1: ffffffffa8769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.063286][ T372] #2: ffffffffa8769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.063289][ T372] #3: ffffffffa8689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.063293][ T372] #4: ffffffffa8689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.063297][ T372] irq event stamp: 19390 [ 13.063298][ T372] hardirqs last enabled at (19389): [] __down_trylock_console_sem+0x86/0xa0 [ 13.063301][ T372] hardirqs last disabled at (19390): [] console_emit_next_record+0x3d4/0x4c0 [ 13.063303][ T372] softirqs last enabled at (19302): [] __alloc_skb+0x4c2/0x5f0 [ 13.063306][ T372] softirqs last disabled at (19300): [] __alloc_skb+0x4c2/0x5f0 [ 13.063308][ T372] Preemption disabled at: [ 13.063309][ T372] [] vprintk_emit+0x31b/0x3e0 [ 13.063315][ T372] CPU: 2 UID: 0 PID: 372 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.063318][ T372] Tainted: [W]=WARN [ 13.063319][ T372] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.063321][ T372] Call Trace: [ 13.063323][ T372] [ 13.063324][ T372] dump_stack_lvl+0x6f/0xa0 [ 13.063330][ T372] ? vprintk_emit+0x31b/0x3e0 [ 13.063332][ T372] __might_resched.cold+0x1fe/0x2c1 [ 13.063337][ T372] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.063341][ T372] ? __kmalloc_noprof+0xdb/0x760 [ 13.063346][ T372] __kmalloc_noprof+0x443/0x760 [ 13.063348][ T372] ? alloc_buf.isra.0+0x4b/0x260 [ 13.063354][ T372] ? do_raw_spin_unlock+0x59/0x250 [ 13.063356][ T372] alloc_buf.isra.0+0x4b/0x260 [ 13.063360][ T372] put_chars+0x1e1/0x2f0 [ 13.063362][ T372] ? __send_to_port+0x420/0x420 [ 13.063367][ T372] ? validate_chain+0x34a/0xc20 [ 13.063370][ T372] hvc_console_print+0x292/0x780 [ 13.063373][ T372] ? mark_usage+0x61/0x170 [ 13.063375][ T372] ? __lock_acquire+0x518/0xc20 [ 13.063376][ T372] ? __lock_acquire+0x518/0xc20 [ 13.063380][ T372] ? hvc_write+0x3a0/0x3a0 [ 13.063382][ T372] ? console_emit_next_record+0x1df/0x4c0 [ 13.063386][ T372] ? rcu_is_watching+0x16/0xd0 [ 13.063389][ T372] ? lock_acquire+0x13c/0x160 [ 13.063393][ T372] console_emit_next_record+0x22f/0x4c0 [ 13.063399][ T372] ? devkmsg_read+0x4b0/0x4b0 [ 13.063401][ T372] ? console_flush_one_record+0x106/0x710 [ 13.063405][ T372] ? rcu_is_watching+0x16/0xd0 [ 13.063408][ T372] ? lock_acquire+0x13c/0x160 [ 13.063412][ T372] console_flush_one_record+0x46f/0x710 [ 13.063417][ T372] ? console_emit_next_record+0x4c0/0x4c0 [ 13.063419][ T372] ? __lock_acquire+0x518/0xc20 [ 13.063424][ T372] console_unlock+0xee/0x1f0 [ 13.063427][ T372] ? console_flush_one_record+0x710/0x710 [ 13.063429][ T372] ? rcu_is_watching+0x16/0xd0 [ 13.063431][ T372] ? lock_acquire+0x60/0x160 [ 13.063434][ T372] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.063436][ T372] ? vprintk_emit+0x320/0x3e0 [ 13.063439][ T372] vprintk_emit+0x37c/0x3e0 [ 13.063443][ T372] ? wake_up_klogd_work_func+0x90/0x90 [ 13.063446][ T372] ? __lock_release.isra.0+0x69/0x1a0 [ 13.063447][ T372] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 13.063454][ T372] _printk+0xc7/0x100 [ 13.063457][ T372] ? snapshot_read.cold+0x21/0x21 [ 13.063461][ T372] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 13.063474][ T372] ? do_setlink.isra.0+0xa31/0x2750 [ 13.063475][ T372] ? rtnl_newlink+0x9f1/0x11c0 [ 13.063476][ T372] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.063481][ T372] br_set_state+0x22f/0x430 [bridge] [ 13.063491][ T372] br_init_port+0xc4/0x200 [bridge] [ 13.063499][ T372] new_nbp+0x39c/0x580 [bridge] [ 13.063509][ T372] br_add_if+0x212/0x1320 [bridge] [ 13.063516][ T372] ? is_bpf_text_address+0x72/0x110 [ 13.063520][ T372] ? kernel_text_address+0x149/0x170 [ 13.063523][ T372] ? __kernel_text_address+0x12/0x30 [ 13.063527][ T372] do_set_master+0x357/0x580 [ 13.063531][ T372] do_setlink.isra.0+0xa31/0x2750 [ 13.063534][ T372] ? stack_trace_save+0x93/0xc0 [ 13.063538][ T372] ? rtnl_link_get_size+0x350/0x350 [ 13.063539][ T372] ? rcu_read_lock_any_held+0x66/0x90 [ 13.063541][ T372] ? stack_depot_save_flags+0x38e/0x790 [ 13.063545][ T372] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.063548][ T372] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.063549][ T372] ? validate_chain+0x38b/0xc20 [ 13.063552][ T372] ? kasan_save_stack+0x3d/0x50 [ 13.063555][ T372] ? kasan_save_stack+0x2f/0x50 [ 13.063556][ T372] ? kasan_save_track+0x14/0x30 [ 13.063559][ T372] ? __lock_acquire+0x518/0xc20 [ 13.063561][ T372] ? netlink_seq_next+0x4e/0x60 [ 13.063564][ T372] ? ___sys_sendmsg+0xb0/0x1d0 [ 13.063568][ T372] ? lock_acquire.part.0+0xd4/0x280 [ 13.063570][ T372] ? rtnl_newlink+0x9a8/0x11c0 [ 13.063573][ T372] ? rcu_is_watching+0x16/0xd0 [ 13.063575][ T372] ? lock_acquire+0x13c/0x160 [ 13.063577][ T372] ? rcu_is_watching+0x16/0xd0 [ 13.063578][ T372] ? rcu_is_watching+0x16/0xd0 [ 13.063581][ T372] ? trace_contention_end+0xb3/0x180 [ 13.063584][ T372] ? __mutex_lock+0x1db/0x1ea0 [ 13.063586][ T372] ? __mutex_lock+0x9a3/0x1ea0 [ 13.063588][ T372] ? rtnl_newlink+0x9a8/0x11c0 [ 13.063591][ T372] ? ww_mutex_lock+0x160/0x160 [ 13.063593][ T372] ? nla_get_range_signed+0x3d0/0x3d0 [ 13.063598][ T372] ? __rtnl_newlink+0x3fa/0xa50 [ 13.063603][ T372] rtnl_newlink+0x9f1/0x11c0 [ 13.063608][ T372] ? rtnl_bridge_getlink+0x850/0x850 [ 13.063610][ T372] ? __lock_acquire+0x518/0xc20 [ 13.063614][ T372] ? lock_acquire.part.0+0xd4/0x280 [ 13.063616][ T372] ? find_held_lock+0x2b/0x80 [ 13.063618][ T372] ? rtnl_bridge_getlink+0x850/0x850 [ 13.063620][ T372] ? __lock_release.isra.0+0x69/0x1a0 [ 13.063624][ T372] ? rtnl_bridge_getlink+0x850/0x850 [ 13.063626][ T372] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.063628][ T372] ? rtnl_link_fill+0x920/0x920 [ 13.063630][ T372] ? __lock_acquire+0x518/0xc20 [ 13.063634][ T372] ? lock_acquire.part.0+0xd4/0x280 [ 13.063636][ T372] ? find_held_lock+0x2b/0x80 [ 13.063639][ T372] netlink_rcv_skb+0x14e/0x3a0 [ 13.063641][ T372] ? rtnl_link_fill+0x920/0x920 [ 13.063644][ T372] ? netlink_ack+0xcf0/0xcf0 [ 13.063650][ T372] ? netlink_deliver_tap+0xc5/0x330 [ 13.063652][ T372] ? netlink_deliver_tap+0x13c/0x330 [ 13.063656][ T372] netlink_unicast+0x486/0x750 [ 13.063660][ T372] ? netlink_attachskb+0x810/0x810 [ 13.063662][ T372] ? __lock_acquire+0x518/0xc20 [ 13.063666][ T372] netlink_sendmsg+0x735/0xc60 [ 13.063670][ T372] ? netlink_unicast+0x750/0x750 [ 13.063674][ T372] ? __might_fault+0x97/0x140 [ 13.063679][ T372] ____sys_sendmsg+0x415/0x880 [ 13.063681][ T372] ? copy_msghdr_from_user+0x279/0x420 [ 13.063683][ T372] ? get_timestamp.constprop.0+0x390/0x390 [ 13.063685][ T372] ? move_addr_to_kernel+0x40/0x40 [ 13.063692][ T372] ___sys_sendmsg+0x14e/0x1d0 [ 13.063695][ T372] ? copy_msghdr_from_user+0x420/0x420 [ 13.063711][ T372] __sys_sendmsg+0x12c/0x1d0 [ 13.063713][ T372] ? __sys_sendmsg_sock+0x20/0x20 [ 13.063720][ T372] ? rcu_is_watching+0x16/0xd0 [ 13.063723][ T372] do_syscall_64+0xff/0x530 [ 13.063725][ T372] ? exc_page_fault+0xee/0x100 [ 13.063728][ T372] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.063731][ T372] RIP: 0033:0x7fb930d1954e [ 13.063734][ T372] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 13.063736][ T372] RSP: 002b:00007ffc0aa24df0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 13.063739][ T372] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fb930d1954e [ 13.063741][ T372] RDX: 0000000000000000 RSI: 00007ffc0aa24ea0 RDI: 0000000000000005 [ 13.063742][ T372] RBP: 00007ffc0aa24e00 R08: 0000000000000000 R09: 0000000000000000 [ 13.063743][ T372] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9106e7 [ 13.063743][ T372] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc0aa25560 [ 13.063750][ T372] [ 13.111209][ T372] br10: port 1(veth1.10) entered disabled state [ 13.111692][ T372] veth1.10: entered allmulticast mode [ 13.111957][ T372] veth1: entered allmulticast mode [ 13.113868][ T372] veth1.10: entered promiscuous mode [ 13.114160][ T372] veth1: entered promiscuous mode [ 13.139447][ T372] ip (372) used greatest stack depth: 23336 bytes left [ 13.174681][ T377] br10: port 2(veth2.10) entered blocking state [ 13.175034][ T377] br10: port 2(veth2.10) entered disabled state [ 13.175344][ T377] veth2.10: entered allmulticast mode [ 13.175780][ T377] veth2: entered allmulticast mode [ 13.177847][ T377] veth2.10: entered promiscuous mode [ 13.178106][ T377] veth2: entered promiscuous mode [ 13.227998][ T383] br10: port 2(veth2.10) entered blocking state [ 13.228603][ T383] br10: port 2(veth2.10) entered forwarding state [ 13.229326][ T383] br10: port 1(veth1.10) entered blocking state [ 13.229681][ T383] br10: port 1(veth1.10) entered forwarding state [ 13.654858][ T418] br11: port 1(veth1.11) entered blocking state [ 13.655197][ T418] br11: port 1(veth1.11) entered disabled state [ 13.655519][ T418] veth1.11: entered allmulticast mode [ 13.657779][ T418] veth1.11: entered promiscuous mode [ 13.707230][ T425] br11: port 2(veth2.11) entered blocking state [ 13.707557][ T425] br11: port 2(veth2.11) entered disabled state [ 13.707871][ T425] veth2.11: entered allmulticast mode [ 13.709456][ T425] veth2.11: entered promiscuous mode [ 13.755061][ T430] br11: port 2(veth2.11) entered blocking state [ 13.755390][ T430] br11: port 2(veth2.11) entered forwarding state [ 13.755819][ T430] br11: port 1(veth1.11) entered blocking state [ 13.756129][ T430] br11: port 1(veth1.11) entered forwarding state [ 14.182968][ T465] br12: port 1(veth1.12) entered blocking state [ 14.182985][ T465] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.182987][ T465] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 465, name: ip [ 14.182988][ T465] preempt_count: 1, expected: 0 [ 14.182989][ T465] RCU nest depth: 0, expected: 0 [ 14.182990][ T465] locks held by ip/465: 5, last CPU#3: [ 14.182992][ T465] #0: ffffffffa8ed2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 14.183004][ T465] #1: ffffffffa8769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.183010][ T465] #2: ffffffffa8769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.183014][ T465] #3: ffffffffa8689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.183018][ T465] #4: ffffffffa8689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.183022][ T465] irq event stamp: 15966 [ 14.183023][ T465] hardirqs last enabled at (15965): [] __down_trylock_console_sem+0x86/0xa0 [ 14.183025][ T465] hardirqs last disabled at (15966): [] console_emit_next_record+0x3d4/0x4c0 [ 14.183027][ T465] softirqs last enabled at (15908): [] __alloc_skb+0x4c2/0x5f0 [ 14.183030][ T465] softirqs last disabled at (15906): [] __alloc_skb+0x4c2/0x5f0 [ 14.183033][ T465] Preemption disabled at: [ 14.183033][ T465] [] vprintk_emit+0x31b/0x3e0 [ 14.183039][ T465] CPU: 3 UID: 0 PID: 465 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.183042][ T465] Tainted: [W]=WARN [ 14.183043][ T465] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.183045][ T465] Call Trace: [ 14.183046][ T465] [ 14.183048][ T465] dump_stack_lvl+0x6f/0xa0 [ 14.183054][ T465] ? vprintk_emit+0x31b/0x3e0 [ 14.183056][ T465] __might_resched.cold+0x1fe/0x2c1 [ 14.183060][ T465] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.183065][ T465] ? __kmalloc_noprof+0xdb/0x760 [ 14.183070][ T465] __kmalloc_noprof+0x443/0x760 [ 14.183072][ T465] ? alloc_buf.isra.0+0x4b/0x260 [ 14.183078][ T465] ? do_raw_spin_unlock+0x59/0x250 [ 14.183081][ T465] alloc_buf.isra.0+0x4b/0x260 [ 14.183084][ T465] put_chars+0x1e1/0x2f0 [ 14.183086][ T465] ? prb_final_commit+0x50/0x50 [ 14.183088][ T465] ? __send_to_port+0x420/0x420 [ 14.183091][ T465] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.183095][ T465] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.183097][ T465] ? validate_chain+0x38b/0xc20 [ 14.183101][ T465] hvc_console_print+0x292/0x780 [ 14.183104][ T465] ? mark_usage+0x61/0x170 [ 14.183106][ T465] ? __lock_acquire+0x518/0xc20 [ 14.183107][ T465] ? __lock_acquire+0x518/0xc20 [ 14.183111][ T465] ? hvc_write+0x3a0/0x3a0 [ 14.183113][ T465] ? console_emit_next_record+0x1df/0x4c0 [ 14.183116][ T465] ? rcu_is_watching+0x16/0xd0 [ 14.183118][ T465] ? lock_acquire+0x13c/0x160 [ 14.183122][ T465] console_emit_next_record+0x22f/0x4c0 [ 14.183126][ T465] ? devkmsg_read+0x4b0/0x4b0 [ 14.183128][ T465] ? console_flush_one_record+0x106/0x710 [ 14.183131][ T465] ? rcu_is_watching+0x16/0xd0 [ 14.183133][ T465] ? lock_acquire+0x13c/0x160 [ 14.183137][ T465] console_flush_one_record+0x46f/0x710 [ 14.183141][ T465] ? console_emit_next_record+0x4c0/0x4c0 [ 14.183143][ T465] ? __lock_acquire+0x518/0xc20 [ 14.183148][ T465] console_unlock+0xee/0x1f0 [ 14.183151][ T465] ? console_flush_one_record+0x710/0x710 [ 14.183153][ T465] ? rcu_is_watching+0x16/0xd0 [ 14.183155][ T465] ? lock_acquire+0x60/0x160 [ 14.183159][ T465] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.183160][ T465] ? vprintk_emit+0x320/0x3e0 [ 14.183164][ T465] vprintk_emit+0x37c/0x3e0 [ 14.183167][ T465] ? wake_up_klogd_work_func+0x90/0x90 [ 14.183170][ T465] ? __lock_release.isra.0+0x69/0x1a0 [ 14.183175][ T465] _printk+0xc7/0x100 [ 14.183178][ T465] ? snapshot_read.cold+0x21/0x21 [ 14.183182][ T465] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 14.183195][ T465] ? do_setlink.isra.0+0xa31/0x2750 [ 14.183197][ T465] ? rtnl_newlink+0x9f1/0x11c0 [ 14.183198][ T465] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.183202][ T465] br_set_state+0x22f/0x430 [bridge] [ 14.183213][ T465] br_init_port+0xc4/0x200 [bridge] [ 14.183221][ T465] new_nbp+0x39c/0x580 [bridge] [ 14.183230][ T465] br_add_if+0x212/0x1320 [bridge] [ 14.183237][ T465] ? is_bpf_text_address+0x72/0x110 [ 14.183241][ T465] ? kernel_text_address+0x149/0x170 [ 14.183244][ T465] ? __kernel_text_address+0x12/0x30 [ 14.183248][ T465] do_set_master+0x357/0x580 [ 14.183252][ T465] do_setlink.isra.0+0xa31/0x2750 [ 14.183255][ T465] ? stack_trace_save+0x93/0xc0 [ 14.183259][ T465] ? rtnl_link_get_size+0x350/0x350 [ 14.183260][ T465] ? rcu_read_lock_any_held+0x66/0x90 [ 14.183262][ T465] ? stack_depot_save_flags+0x38e/0x790 [ 14.183265][ T465] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.183268][ T465] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.183270][ T465] ? validate_chain+0x38b/0xc20 [ 14.183272][ T465] ? kasan_save_stack+0x3d/0x50 [ 14.183275][ T465] ? kasan_save_stack+0x2f/0x50 [ 14.183277][ T465] ? kasan_save_track+0x14/0x30 [ 14.183280][ T465] ? __lock_acquire+0x518/0xc20 [ 14.183281][ T465] ? netlink_seq_next+0x4e/0x60 [ 14.183284][ T465] ? ___sys_sendmsg+0xb0/0x1d0 [ 14.183291][ T465] ? lock_acquire.part.0+0xd4/0x280 [ 14.183294][ T465] ? rtnl_newlink+0x9a8/0x11c0 [ 14.183298][ T465] ? rcu_is_watching+0x16/0xd0 [ 14.183301][ T465] ? lock_acquire+0x13c/0x160 [ 14.183303][ T465] ? rcu_is_watching+0x16/0xd0 [ 14.183304][ T465] ? rcu_is_watching+0x16/0xd0 [ 14.183307][ T465] ? trace_contention_end+0xb3/0x180 [ 14.183310][ T465] ? __mutex_lock+0x1db/0x1ea0 [ 14.183314][ T465] ? __mutex_lock+0x9a3/0x1ea0 [ 14.183316][ T465] ? rtnl_newlink+0x9a8/0x11c0 [ 14.183319][ T465] ? ww_mutex_lock+0x160/0x160 [ 14.183321][ T465] ? nla_get_range_signed+0x3d0/0x3d0 [ 14.183326][ T465] ? __rtnl_newlink+0x3fa/0xa50 [ 14.183331][ T465] rtnl_newlink+0x9f1/0x11c0 [ 14.183336][ T465] ? rtnl_bridge_getlink+0x850/0x850 [ 14.183338][ T465] ? __lock_acquire+0x518/0xc20 [ 14.183342][ T465] ? lock_acquire.part.0+0xd4/0x280 [ 14.183344][ T465] ? find_held_lock+0x2b/0x80 [ 14.183346][ T465] ? rtnl_bridge_getlink+0x850/0x850 [ 14.183348][ T465] ? __lock_release.isra.0+0x69/0x1a0 [ 14.183351][ T465] ? rtnl_bridge_getlink+0x850/0x850 [ 14.183354][ T465] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.183356][ T465] ? rtnl_link_fill+0x920/0x920 [ 14.183358][ T465] ? __lock_acquire+0x518/0xc20 [ 14.183362][ T465] ? lock_acquire.part.0+0xd4/0x280 [ 14.183364][ T465] ? find_held_lock+0x2b/0x80 [ 14.183367][ T465] netlink_rcv_skb+0x14e/0x3a0 [ 14.183369][ T465] ? rtnl_link_fill+0x920/0x920 [ 14.183372][ T465] ? netlink_ack+0xcf0/0xcf0 [ 14.183377][ T465] ? netlink_deliver_tap+0xc5/0x330 [ 14.183379][ T465] ? netlink_deliver_tap+0x13c/0x330 [ 14.183383][ T465] netlink_unicast+0x486/0x750 [ 14.183387][ T465] ? netlink_attachskb+0x810/0x810 [ 14.183389][ T465] ? __lock_acquire+0x518/0xc20 [ 14.183393][ T465] netlink_sendmsg+0x735/0xc60 [ 14.183399][ T465] ? netlink_unicast+0x750/0x750 [ 14.183403][ T465] ? __might_fault+0x97/0x140 [ 14.183408][ T465] ____sys_sendmsg+0x415/0x880 [ 14.183410][ T465] ? copy_msghdr_from_user+0x279/0x420 [ 14.183412][ T465] ? get_timestamp.constprop.0+0x390/0x390 [ 14.183414][ T465] ? move_addr_to_kernel+0x40/0x40 [ 14.183422][ T465] ___sys_sendmsg+0x14e/0x1d0 [ 14.183424][ T465] ? copy_msghdr_from_user+0x420/0x420 [ 14.183441][ T465] __sys_sendmsg+0x12c/0x1d0 [ 14.183444][ T465] ? __sys_sendmsg_sock+0x20/0x20 [ 14.183451][ T465] ? rcu_is_watching+0x16/0xd0 [ 14.183454][ T465] do_syscall_64+0xff/0x530 [ 14.183457][ T465] ? exc_page_fault+0xee/0x100 [ 14.183460][ T465] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.183462][ T465] RIP: 0033:0x7f479ab5154e [ 14.183466][ T465] 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.183467][ T465] RSP: 002b:00007ffc0272bfc0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 14.183471][ T465] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f479ab5154e [ 14.183473][ T465] RDX: 0000000000000000 RSI: 00007ffc0272c070 RDI: 0000000000000005 [ 14.183474][ T465] RBP: 00007ffc0272bfd0 R08: 0000000000000000 R09: 0000000000000000 [ 14.183476][ T465] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9106e8 [ 14.183477][ T465] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc0272c730 [ 14.183487][ T465] [ 14.225249][ T465] br12: port 1(veth1.12) entered disabled state [ 14.225655][ T465] veth1.12: entered allmulticast mode [ 14.227341][ T465] veth1.12: entered promiscuous mode [ 14.287840][ T471] br12: port 2(veth2.12) entered blocking state [ 14.288169][ T471] br12: port 2(veth2.12) entered disabled state [ 14.288488][ T471] veth2.12: entered allmulticast mode [ 14.290056][ T471] veth2.12: entered promiscuous mode [ 14.339579][ T476] br12: port 2(veth2.12) entered blocking state [ 14.339909][ T476] br12: port 2(veth2.12) entered forwarding state [ 14.340320][ T476] br12: port 1(veth1.12) entered blocking state [ 14.340614][ T476] br12: port 1(veth1.12) entered forwarding state [ 28.540643][ C2] [ 28.540660][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 28.540662][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 584, name: mausezahn [ 28.540664][ C2] preempt_count: 103, expected: 0 [ 28.540665][ C2] RCU nest depth: 1, expected: 0 [ 28.540666][ C2] INFO: lockdep is turned off. [ 28.540667][ C2] irq event stamp: 5155188 [ 28.540667][ C2] hardirqs last enabled at (5155188): [] _raw_spin_unlock_irq+0x28/0x50 [ 28.540676][ C2] hardirqs last disabled at (5155187): [] _raw_spin_lock_irq+0x4a/0x50 [ 28.540678][ C2] softirqs last enabled at (5154756): [] __alloc_skb+0x4c2/0x5f0 [ 28.540682][ C2] softirqs last disabled at (5155075): [] __irq_exit_rcu+0x145/0x1c0 [ 28.540686][ C2] Preemption disabled at: [ 28.540686][ C2] [] handle_softirqs+0xf6/0x900 [ 28.540692][ C2] CPU: 2 UID: 0 PID: 584 Comm: mausezahn Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.540696][ C2] Tainted: [W]=WARN [ 28.540697][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.540698][ C2] Call Trace: [ 28.540700][ C2] [ 28.540701][ C2] dump_stack_lvl+0x6f/0xa0 [ 28.540707][ C2] ? handle_softirqs+0xf6/0x900 [ 28.540708][ C2] __might_resched.cold+0x1fe/0x2c1 [ 28.540712][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 28.540715][ C2] ? __asan_memcpy+0x3c/0x60 [ 28.540718][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.540722][ C2] __kmalloc_noprof+0x443/0x760 [ 28.540725][ C2] ? __rwlock_init+0x150/0x150 [ 28.540728][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 28.540731][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 28.540733][ C2] alloc_buf.isra.0+0x4b/0x260 [ 28.540735][ C2] put_chars+0x1e1/0x2f0 [ 28.540737][ C2] ? __send_to_port+0x420/0x420 [ 28.540739][ C2] ? console_prepend_replay+0x20/0x20 [ 28.540742][ C2] hvc_console_print+0x292/0x780 [ 28.540745][ C2] ? hvc_write+0x3a0/0x3a0 [ 28.540747][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.540748][ C2] ? lock_acquire+0x13c/0x160 [ 28.540751][ C2] console_emit_next_record+0x22f/0x4c0 [ 28.540753][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 28.540755][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.540757][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.540758][ C2] ? lock_acquire+0x13c/0x160 [ 28.540760][ C2] ? console_flush_one_record+0x111/0x710 [ 28.540762][ C2] console_flush_one_record+0x46f/0x710 [ 28.540764][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 28.540767][ C2] console_unlock+0xee/0x1f0 [ 28.540769][ C2] ? lock_acquire+0x13c/0x160 [ 28.540770][ C2] ? console_flush_one_record+0x710/0x710 [ 28.540772][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.540773][ C2] ? lock_release+0x184/0x1f0 [ 28.540775][ C2] ? lock_acquire+0x60/0x160 [ 28.540777][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 28.540779][ C2] ? vprintk_emit+0x320/0x3e0 [ 28.540781][ C2] vprintk_emit+0x37c/0x3e0 [ 28.540783][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 28.540785][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.540786][ C2] ? lock_release+0x184/0x1f0 [ 28.540789][ C2] _printk+0xc7/0x100 [ 28.540792][ C2] ? snapshot_read.cold+0x21/0x21 [ 28.540793][ C2] ? arch_stack_walk+0xed/0x130 [ 28.540797][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 28.540799][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 28.540801][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 28.540804][ C2] mark_lock_irq+0x989/0x9c0 [ 28.540806][ C2] mark_lock+0x1d7/0xa00 [ 28.540808][ C2] mark_usage+0x42/0x170 [ 28.540810][ C2] __lock_acquire+0x388/0xc20 [ 28.540812][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.540814][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.540826][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.540828][ C2] ? lock_acquire+0x13c/0x160 [ 28.540829][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.540836][ C2] _raw_spin_lock+0x33/0x40 [ 28.540837][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.540844][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.540850][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.540856][ C2] call_timer_fn+0x160/0x4d0 [ 28.540860][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 28.540861][ C2] ? debug_object_active_state+0x430/0x430 [ 28.540865][ C2] ? find_held_lock+0x2b/0x80 [ 28.540866][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 28.540868][ C2] ? mark_held_locks+0x40/0x70 [ 28.540870][ C2] __run_timers+0x68f/0xaa0 [ 28.540872][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.540879][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 28.540880][ C2] ? __lock_acquire+0x518/0xc20 [ 28.540883][ C2] ? __rwlock_init+0x150/0x150 [ 28.540885][ C2] run_timer_softirq+0xf0/0x160 [ 28.540887][ C2] ? __run_timers+0xaa0/0xaa0 [ 28.540889][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.540891][ C2] handle_softirqs+0x1d3/0x900 [ 28.540893][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 28.540895][ C2] ? _local_bh_enable+0xc0/0xc0 [ 28.540897][ C2] __irq_exit_rcu+0x145/0x1c0 [ 28.540898][ C2] irq_exit_rcu+0xe/0x30 [ 28.540900][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.540903][ C2] [ 28.540903][ C2] [ 28.540904][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.540906][ C2] RIP: 0010:lock_acquire.part.0+0x112/0x280 [ 28.540909][ C2] Code: ff 65 0f c1 05 ff a7 38 04 83 f8 01 4c 8b 14 24 0f 85 35 01 00 00 9c 58 f6 c4 02 0f 85 52 01 00 00 4d 85 d2 0f 85 1b 01 00 00 <48> 8b 44 24 30 65 48 2b 05 d1 62 38 04 0f 85 48 01 00 00 48 83 c4 [ 28.540911][ C2] RSP: 0018:ffa00000005675b0 EFLAGS: 00000206 [ 28.540914][ C2] RAX: 0000000000000046 RBX: 0000000000000000 RCX: 5eaeee35fd653602 [ 28.540916][ C2] RDX: 0000000000000000 RSI: ffffffffa8221b20 RDI: ffffffffa7c8d8e0 [ 28.540916][ C2] RBP: 0000000000000000 R08: 0000000000000001 R09: ff110000090daeb8 [ 28.540917][ C2] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffffa8780b00 [ 28.540918][ C2] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 28.540920][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.540922][ C2] ? lock_acquire+0x13c/0x160 [ 28.540924][ C2] unwind_next_frame+0x192/0x1ea0 [ 28.540926][ C2] ? unwind_next_frame+0x17e/0x1ea0 [ 28.540928][ C2] ? alloc_skb_with_frags+0xcc/0x6c0 [ 28.540936][ C2] ? stack_access_ok+0x1e0/0x1e0 [ 28.540938][ C2] ? __unwind_start+0x42e/0x900 [ 28.540940][ C2] ? write_profile+0xf0/0xf0 [ 28.540942][ C2] arch_stack_walk+0xd7/0x130 [ 28.540944][ C2] ? alloc_skb_with_frags+0xcc/0x6c0 [ 28.540946][ C2] stack_trace_save+0x93/0xc0 [ 28.540948][ C2] ? filter_irq_stacks+0xd0/0xd0 [ 28.540950][ C2] set_track_prepare+0x62/0x90 [ 28.540952][ C2] ? kasan_quarantine_reduce+0x196/0x240 [ 28.540953][ C2] ? __kasan_slab_alloc+0x4b/0x70 [ 28.540956][ C2] ? kmem_cache_alloc_node_noprof+0x25c/0x660 [ 28.540957][ C2] ? __alloc_skb+0xde/0x5f0 [ 28.540959][ C2] ? alloc_skb_with_frags+0xcc/0x6c0 [ 28.540961][ C2] free_to_partial_list+0x1c6/0x310 [ 28.540963][ C2] ? qlist_free_all+0x57/0x110 [ 28.540965][ C2] qlist_free_all+0x5c/0x110 [ 28.540966][ C2] kasan_quarantine_reduce+0x196/0x240 [ 28.540968][ C2] __kasan_slab_alloc+0x4b/0x70 [ 28.540970][ C2] kmem_cache_alloc_node_noprof+0x25c/0x660 [ 28.540971][ C2] ? __alloc_skb+0xde/0x5f0 [ 28.540974][ C2] __alloc_skb+0xde/0x5f0 [ 28.540975][ C2] ? __alloc_skb+0x4c2/0x5f0 [ 28.540977][ C2] ? napi_skb_cache_get+0x830/0x830 [ 28.540979][ C2] ? mark_usage+0x61/0x170 [ 28.540980][ C2] ? ref_tracker_alloc+0x2e7/0x430 [ 28.540983][ C2] ? ref_tracker_dir_print+0x100/0x100 [ 28.540985][ C2] alloc_skb_with_frags+0xcc/0x6c0 [ 28.540987][ C2] ? find_held_lock+0x2b/0x80 [ 28.540989][ C2] sock_alloc_send_pskb+0x327/0x3f0 [ 28.540991][ C2] packet_snd+0x5dd/0x1840 [ 28.540996][ C2] ? tpacket_snd+0x1990/0x1990 [ 28.540999][ C2] ? __might_fault+0x97/0x140 [ 28.541001][ C2] ? __might_fault+0x97/0x140 [ 28.541003][ C2] __sys_sendto+0x2aa/0x400 [ 28.541006][ C2] ? __ia32_sys_getpeername+0xd0/0xd0 [ 28.541009][ C2] ? sock_ioctl+0x3cb/0x5f0 [ 28.541011][ C2] ? xfd_validate_state+0x2a/0x140 [ 28.541014][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 28.541016][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.541018][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 28.541020][ C2] ? do_syscall_64+0xa6/0x530 [ 28.541021][ C2] do_syscall_64+0xff/0x530 [ 28.541023][ C2] ? irq_exit_rcu+0x1a/0x30 [ 28.541024][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.541026][ C2] RIP: 0033:0x7effcc41d54e [ 28.541029][ C2] 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 [ 28.541030][ C2] RSP: 002b:00007fff2ea9c660 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 28.541031][ C2] RAX: ffffffffffffffda RBX: 00005614df1d85e2 RCX: 00007effcc41d54e [ 28.541032][ C2] RDX: 0000000000001f6a RSI: 00005614df1d85e2 RDI: 0000000000000005 [ 28.541033][ C2] RBP: 00007fff2ea9c670 R08: 00007fff2ea9c6c0 R09: 0000000000000014 [ 28.541034][ C2] R10: 0000000000000000 R11: 0000000000000202 R12: 00005614df1d5830 [ 28.541035][ C2] R13: 0000000000001f6a R14: 0000000000000005 R15: 00005614df1d5830 [ 28.541037][ C2] [ 28.571990][ C2] ======================================================== [ 28.572221][ C2] WARNING: possible irq lock inversion dependency detected [ 28.572458][ C2] 7.2.0-virtme #1 Tainted: G W [ 28.572655][ C2] -------------------------------------------------------- [ 28.572887][ C2] mausezahn/584 just changed the state of lock: [ 28.573082][ C2] ff11000008e62e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.573401][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 28.573633][ C2] (fs_reclaim){+.+.}-{0:0} [ 28.573636][ C2] [ 28.573636][ C2] [ 28.573636][ C2] and interrupts could create inverse lock ordering between them. [ 28.573636][ C2] [ 28.574203][ C2] [ 28.574203][ C2] other info that might help us debug this: [ 28.574434][ C2] Chain exists of: [ 28.574434][ C2] &br->lock --> console_owner --> fs_reclaim [ 28.574434][ C2] [ 28.574781][ C2] Possible interrupt unsafe locking scenario: [ 28.574781][ C2] [ 28.575016][ C2] CPU0 CPU1 [ 28.575170][ C2] ---- ---- [ 28.575325][ C2] lock(fs_reclaim); [ 28.575451][ C2] local_irq_disable(); [ 28.575647][ C2] lock(&br->lock); [ 28.575842][ C2] lock(console_owner); [ 28.576042][ C2] [ 28.576160][ C2] lock(&br->lock); [ 28.576280][ C2] [ 28.576280][ C2] *** DEADLOCK *** [ 28.576280][ C2] [ 28.576508][ C2] locks held by mausezahn/584: 3, last CPU#2: [ 28.576700][ C2] #0: ffffffffa89ae1b8 (remove_cache_srcu){.+.+}-{0:0}, at: kasan_quarantine_reduce+0x84/0x240 [ 28.577019][ C2] #1: ffffffffa8780b00 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x17e/0x1ea0 [ 28.577399][ C2] #2: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 28.577760][ C2] [ 28.577760][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 28.578084][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 28.578246][ C2] HARDIRQ-ON-W at: [ 28.578365][ C2] __lock_acquire+0x388/0xc20 [ 28.578624][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.578873][ C2] fs_reclaim_acquire+0xd5/0x120 [ 28.579184][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 28.579419][ C2] kthread_create_worker_on_node+0xea/0x210 [ 28.583160][ C2] workqueue_init+0x2a/0x680 [ 28.583361][ C2] kernel_init_freeable+0x2fe/0x630 [ 28.583594][ C2] kernel_init+0x21/0x150 [ 28.583791][ C2] ret_from_fork+0x474/0x6b0 [ 28.584049][ C2] ret_from_fork_asm+0x11/0x20 [ 28.584349][ C2] SOFTIRQ-ON-W at: [ 28.584466][ C2] __lock_acquire+0x388/0xc20 [ 28.584693][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.584886][ C2] fs_reclaim_acquire+0xd5/0x120 [ 28.585082][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 28.585369][ C2] kthread_create_worker_on_node+0xea/0x210 [ 28.585601][ C2] workqueue_init+0x2a/0x680 [ 28.585847][ C2] kernel_init_freeable+0x2fe/0x630 [ 28.586081][ C2] kernel_init+0x21/0x150 [ 28.586276][ C2] ret_from_fork+0x474/0x6b0 [ 28.586470][ C2] ret_from_fork_asm+0x11/0x20 [ 28.586717][ C2] INITIAL USE at: [ 28.586834][ C2] __lock_acquire+0x388/0xc20 [ 28.587030][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.587222][ C2] fs_reclaim_acquire+0xd5/0x120 [ 28.587416][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 28.587700][ C2] kthread_create_worker_on_node+0xea/0x210 [ 28.587927][ C2] workqueue_init+0x2a/0x680 [ 28.588235][ C2] kernel_init_freeable+0x2fe/0x630 [ 28.588592][ C2] kernel_init+0x21/0x150 [ 28.588841][ C2] ret_from_fork+0x474/0x6b0 [ 28.589100][ C2] ret_from_fork_asm+0x11/0x20 [ 28.589291][ C2] } [ 28.589370][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 28.589599][ C2] ... acquired at: [ 28.589716][ C2] __lock_acquire+0x518/0xc20 [ 28.589874][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.590196][ C2] fs_reclaim_acquire+0xd5/0x120 [ 28.590407][ C2] __kmalloc_noprof+0xd3/0x760 [ 28.590671][ C2] alloc_buf.isra.0+0x4b/0x260 [ 28.590824][ C2] put_chars+0x1e1/0x2f0 [ 28.591091][ C2] hvc_console_print+0x292/0x780 [ 28.591304][ C2] console_emit_next_record+0x22f/0x4c0 [ 28.591515][ C2] console_flush_one_record+0x46f/0x710 [ 28.591666][ C2] console_unlock+0xee/0x1f0 [ 28.591818][ C2] vprintk_emit+0x37c/0x3e0 [ 28.591976][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 28.592242][ C2] dev_printk_emit+0xb9/0xee [ 28.592451][ C2] _dev_info+0xe2/0x116 [ 28.592729][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 28.592921][ C2] cmos_do_probe+0x73b/0x98a [ 28.593085][ C2] platform_probe+0xfe/0x1f0 [ 28.593262][ C2] call_driver_probe+0x61/0x1c0 [ 28.593528][ C2] really_probe+0x199/0x760 [ 28.593741][ C2] __driver_probe_device+0x24f/0x440 [ 28.593957][ C2] driver_probe_device+0x4a/0xf0 [ 28.594108][ C2] __driver_attach+0x1b8/0x540 [ 28.594262][ C2] bus_for_each_dev+0x130/0x1e0 [ 28.594414][ C2] bus_add_driver+0x2c8/0x530 [ 28.594680][ C2] driver_register+0x1a3/0x390 [ 28.594832][ C2] __platform_driver_probe+0x13f/0x270 [ 28.595153][ C2] cmos_init+0x31/0x40 [ 28.595273][ C2] do_one_initcall+0x124/0x4f0 [ 28.595429][ C2] kernel_init_freeable+0x596/0x630 [ 28.595584][ C2] kernel_init+0x21/0x150 [ 28.595845][ C2] ret_from_fork+0x474/0x6b0 [ 28.596007][ C2] ret_from_fork_asm+0x11/0x20 [ 28.596160][ C2] [ 28.596295][ C2] -> (console_owner){....}-{0:0} { [ 28.596451][ C2] INITIAL USE at: [ 28.596620][ C2] } [ 28.596697][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 28.596924][ C2] ... acquired at: [ 28.597042][ C2] __lock_acquire+0x518/0xc20 [ 28.597197][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.597348][ C2] console_lock_spinning_enable+0x5c/0x60 [ 28.597595][ C2] console_emit_next_record+0x1d1/0x4c0 [ 28.597801][ C2] console_flush_one_record+0x46f/0x710 [ 28.597956][ C2] console_unlock+0xee/0x1f0 [ 28.598108][ C2] vprintk_emit+0x37c/0x3e0 [ 28.598259][ C2] _printk+0xc7/0x100 [ 28.598433][ C2] br_set_state+0x22f/0x430 [bridge] [ 28.598591][ C2] br_init_port+0xc4/0x200 [bridge] [ 28.598747][ C2] br_stp_enable_bridge+0x1fc/0x340 [bridge] [ 28.598998][ C2] br_dev_open+0x94/0x120 [bridge] [ 28.599154][ C2] __dev_open+0x2f3/0x610 [ 28.599419][ C2] __dev_change_flags+0x4a4/0x660 [ 28.599572][ C2] netif_change_flags+0x87/0x190 [ 28.599837][ C2] do_setlink.isra.0+0x1cc3/0x2750 [ 28.599997][ C2] rtnl_newlink+0x9f1/0x11c0 [ 28.600149][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.600301][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 28.600470][ C2] netlink_unicast+0x486/0x750 [ 28.600625][ C2] netlink_sendmsg+0x735/0xc60 [ 28.600886][ C2] ____sys_sendmsg+0x415/0x880 [ 28.601048][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 28.601311][ C2] __sys_sendmsg+0x12c/0x1d0 [ 28.601465][ C2] do_syscall_64+0xff/0x530 [ 28.601616][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.601804][ C2] [ 28.601936][ C2] -> (&br->lock){+.-.}-{3:3} { [ 28.602149][ C2] HARDIRQ-ON-W at: [ 28.602319][ C2] __lock_acquire+0x388/0xc20 [ 28.602513][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.602822][ C2] _raw_spin_lock_bh+0x38/0x50 [ 28.603020][ C2] br_add_if+0x8c1/0x1320 [bridge] [ 28.603382][ C2] do_set_master+0x357/0x580 [ 28.603631][ C2] do_setlink.isra.0+0xa31/0x2750 [ 28.603876][ C2] rtnl_newlink+0x9f1/0x11c0 [ 28.604188][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.604435][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 28.604681][ C2] netlink_unicast+0x486/0x750 [ 28.604925][ C2] netlink_sendmsg+0x735/0xc60 [ 28.605180][ C2] ____sys_sendmsg+0x415/0x880 [ 28.605371][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 28.605563][ C2] __sys_sendmsg+0x12c/0x1d0 [ 28.605754][ C2] do_syscall_64+0xff/0x530 [ 28.605951][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.606179][ C2] IN-SOFTIRQ-W at: [ 28.606291][ C2] __lock_acquire+0x388/0xc20 [ 28.606538][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.606730][ C2] _raw_spin_lock+0x33/0x40 [ 28.606979][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.607266][ C2] call_timer_fn+0x160/0x4d0 [ 28.607513][ C2] __run_timers+0x68f/0xaa0 [ 28.607755][ C2] run_timer_softirq+0xf0/0x160 [ 28.608061][ C2] handle_softirqs+0x1d3/0x900 [ 28.608256][ C2] __irq_exit_rcu+0x145/0x1c0 [ 28.611399][ C2] irq_exit_rcu+0xe/0x30 [ 28.611602][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.611838][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.612244][ C2] lock_acquire.part.0+0x112/0x280 [ 28.612498][ C2] unwind_next_frame+0x192/0x1ea0 [ 28.612692][ C2] arch_stack_walk+0xd7/0x130 [ 28.612888][ C2] stack_trace_save+0x93/0xc0 [ 28.613085][ C2] set_track_prepare+0x62/0x90 [ 28.613336][ C2] free_to_partial_list+0x1c6/0x310 [ 28.613646][ C2] qlist_free_all+0x5c/0x110 [ 28.613840][ C2] kasan_quarantine_reduce+0x196/0x240 [ 28.614206][ C2] __kasan_slab_alloc+0x4b/0x70 [ 28.614408][ C2] kmem_cache_alloc_node_noprof+0x25c/0x660 [ 28.614642][ C2] __alloc_skb+0xde/0x5f0 [ 28.614958][ C2] alloc_skb_with_frags+0xcc/0x6c0 [ 28.615216][ C2] sock_alloc_send_pskb+0x327/0x3f0 [ 28.615524][ C2] packet_snd+0x5dd/0x1840 [ 28.615835][ C2] __sys_sendto+0x2aa/0x400 [ 28.616037][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 28.616345][ C2] do_syscall_64+0xff/0x530 [ 28.616541][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.616778][ C2] INITIAL USE at: [ 28.617069][ C2] __lock_acquire+0x388/0xc20 [ 28.617262][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.617457][ C2] _raw_spin_lock_bh+0x38/0x50 [ 28.617823][ C2] br_add_if+0x8c1/0x1320 [bridge] [ 28.618031][ C2] do_set_master+0x357/0x580 [ 28.618230][ C2] do_setlink.isra.0+0xa31/0x2750 [ 28.618539][ C2] rtnl_newlink+0x9f1/0x11c0 [ 28.618735][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.619046][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 28.619297][ C2] netlink_unicast+0x486/0x750 [ 28.619548][ C2] netlink_sendmsg+0x735/0xc60 [ 28.619744][ C2] ____sys_sendmsg+0x415/0x880 [ 28.619942][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 28.620303][ C2] __sys_sendmsg+0x12c/0x1d0 [ 28.620555][ C2] do_syscall_64+0xff/0x530 [ 28.620806][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.621043][ C2] } [ 28.621121][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 28.621464][ C2] ... acquired at: [ 28.621633][ C2] mark_lock+0x1d7/0xa00 [ 28.621791][ C2] mark_usage+0x42/0x170 [ 28.621950][ C2] __lock_acquire+0x388/0xc20 [ 28.622104][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.622261][ C2] _raw_spin_lock+0x33/0x40 [ 28.622413][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.622610][ C2] call_timer_fn+0x160/0x4d0 [ 28.622821][ C2] __run_timers+0x68f/0xaa0 [ 28.622981][ C2] run_timer_softirq+0xf0/0x160 [ 28.623297][ C2] handle_softirqs+0x1d3/0x900 [ 28.623459][ C2] __irq_exit_rcu+0x145/0x1c0 [ 28.623615][ C2] irq_exit_rcu+0xe/0x30 [ 28.623825][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.624021][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.624211][ C2] lock_acquire.part.0+0x112/0x280 [ 28.624367][ C2] unwind_next_frame+0x192/0x1ea0 [ 28.624630][ C2] arch_stack_walk+0xd7/0x130 [ 28.624788][ C2] stack_trace_save+0x93/0xc0 [ 28.625004][ C2] set_track_prepare+0x62/0x90 [ 28.625164][ C2] free_to_partial_list+0x1c6/0x310 [ 28.625320][ C2] qlist_free_all+0x5c/0x110 [ 28.625475][ C2] kasan_quarantine_reduce+0x196/0x240 [ 28.625629][ C2] __kasan_slab_alloc+0x4b/0x70 [ 28.625787][ C2] kmem_cache_alloc_node_noprof+0x25c/0x660 [ 28.626039][ C2] __alloc_skb+0xde/0x5f0 [ 28.626195][ C2] alloc_skb_with_frags+0xcc/0x6c0 [ 28.626407][ C2] sock_alloc_send_pskb+0x327/0x3f0 [ 28.626616][ C2] packet_snd+0x5dd/0x1840 [ 28.626879][ C2] __sys_sendto+0x2aa/0x400 [ 28.627036][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 28.627296][ C2] do_syscall_64+0xff/0x530 [ 28.627448][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.627690][ C2] [ 28.627766][ C2] [ 28.627766][ C2] stack backtrace: [ 28.628017][ C2] CPU: 2 UID: 0 PID: 584 Comm: mausezahn Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.628021][ C2] Tainted: [W]=WARN [ 28.628022][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.628023][ C2] Call Trace: [ 28.628025][ C2] [ 28.628026][ C2] dump_stack_lvl+0x6f/0xa0 [ 28.628031][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 28.628034][ C2] mark_lock_irq+0x989/0x9c0 [ 28.628037][ C2] mark_lock+0x1d7/0xa00 [ 28.628039][ C2] mark_usage+0x42/0x170 [ 28.628041][ C2] __lock_acquire+0x388/0xc20 [ 28.628043][ C2] lock_acquire.part.0+0xd4/0x280 [ 28.628045][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.628052][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.628055][ C2] ? lock_acquire+0x13c/0x160 [ 28.628058][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.628064][ C2] _raw_spin_lock+0x33/0x40 [ 28.628066][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.628072][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.628079][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.628085][ C2] call_timer_fn+0x160/0x4d0 [ 28.628087][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 28.628089][ C2] ? debug_object_active_state+0x430/0x430 [ 28.628093][ C2] ? find_held_lock+0x2b/0x80 [ 28.628095][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 28.628096][ C2] ? mark_held_locks+0x40/0x70 [ 28.628098][ C2] __run_timers+0x68f/0xaa0 [ 28.628100][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.628107][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 28.628109][ C2] ? __lock_acquire+0x518/0xc20 [ 28.628111][ C2] ? __rwlock_init+0x150/0x150 [ 28.628114][ C2] run_timer_softirq+0xf0/0x160 [ 28.628116][ C2] ? __run_timers+0xaa0/0xaa0 [ 28.628118][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.628119][ C2] handle_softirqs+0x1d3/0x900 [ 28.628122][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 28.628123][ C2] ? _local_bh_enable+0xc0/0xc0 [ 28.628125][ C2] __irq_exit_rcu+0x145/0x1c0 [ 28.628127][ C2] irq_exit_rcu+0xe/0x30 [ 28.628128][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.628130][ C2] [ 28.628131][ C2] [ 28.628132][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.628133][ C2] RIP: 0010:lock_acquire.part.0+0x112/0x280 [ 28.628135][ C2] Code: ff 65 0f c1 05 ff a7 38 04 83 f8 01 4c 8b 14 24 0f 85 35 01 00 00 9c 58 f6 c4 02 0f 85 52 01 00 00 4d 85 d2 0f 85 1b 01 00 00 <48> 8b 44 24 30 65 48 2b 05 d1 62 38 04 0f 85 48 01 00 00 48 83 c4 [ 28.628137][ C2] RSP: 0018:ffa00000005675b0 EFLAGS: 00000206 [ 28.628139][ C2] RAX: 0000000000000046 RBX: 0000000000000000 RCX: 5eaeee35fd653602 [ 28.628140][ C2] RDX: 0000000000000000 RSI: ffffffffa8221b20 RDI: ffffffffa7c8d8e0 [ 28.628141][ C2] RBP: 0000000000000000 R08: 0000000000000001 R09: ff110000090daeb8 [ 28.628142][ C2] R10: 0000000000000200 R11: 0000000000000001 R12: ffffffffa8780b00 [ 28.628143][ C2] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000002 [ 28.628145][ C2] ? rcu_is_watching+0x16/0xd0 [ 28.628147][ C2] ? lock_acquire+0x13c/0x160 [ 28.628149][ C2] unwind_next_frame+0x192/0x1ea0 [ 28.628151][ C2] ? unwind_next_frame+0x17e/0x1ea0 [ 28.628152][ C2] ? alloc_skb_with_frags+0xcc/0x6c0 [ 28.628155][ C2] ? stack_access_ok+0x1e0/0x1e0 [ 28.628156][ C2] ? __unwind_start+0x42e/0x900 [ 28.628158][ C2] ? write_profile+0xf0/0xf0 [ 28.628159][ C2] arch_stack_walk+0xd7/0x130 [ 28.628162][ C2] ? alloc_skb_with_frags+0xcc/0x6c0 [ 28.628164][ C2] stack_trace_save+0x93/0xc0 [ 28.628166][ C2] ? filter_irq_stacks+0xd0/0xd0 [ 28.628168][ C2] set_track_prepare+0x62/0x90 [ 28.628169][ C2] ? kasan_quarantine_reduce+0x196/0x240 [ 28.628171][ C2] ? __kasan_slab_alloc+0x4b/0x70 [ 28.628172][ C2] ? kmem_cache_alloc_node_noprof+0x25c/0x660 [ 28.628174][ C2] ? __alloc_skb+0xde/0x5f0 [ 28.628175][ C2] ? alloc_skb_with_frags+0xcc/0x6c0 [ 28.628178][ C2] free_to_partial_list+0x1c6/0x310 [ 28.628179][ C2] ? qlist_free_all+0x57/0x110 [ 28.628181][ C2] qlist_free_all+0x5c/0x110 [ 28.628183][ C2] kasan_quarantine_reduce+0x196/0x240 [ 28.628184][ C2] __kasan_slab_alloc+0x4b/0x70 [ 28.628186][ C2] kmem_cache_alloc_node_noprof+0x25c/0x660 [ 28.628188][ C2] ? __alloc_skb+0xde/0x5f0 [ 28.628190][ C2] __alloc_skb+0xde/0x5f0 [ 28.628192][ C2] ? __alloc_skb+0x4c2/0x5f0 [ 28.628193][ C2] ? napi_skb_cache_get+0x830/0x830 [ 28.628195][ C2] ? mark_usage+0x61/0x170 [ 28.628197][ C2] ? ref_tracker_alloc+0x2e7/0x430 [ 28.628199][ C2] ? ref_tracker_dir_print+0x100/0x100 [ 28.628201][ C2] alloc_skb_with_frags+0xcc/0x6c0 [ 28.628203][ C2] ? find_held_lock+0x2b/0x80 [ 28.628205][ C2] sock_alloc_send_pskb+0x327/0x3f0 [ 28.628207][ C2] packet_snd+0x5dd/0x1840 [ 28.628210][ C2] ? tpacket_snd+0x1990/0x1990 [ 28.628212][ C2] ? __might_fault+0x97/0x140 [ 28.628216][ C2] ? __might_fault+0x97/0x140 [ 28.628218][ C2] __sys_sendto+0x2aa/0x400 [ 28.628220][ C2] ? __ia32_sys_getpeername+0xd0/0xd0 [ 28.628222][ C2] ? sock_ioctl+0x3cb/0x5f0 [ 28.628225][ C2] ? xfd_validate_state+0x2a/0x140 [ 28.628227][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 28.628229][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.628231][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 28.628233][ C2] ? do_syscall_64+0xa6/0x530 [ 28.628234][ C2] do_syscall_64+0xff/0x530 [ 28.628235][ C2] ? irq_exit_rcu+0x1a/0x30 [ 28.628237][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.628238][ C2] RIP: 0033:0x7effcc41d54e [ 28.628240][ C2] 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 [ 28.628241][ C2] RSP: 002b:00007fff2ea9c660 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 28.628242][ C2] RAX: ffffffffffffffda RBX: 00005614df1d85e2 RCX: 00007effcc41d54e [ 28.628243][ C2] RDX: 0000000000001f6a RSI: 00005614df1d85e2 RDI: 0000000000000005 [ 28.628244][ C2] RBP: 00007fff2ea9c670 R08: 00007fff2ea9c6c0 R09: 0000000000000014 [ 28.628245][ C2] R10: 0000000000000000 R11: 0000000000000202 R12: 00005614df1d5830 [ 28.628246][ C2] R13: 0000000000001f6a R14: 0000000000000005 R15: 00005614df1d5830 [ 28.628248][ C2] [ 391.465186][ T1969] br12: port 2(veth2.12) entered disabled state [ 391.465203][ T1969] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 391.465205][ T1969] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1969, name: ip [ 391.465207][ T1969] preempt_count: 202, expected: 0 [ 391.465208][ T1969] RCU nest depth: 0, expected: 0 [ 391.465209][ T1969] INFO: lockdep is turned off. [ 391.465211][ T1969] irq event stamp: 0 [ 391.465211][ T1969] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 391.465216][ T1969] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 391.465222][ T1969] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 391.465224][ T1969] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 391.465226][ T1969] Preemption disabled at: [ 391.465226][ T1969] [<0000000000000000>] 0x0 [ 391.465231][ T1969] CPU: 2 UID: 0 PID: 1969 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 391.465235][ T1969] Tainted: [W]=WARN [ 391.465236][ T1969] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 391.465237][ T1969] Call Trace: [ 391.465239][ T1969] [ 391.465240][ T1969] dump_stack_lvl+0x6f/0xa0 [ 391.465247][ T1969] __might_resched.cold+0x1fe/0x2c1 [ 391.465250][ T1969] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 391.465254][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465258][ T1969] __kmalloc_noprof+0x443/0x760 [ 391.465262][ T1969] ? __rwlock_init+0x150/0x150 [ 391.465265][ T1969] ? alloc_buf.isra.0+0x4b/0x260 [ 391.465268][ T1969] ? do_raw_spin_unlock+0x59/0x250 [ 391.465270][ T1969] alloc_buf.isra.0+0x4b/0x260 [ 391.465272][ T1969] put_chars+0x1e1/0x2f0 [ 391.465274][ T1969] ? __send_to_port+0x420/0x420 [ 391.465275][ T1969] ? printk_get_next_message+0x240/0x650 [ 391.465279][ T1969] hvc_console_print+0x292/0x780 [ 391.465282][ T1969] ? hvc_write+0x3a0/0x3a0 [ 391.465285][ T1969] ? __rwlock_init+0x150/0x150 [ 391.465286][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465288][ T1969] ? lock_acquire+0x13c/0x160 [ 391.465290][ T1969] console_emit_next_record+0x22f/0x4c0 [ 391.465292][ T1969] ? devkmsg_read+0x4b0/0x4b0 [ 391.465295][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465296][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465298][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465300][ T1969] ? lock_acquire+0x13c/0x160 [ 391.465301][ T1969] ? console_flush_one_record+0x111/0x710 [ 391.465303][ T1969] console_flush_one_record+0x46f/0x710 [ 391.465305][ T1969] ? console_emit_next_record+0x4c0/0x4c0 [ 391.465308][ T1969] console_unlock+0xee/0x1f0 [ 391.465310][ T1969] ? lock_acquire+0x13c/0x160 [ 391.465311][ T1969] ? console_flush_one_record+0x710/0x710 [ 391.465313][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465314][ T1969] ? lock_release+0x184/0x1f0 [ 391.465316][ T1969] ? lock_acquire+0x60/0x160 [ 391.465317][ T1969] ? __rcu_read_lock+0x30/0x70 [ 391.465319][ T1969] ? __down_trylock_console_sem+0x5e/0xa0 [ 391.465321][ T1969] ? vprintk_emit+0x320/0x3e0 [ 391.465323][ T1969] vprintk_emit+0x37c/0x3e0 [ 391.465326][ T1969] ? wake_up_klogd_work_func+0x90/0x90 [ 391.465327][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465329][ T1969] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 391.465332][ T1969] ? trace_hardirqs_on+0x36/0x40 [ 391.465333][ T1969] _printk+0xc7/0x100 [ 391.465336][ T1969] ? snapshot_read.cold+0x21/0x21 [ 391.465338][ T1969] ? ipv6_mc_down+0x17e/0x2d0 [ 391.465343][ T1969] br_set_state+0x22f/0x430 [bridge] [ 391.465355][ T1969] br_stp_disable_port+0x11f/0x390 [bridge] [ 391.465364][ T1969] br_device_event+0x7b5/0x8f0 [bridge] [ 391.465373][ T1969] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 391.465376][ T1969] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 391.465378][ T1969] ? addrconf_notify+0x2c8/0xf30 [ 391.465381][ T1969] notifier_call_chain+0xae/0x300 [ 391.465384][ T1969] __dev_notify_flags+0x23c/0x3c0 [ 391.465388][ T1969] ? netif_change_name+0x8c0/0x8c0 [ 391.465390][ T1969] ? netif_set_allmulti+0x250/0x250 [ 391.465392][ T1969] ? __kernel_text_address+0x12/0x30 [ 391.465397][ T1969] ? write_profile+0xf0/0xf0 [ 391.465399][ T1969] ? unwind_get_return_address+0x67/0xd0 [ 391.465402][ T1969] netif_change_flags+0xfe/0x190 [ 391.465405][ T1969] do_setlink.isra.0+0x1cc3/0x2750 [ 391.465407][ T1969] ? stack_trace_save+0x93/0xc0 [ 391.465409][ T1969] ? rtnl_link_get_size+0x350/0x350 [ 391.465410][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465412][ T1969] ? stack_depot_save_flags+0x1bc/0x790 [ 391.465415][ T1969] ? kasan_save_stack+0x3d/0x50 [ 391.465418][ T1969] ? kasan_save_stack+0x2f/0x50 [ 391.465420][ T1969] ? kasan_save_track+0x14/0x30 [ 391.465421][ T1969] ? __kasan_kmalloc+0x7b/0x90 [ 391.465423][ T1969] ? __kmalloc_cache_noprof+0x29a/0x620 [ 391.465424][ T1969] ? rtnl_newlink+0x124/0x11c0 [ 391.465425][ T1969] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 391.465426][ T1969] ? netlink_rcv_skb+0x14e/0x3a0 [ 391.465429][ T1969] ? netlink_unicast+0x486/0x750 [ 391.465430][ T1969] ? netlink_sendmsg+0x735/0xc60 [ 391.465431][ T1969] ? ____sys_sendmsg+0x415/0x880 [ 391.465434][ T1969] ? ___sys_sendmsg+0x14e/0x1d0 [ 391.465435][ T1969] ? __sys_sendmsg+0x12c/0x1d0 [ 391.465437][ T1969] ? do_syscall_64+0xff/0x530 [ 391.465439][ T1969] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 391.465441][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465443][ T1969] ? lock_acquire+0x13c/0x160 [ 391.465445][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465446][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465448][ T1969] ? trace_contention_end+0xb3/0x180 [ 391.465451][ T1969] ? __mutex_lock+0x1db/0x1ea0 [ 391.465452][ T1969] ? __mutex_lock+0x9a3/0x1ea0 [ 391.465453][ T1969] ? rtnl_newlink+0x9a8/0x11c0 [ 391.465455][ T1969] ? ww_mutex_lock+0x160/0x160 [ 391.465457][ T1969] ? __rtnl_newlink+0x3fa/0xa50 [ 391.465459][ T1969] rtnl_newlink+0x9f1/0x11c0 [ 391.465461][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465463][ T1969] ? rtnl_bridge_getlink+0x850/0x850 [ 391.465464][ T1969] ? lock_acquire+0x13c/0x160 [ 391.465465][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465473][ T1969] ? is_bpf_text_address+0x3e/0x110 [ 391.465476][ T1969] ? is_bpf_text_address+0x72/0x110 [ 391.465478][ T1969] ? kernel_text_address+0x149/0x170 [ 391.465479][ T1969] ? __kernel_text_address+0x12/0x30 [ 391.465480][ T1969] ? write_profile+0xf0/0xf0 [ 391.465481][ T1969] ? unwind_get_return_address+0x67/0xd0 [ 391.465483][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465484][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465486][ T1969] ? cap_capable+0x1d7/0x3d0 [ 391.465489][ T1969] ? lock_acquire+0x13c/0x160 [ 391.465491][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465492][ T1969] ? rtnl_bridge_getlink+0x850/0x850 [ 391.465494][ T1969] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 391.465495][ T1969] ? rtnl_link_fill+0x920/0x920 [ 391.465497][ T1969] ? filter_irq_stacks+0xd0/0xd0 [ 391.465498][ T1969] ? stack_depot_save_flags+0x1bc/0x790 [ 391.465500][ T1969] netlink_rcv_skb+0x14e/0x3a0 [ 391.465502][ T1969] ? rtnl_link_fill+0x920/0x920 [ 391.465503][ T1969] ? lock_release+0x184/0x1f0 [ 391.465505][ T1969] ? netlink_ack+0xcf0/0xcf0 [ 391.465506][ T1969] ? __netlink_lookup+0xb9/0x6b0 [ 391.465508][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465510][ T1969] ? lock_release+0x184/0x1f0 [ 391.465512][ T1969] netlink_unicast+0x486/0x750 [ 391.465514][ T1969] ? netlink_attachskb+0x810/0x810 [ 391.465515][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465517][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465519][ T1969] ? lock_acquire+0x13c/0x160 [ 391.465521][ T1969] netlink_sendmsg+0x735/0xc60 [ 391.465522][ T1969] ? netlink_unicast+0x750/0x750 [ 391.465524][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465526][ T1969] ? lock_release+0x184/0x1f0 [ 391.465528][ T1969] ____sys_sendmsg+0x415/0x880 [ 391.465529][ T1969] ? copy_msghdr_from_user+0x279/0x420 [ 391.465531][ T1969] ? get_timestamp.constprop.0+0x390/0x390 [ 391.465533][ T1969] ? move_addr_to_kernel+0x40/0x40 [ 391.465534][ T1969] ? filter_irq_stacks+0xd0/0xd0 [ 391.465537][ T1969] ___sys_sendmsg+0x14e/0x1d0 [ 391.465538][ T1969] ? copy_msghdr_from_user+0x420/0x420 [ 391.465543][ T1969] __sys_sendmsg+0x12c/0x1d0 [ 391.465545][ T1969] ? __sys_sendmsg_sock+0x20/0x20 [ 391.465548][ T1969] ? rcu_is_watching+0x16/0xd0 [ 391.465550][ T1969] do_syscall_64+0xff/0x530 [ 391.465551][ T1969] ? exc_page_fault+0xee/0x100 [ 391.465553][ T1969] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 391.465555][ T1969] RIP: 0033:0x7f3ab5a1a54e [ 391.465557][ T1969] 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 [ 391.465558][ T1969] RSP: 002b:00007fff644bc590 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 391.465562][ T1969] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f3ab5a1a54e [ 391.465563][ T1969] RDX: 0000000000000000 RSI: 00007fff644bc640 RDI: 0000000000000005 [ 391.465564][ T1969] RBP: 00007fff644bc5a0 R08: 0000000000000000 R09: 0000000000000000 [ 391.465565][ T1969] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910861 [ 391.465566][ T1969] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff644bcd00 [ 391.465568][ T1969] [ 391.504184][ T1969] ip (1969) used greatest stack depth: 23016 bytes left [ 391.530120][ T1971] br12: port 1(veth1.12) entered disabled state [ 391.576819][ T1975] veth2.12: left allmulticast mode [ 391.576997][ T1975] veth2.12: left promiscuous mode [ 391.577289][ T1975] br12: port 2(veth2.12) entered disabled state [ 391.594885][ T1977] veth1.12: left allmulticast mode [ 391.595048][ T1977] veth1.12: left promiscuous mode [ 391.595325][ T1977] br12: port 1(veth1.12) entered disabled state [ 391.742218][ T1988] br11: port 2(veth2.11) entered disabled state [ 391.761176][ T1990] br11: port 1(veth1.11) entered disabled state [ 391.806052][ T1995] veth2.11: left allmulticast mode [ 391.806220][ T1995] veth2.11: left promiscuous mode [ 391.806549][ T1995] br11: port 2(veth2.11) entered disabled state [ 391.829360][ T1997] veth1.11: left allmulticast mode [ 391.829992][ T1997] veth1.11: left promiscuous mode [ 391.830284][ T1997] br11: port 1(veth1.11) entered disabled state [ 391.945580][ T2005] br10: port 2(veth2.10) entered disabled state [ 391.962945][ T2007] br10: port 1(veth1.10) entered disabled state [ 392.005331][ T2011] veth2.10: left allmulticast mode [ 392.005527][ T2011] veth2: left allmulticast mode [ 392.005704][ T2011] veth2.10: left promiscuous mode [ 392.005846][ T2011] veth2: left promiscuous mode [ 392.006148][ T2011] br10: port 2(veth2.10) entered disabled state [ 392.024231][ T2013] veth1.10: left allmulticast mode [ 392.024443][ T2013] veth1: left allmulticast mode [ 392.024651][ T2013] veth1.10: left promiscuous mode [ 392.024837][ T2013] veth1: left promiscuous mode [ 392.025174][ T2013] br10: port 1(veth1.10) entered disabled state