[ 11.805185][ T263] ip (263) used greatest stack depth: 24320 bytes left [ 11.805204][ T263] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.805206][ T263] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 263, name: ip [ 11.805207][ T263] preempt_count: 2, expected: 0 [ 11.805208][ T263] RCU nest depth: 0, expected: 0 [ 11.805209][ T263] locks held by ip/263: 5, last CPU#0: [ 11.805212][ T263] #0: ffffffff896027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 11.805224][ T263] #1: ffffffff89769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.805230][ T263] #2: ffffffff89769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.805234][ T263] #3: ffffffff89689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.805238][ T263] #4: ffffffff89689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.805242][ T263] irq event stamp: 27108 [ 11.805243][ T263] hardirqs last enabled at (27107): [] __down_trylock_console_sem+0x86/0xa0 [ 11.805245][ T263] hardirqs last disabled at (27108): [] console_emit_next_record+0x3d4/0x4c0 [ 11.805248][ T263] softirqs last enabled at (26820): [] handle_softirqs+0x67c/0x900 [ 11.805250][ T263] softirqs last disabled at (26815): [] __irq_exit_rcu+0x145/0x1c0 [ 11.805252][ T263] Preemption disabled at: [ 11.805252][ T263] [<0000000000000000>] 0x0 [ 11.805259][ T263] CPU: 0 UID: 0 PID: 263 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.805263][ T263] Tainted: [W]=WARN [ 11.805264][ T263] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.805265][ T263] Call Trace: [ 11.805267][ T263] [ 11.805269][ T263] dump_stack_lvl+0x6f/0xa0 [ 11.805275][ T263] __might_resched.cold+0x1fe/0x2c1 [ 11.805279][ T263] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.805283][ T263] ? __kmalloc_noprof+0xdb/0x760 [ 11.805289][ T263] __kmalloc_noprof+0x443/0x760 [ 11.805291][ T263] ? alloc_buf.isra.0+0x4b/0x260 [ 11.805297][ T263] ? do_raw_spin_unlock+0x59/0x250 [ 11.805300][ T263] alloc_buf.isra.0+0x4b/0x260 [ 11.805303][ T263] put_chars+0x1e1/0x2f0 [ 11.805306][ T263] ? __send_to_port+0x420/0x420 [ 11.805307][ T263] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.805312][ T263] ? validate_chain+0x38b/0xc20 [ 11.805317][ T263] hvc_console_print+0x292/0x780 [ 11.805323][ T263] ? hvc_write+0x3a0/0x3a0 [ 11.805326][ T263] ? rcu_is_watching+0x16/0xd0 [ 11.805328][ T263] ? lock_acquire+0x13c/0x160 [ 11.805332][ T263] console_emit_next_record+0x22f/0x4c0 [ 11.805336][ T263] ? devkmsg_read+0x4b0/0x4b0 [ 11.805338][ T263] ? console_flush_one_record+0x106/0x710 [ 11.805341][ T263] ? rcu_is_watching+0x16/0xd0 [ 11.805344][ T263] ? lock_acquire+0x13c/0x160 [ 11.805348][ T263] console_flush_one_record+0x46f/0x710 [ 11.805352][ T263] ? console_emit_next_record+0x4c0/0x4c0 [ 11.805354][ T263] ? __lock_acquire+0x518/0xc20 [ 11.805359][ T263] console_unlock+0xee/0x1f0 [ 11.805362][ T263] ? console_flush_one_record+0x710/0x710 [ 11.805364][ T263] ? rcu_is_watching+0x16/0xd0 [ 11.805366][ T263] ? lock_acquire+0x60/0x160 [ 11.805370][ T263] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.805372][ T263] ? vprintk_emit+0x320/0x3e0 [ 11.805375][ T263] vprintk_emit+0x37c/0x3e0 [ 11.805379][ T263] ? wake_up_klogd_work_func+0x90/0x90 [ 11.805383][ T263] ? __lock_acquire+0x518/0xc20 [ 11.805386][ T263] _printk+0xc7/0x100 [ 11.805390][ T263] ? snapshot_read.cold+0x21/0x21 [ 11.805393][ T263] ? do_raw_spin_lock+0x131/0x280 [ 11.805396][ T263] ? __rwlock_init+0x150/0x150 [ 11.805400][ T263] ? do_raw_spin_lock+0x131/0x280 [ 11.805402][ T263] do_exit.cold+0x82/0x9c [ 11.805406][ T263] ? exit_notify+0x890/0x890 [ 11.805408][ T263] ? __lock_release.isra.0+0x69/0x1a0 [ 11.805411][ T263] ? rcu_is_watching+0x16/0xd0 [ 11.805415][ T263] do_group_exit+0xb8/0x370 [ 11.805418][ T263] __x64_sys_exit_group+0x3c/0x50 [ 11.805420][ T263] x64_sys_call+0x1567/0x1570 [ 11.805422][ T263] do_syscall_64+0xff/0x530 [ 11.805425][ T263] ? exc_page_fault+0xee/0x100 [ 11.805428][ T263] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.805431][ T263] RIP: 0033:0x7f90a6bc21b8 [ 11.805433][ T263] Code: Unable to access opcode bytes at 0x7f90a6bc218e. [ 11.805434][ T263] RSP: 002b:00007ffe925c5968 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 11.805437][ T263] RAX: ffffffffffffffda RBX: 00007f90a6cf2f88 RCX: 00007f90a6bc21b8 [ 11.805438][ T263] RDX: 00007f90a690cfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 11.805439][ T263] RBP: 00007ffe925c59c0 R08: 0000000000000000 R09: 0000000000000050 [ 11.805440][ T263] R10: 00007ffe925c5780 R11: 0000000000000246 R12: 0000000000000001 [ 11.805441][ T263] R13: 0000000000000000 R14: 00007f90a6cf1680 R15: 00007f90a6cf2fa0 [ 11.805448][ T263] [ 11.846534][ T265] br1: port 1(vx10) entered blocking state [ 11.847275][ T265] br1: port 1(vx10) entered disabled state [ 11.847784][ T265] vx10: entered allmulticast mode [ 11.849493][ T265] vx10: entered promiscuous mode [ 11.850098][ T265] br1: port 1(vx10) entered blocking state [ 11.850588][ T265] br1: port 1(vx10) entered forwarding state [ 12.005305][ T270] br1: port 2(vx20) entered blocking state [ 12.005659][ T270] br1: port 2(vx20) entered disabled state [ 12.006003][ T270] vx20: entered allmulticast mode [ 12.015642][ T270] vx20: entered promiscuous mode [ 12.016163][ T270] br1: port 2(vx20) entered blocking state [ 12.016463][ T270] br1: port 2(vx20) entered forwarding state [ 12.080185][ T272] br1: port 3(veth1) entered blocking state [ 12.080548][ T272] br1: port 3(veth1) entered disabled state [ 12.080877][ T272] veth1: entered allmulticast mode [ 12.082428][ T272] veth1: entered promiscuous mode [ 12.109168][ T38] br1: port 3(veth1) entered blocking state [ 12.109922][ T38] br1: port 3(veth1) entered forwarding state [ 12.141200][ T274] br1: port 4(veth2) entered blocking state [ 12.141546][ T274] br1: port 4(veth2) entered disabled state [ 12.141870][ T274] veth2: entered allmulticast mode [ 12.143451][ T274] veth2: entered promiscuous mode [ 12.175516][ T38] br1: port 4(veth2) entered blocking state [ 12.175887][ T38] br1: port 4(veth2) entered forwarding state [ 12.300891][ T279] br1: port 5(vx4001) entered blocking state [ 12.301217][ T279] br1: port 5(vx4001) entered disabled state [ 12.301526][ T279] vx4001: entered allmulticast mode [ 12.303276][ T279] vx4001: entered promiscuous mode [ 12.303987][ T279] br1: port 5(vx4001) entered blocking state [ 12.304274][ T279] br1: port 5(vx4001) entered forwarding state [ 12.559757][ T288] 8021q: 802.1Q VLAN Support v1.8 [ 12.722531][ T65] br1: entered promiscuous mode [ 14.844907][ T370] br1: port 1(vx10) entered blocking state [ 14.844925][ T370] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.844928][ T370] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 370, name: ip [ 14.844929][ T370] preempt_count: 1, expected: 0 [ 14.844930][ T370] RCU nest depth: 0, expected: 0 [ 14.844931][ T370] locks held by ip/370: 5, last CPU#2: [ 14.844933][ T370] #0: ffffffff89ed2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 14.844946][ T370] #1: ffffffff89769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.844952][ T370] #2: ffffffff89769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.844956][ T370] #3: ffffffff89689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.844960][ T370] #4: ffffffff89689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.844963][ T370] irq event stamp: 16832 [ 14.844964][ T370] hardirqs last enabled at (16831): [] __down_trylock_console_sem+0x86/0xa0 [ 14.844967][ T370] hardirqs last disabled at (16832): [] console_emit_next_record+0x3d4/0x4c0 [ 14.844969][ T370] softirqs last enabled at (16788): [] handle_softirqs+0x67c/0x900 [ 14.844972][ T370] softirqs last disabled at (16567): [] __irq_exit_rcu+0x145/0x1c0 [ 14.844974][ T370] Preemption disabled at: [ 14.844975][ T370] [] vprintk_emit+0x31b/0x3e0 [ 14.844981][ T370] CPU: 2 UID: 0 PID: 370 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.844984][ T370] Tainted: [W]=WARN [ 14.844985][ T370] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.844987][ T370] Call Trace: [ 14.844989][ T370] [ 14.844990][ T370] dump_stack_lvl+0x6f/0xa0 [ 14.844996][ T370] ? vprintk_emit+0x31b/0x3e0 [ 14.844998][ T370] __might_resched.cold+0x1fe/0x2c1 [ 14.845003][ T370] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.845007][ T370] ? __kmalloc_noprof+0xdb/0x760 [ 14.845012][ T370] __kmalloc_noprof+0x443/0x760 [ 14.845014][ T370] ? alloc_buf.isra.0+0x4b/0x260 [ 14.845020][ T370] ? do_raw_spin_unlock+0x59/0x250 [ 14.845023][ T370] alloc_buf.isra.0+0x4b/0x260 [ 14.845026][ T370] put_chars+0x1e1/0x2f0 [ 14.845028][ T370] ? prb_final_commit+0x50/0x50 [ 14.845030][ T370] ? __send_to_port+0x420/0x420 [ 14.845033][ T370] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.845037][ T370] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.845040][ T370] ? validate_chain+0x38b/0xc20 [ 14.845043][ T370] hvc_console_print+0x292/0x780 [ 14.845046][ T370] ? mark_usage+0x61/0x170 [ 14.845048][ T370] ? __lock_acquire+0x518/0xc20 [ 14.845049][ T370] ? __lock_acquire+0x518/0xc20 [ 14.845053][ T370] ? hvc_write+0x3a0/0x3a0 [ 14.845055][ T370] ? console_emit_next_record+0x1df/0x4c0 [ 14.845058][ T370] ? rcu_is_watching+0x16/0xd0 [ 14.845060][ T370] ? lock_acquire+0x13c/0x160 [ 14.845064][ T370] console_emit_next_record+0x22f/0x4c0 [ 14.845068][ T370] ? devkmsg_read+0x4b0/0x4b0 [ 14.845070][ T370] ? console_flush_one_record+0x106/0x710 [ 14.845073][ T370] ? rcu_is_watching+0x16/0xd0 [ 14.845075][ T370] ? lock_acquire+0x13c/0x160 [ 14.845079][ T370] console_flush_one_record+0x46f/0x710 [ 14.845083][ T370] ? console_emit_next_record+0x4c0/0x4c0 [ 14.845085][ T370] ? __lock_acquire+0x518/0xc20 [ 14.845090][ T370] console_unlock+0xee/0x1f0 [ 14.845092][ T370] ? console_flush_one_record+0x710/0x710 [ 14.845094][ T370] ? rcu_is_watching+0x16/0xd0 [ 14.845096][ T370] ? lock_acquire+0x60/0x160 [ 14.845100][ T370] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.845101][ T370] ? vprintk_emit+0x320/0x3e0 [ 14.845105][ T370] vprintk_emit+0x37c/0x3e0 [ 14.845109][ T370] ? wake_up_klogd_work_func+0x90/0x90 [ 14.845111][ T370] ? __lock_release.isra.0+0x69/0x1a0 [ 14.845114][ T370] ? mark_held_locks+0x40/0x70 [ 14.845117][ T370] _printk+0xc7/0x100 [ 14.845121][ T370] ? snapshot_read.cold+0x21/0x21 [ 14.845125][ T370] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 14.845138][ T370] ? do_setlink.isra.0+0xa31/0x2750 [ 14.845139][ T370] ? rtnl_newlink+0x9f1/0x11c0 [ 14.845140][ T370] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.845144][ T370] br_set_state+0x22f/0x430 [bridge] [ 14.845155][ T370] br_init_port+0xc4/0x200 [bridge] [ 14.845163][ T370] new_nbp+0x39c/0x580 [bridge] [ 14.845171][ T370] br_add_if+0x212/0x1320 [bridge] [ 14.845178][ T370] ? is_bpf_text_address+0x72/0x110 [ 14.845182][ T370] ? kernel_text_address+0x149/0x170 [ 14.845185][ T370] ? __kernel_text_address+0x12/0x30 [ 14.845189][ T370] do_set_master+0x357/0x580 [ 14.845193][ T370] do_setlink.isra.0+0xa31/0x2750 [ 14.845197][ T370] ? stack_trace_save+0x93/0xc0 [ 14.845200][ T370] ? rtnl_link_get_size+0x350/0x350 [ 14.845201][ T370] ? rcu_read_lock_any_held+0x66/0x90 [ 14.845203][ T370] ? stack_depot_save_flags+0x38e/0x790 [ 14.845206][ T370] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.845208][ T370] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.845210][ T370] ? validate_chain+0x38b/0xc20 [ 14.845213][ T370] ? kasan_save_stack+0x3d/0x50 [ 14.845216][ T370] ? kasan_save_stack+0x2f/0x50 [ 14.845217][ T370] ? kasan_save_track+0x14/0x30 [ 14.845220][ T370] ? __lock_acquire+0x518/0xc20 [ 14.845222][ T370] ? netlink_seq_next+0xe/0x60 [ 14.845225][ T370] ? ___sys_sendmsg+0xb0/0x1d0 [ 14.845230][ T370] ? lock_acquire.part.0+0xd4/0x280 [ 14.845231][ T370] ? rtnl_newlink+0x9a8/0x11c0 [ 14.845234][ T370] ? rcu_is_watching+0x16/0xd0 [ 14.845236][ T370] ? lock_acquire+0x13c/0x160 [ 14.845238][ T370] ? rcu_is_watching+0x16/0xd0 [ 14.845239][ T370] ? rcu_is_watching+0x16/0xd0 [ 14.845242][ T370] ? trace_contention_end+0xb3/0x180 [ 14.845245][ T370] ? __mutex_lock+0x1db/0x1ea0 [ 14.845248][ T370] ? __mutex_lock+0x9a3/0x1ea0 [ 14.845250][ T370] ? rtnl_newlink+0x9a8/0x11c0 [ 14.845254][ T370] ? ww_mutex_lock+0x160/0x160 [ 14.845255][ T370] ? nla_get_range_signed+0x3d0/0x3d0 [ 14.845260][ T370] ? __rtnl_newlink+0x3fa/0xa50 [ 14.845265][ T370] rtnl_newlink+0x9f1/0x11c0 [ 14.845270][ T370] ? rtnl_bridge_getlink+0x850/0x850 [ 14.845272][ T370] ? __lock_acquire+0x518/0xc20 [ 14.845276][ T370] ? lock_acquire.part.0+0xd4/0x280 [ 14.845278][ T370] ? find_held_lock+0x2b/0x80 [ 14.845280][ T370] ? rtnl_bridge_getlink+0x850/0x850 [ 14.845282][ T370] ? __lock_release.isra.0+0x69/0x1a0 [ 14.845285][ T370] ? rtnl_bridge_getlink+0x850/0x850 [ 14.845287][ T370] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.845290][ T370] ? rtnl_link_fill+0x920/0x920 [ 14.845292][ T370] ? __lock_acquire+0x518/0xc20 [ 14.845296][ T370] ? lock_acquire.part.0+0xd4/0x280 [ 14.845298][ T370] ? find_held_lock+0x2b/0x80 [ 14.845301][ T370] netlink_rcv_skb+0x14e/0x3a0 [ 14.845303][ T370] ? rtnl_link_fill+0x920/0x920 [ 14.845306][ T370] ? netlink_ack+0xcf0/0xcf0 [ 14.845312][ T370] ? netlink_deliver_tap+0xc5/0x330 [ 14.845313][ T370] ? netlink_deliver_tap+0x13c/0x330 [ 14.845317][ T370] netlink_unicast+0x486/0x750 [ 14.845321][ T370] ? netlink_attachskb+0x810/0x810 [ 14.845324][ T370] ? __lock_acquire+0x518/0xc20 [ 14.845328][ T370] netlink_sendmsg+0x735/0xc60 [ 14.845332][ T370] ? netlink_unicast+0x750/0x750 [ 14.845335][ T370] ? __might_fault+0x97/0x140 [ 14.845340][ T370] ____sys_sendmsg+0x415/0x880 [ 14.845343][ T370] ? copy_msghdr_from_user+0x279/0x420 [ 14.845345][ T370] ? get_timestamp.constprop.0+0x390/0x390 [ 14.845347][ T370] ? move_addr_to_kernel+0x40/0x40 [ 14.845354][ T370] ___sys_sendmsg+0x14e/0x1d0 [ 14.845356][ T370] ? copy_msghdr_from_user+0x420/0x420 [ 14.845372][ T370] __sys_sendmsg+0x12c/0x1d0 [ 14.845375][ T370] ? __sys_sendmsg_sock+0x20/0x20 [ 14.845381][ T370] ? rcu_is_watching+0x16/0xd0 [ 14.845384][ T370] do_syscall_64+0xff/0x530 [ 14.845387][ T370] ? exc_page_fault+0xee/0x100 [ 14.845390][ T370] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.845392][ T370] RIP: 0033:0x7fddf81a754e [ 14.845395][ T370] 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.845397][ T370] RSP: 002b:00007ffd6bbc0ff0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 14.845400][ T370] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fddf81a754e [ 14.845402][ T370] RDX: 0000000000000000 RSI: 00007ffd6bbc10a0 RDI: 0000000000000005 [ 14.845402][ T370] RBP: 00007ffd6bbc1000 R08: 0000000000000000 R09: 0000000000000000 [ 14.845403][ T370] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a918d93 [ 14.845404][ T370] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd6bbc1760 [ 14.845411][ T370] [ 14.901008][ T370] br1: port 1(vx10) entered disabled state [ 14.901473][ T370] vx10: entered allmulticast mode [ 14.903107][ T370] vx10: entered promiscuous mode [ 14.904095][ T370] br1: port 1(vx10) entered blocking state [ 14.904533][ T370] br1: port 1(vx10) entered forwarding state [ 14.913822][ T370] ip (370) used greatest stack depth: 23336 bytes left [ 15.020146][ T374] br1: port 2(vx20) entered blocking state [ 15.020473][ T374] br1: port 2(vx20) entered disabled state [ 15.020956][ T374] vx20: entered allmulticast mode [ 15.022824][ T374] vx20: entered promiscuous mode [ 15.023275][ T374] br1: port 2(vx20) entered blocking state [ 15.023579][ T374] br1: port 2(vx20) entered forwarding state [ 15.138203][ T378] br1: port 3(vx4001) entered blocking state [ 15.138545][ T378] br1: port 3(vx4001) entered disabled state [ 15.138874][ T378] vx4001: entered allmulticast mode [ 15.140412][ T378] vx4001: entered promiscuous mode [ 15.140864][ T378] br1: port 3(vx4001) entered blocking state [ 15.141153][ T378] br1: port 3(vx4001) entered forwarding state [ 15.196656][ T380] br1: port 4(w1) entered blocking state [ 15.196928][ T380] br1: port 4(w1) entered disabled state [ 15.197181][ T380] w1: entered allmulticast mode [ 15.198748][ T380] w1: entered promiscuous mode [ 15.237102][ T46] br1: port 4(w1) entered blocking state [ 15.237374][ T46] br1: port 4(w1) entered forwarding state [ 15.283354][ T383] br1: port 5(w3) entered blocking state [ 15.283635][ T383] br1: port 5(w3) entered disabled state [ 15.283897][ T383] w3: entered allmulticast mode [ 15.285445][ T383] w3: entered promiscuous mode [ 15.325547][ T46] br1: port 5(w3) entered blocking state [ 15.325816][ T46] br1: port 5(w3) entered forwarding state [ 15.600846][ T72] br1: entered promiscuous mode [ 16.847671][ C3] br1: received packet on vx10 with own address as source address (addr:00:00:5e:00:01:01, vlan:10) [ 16.847705][ C3] [ 16.847706][ C3] ======================================================== [ 16.847707][ C3] WARNING: possible irq lock inversion dependency detected [ 16.847709][ C3] 7.2.0-virtme #1 Tainted: G W [ 16.847711][ C3] -------------------------------------------------------- [ 16.847711][ C3] swapper/3/0 just changed the state of lock: [ 16.847715][ C3] ffffffff89689660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 16.847727][ C3] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 16.847728][ C3] (fs_reclaim){+.+.}-{0:0} [ 16.847730][ C3] [ 16.847730][ C3] [ 16.847730][ C3] and interrupts could create inverse lock ordering between them. [ 16.847730][ C3] [ 16.847731][ C3] [ 16.847731][ C3] other info that might help us debug this: [ 16.847731][ C3] Possible interrupt unsafe locking scenario: [ 16.847731][ C3] [ 16.847732][ C3] CPU0 CPU1 [ 16.847732][ C3] ---- ---- [ 16.847733][ C3] lock(fs_reclaim); [ 16.847733][ C3] local_irq_disable(); [ 16.847734][ C3] lock(console_owner); [ 16.847735][ C3] lock(fs_reclaim); [ 16.847736][ C3] [ 16.847736][ C3] lock(console_owner); [ 16.847737][ C3] [ 16.847737][ C3] *** DEADLOCK *** [ 16.847737][ C3] [ 16.847737][ C3] locks held by swapper/3/0: 3, last CPU#3: [ 16.847738][ C3] #0: ffffffff89780b00 (rcu_read_lock){....}-{1:3}, at: netif_receive_skb_list_internal+0x2f8/0xe20 [ 16.847745][ C3] #1: ffffffff89769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 16.847748][ C3] #2: ffffffff89769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 16.847751][ C3] [ 16.847751][ C3] the shortest dependencies between 2nd lock and 1st lock: [ 16.847756][ C3] -> (fs_reclaim){+.+.}-{0:0} { [ 16.847758][ C3] HARDIRQ-ON-W at: [ 16.847760][ C3] __lock_acquire+0x388/0xc20 [ 16.847762][ C3] lock_acquire.part.0+0xd4/0x280 [ 16.847764][ C3] fs_reclaim_acquire+0xd5/0x120 [ 16.847767][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 16.847769][ C3] kthread_create_worker_on_node+0xea/0x210 [ 16.847772][ C3] workqueue_init+0x2a/0x680 [ 16.847775][ C3] kernel_init_freeable+0x2fe/0x630 [ 16.847778][ C3] kernel_init+0x21/0x150 [ 16.847781][ C3] ret_from_fork+0x474/0x6b0 [ 16.847784][ C3] ret_from_fork_asm+0x11/0x20 [ 16.847787][ C3] SOFTIRQ-ON-W at: [ 16.847788][ C3] __lock_acquire+0x388/0xc20 [ 16.847789][ C3] lock_acquire.part.0+0xd4/0x280 [ 16.847790][ C3] fs_reclaim_acquire+0xd5/0x120 [ 16.847791][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 16.847793][ C3] kthread_create_worker_on_node+0xea/0x210 [ 16.847794][ C3] workqueue_init+0x2a/0x680 [ 16.847795][ C3] kernel_init_freeable+0x2fe/0x630 [ 16.847796][ C3] kernel_init+0x21/0x150 [ 16.847798][ C3] ret_from_fork+0x474/0x6b0 [ 16.847799][ C3] ret_from_fork_asm+0x11/0x20 [ 16.847800][ C3] INITIAL USE at: [ 16.847801][ C3] __lock_acquire+0x388/0xc20 [ 16.847802][ C3] lock_acquire.part.0+0xd4/0x280 [ 16.847804][ C3] fs_reclaim_acquire+0xd5/0x120 [ 16.847805][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 16.847806][ C3] kthread_create_worker_on_node+0xea/0x210 [ 16.847807][ C3] workqueue_init+0x2a/0x680 [ 16.847808][ C3] kernel_init_freeable+0x2fe/0x630 [ 16.847809][ C3] kernel_init+0x21/0x150 [ 16.847811][ C3] ret_from_fork+0x474/0x6b0 [ 16.847812][ C3] ret_from_fork_asm+0x11/0x20 [ 16.847813][ C3] } [ 16.847814][ C3] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 16.847817][ C3] ... acquired at: [ 16.847818][ C3] __lock_acquire+0x518/0xc20 [ 16.847820][ C3] lock_acquire.part.0+0xd4/0x280 [ 16.847821][ C3] fs_reclaim_acquire+0xd5/0x120 [ 16.847822][ C3] __kmalloc_noprof+0xd3/0x760 [ 16.847823][ C3] alloc_buf.isra.0+0x4b/0x260 [ 16.847826][ C3] put_chars+0x1e1/0x2f0 [ 16.847827][ C3] hvc_console_print+0x292/0x780 [ 16.847830][ C3] console_emit_next_record+0x22f/0x4c0 [ 16.847831][ C3] console_flush_one_record+0x46f/0x710 [ 16.847833][ C3] console_unlock+0xee/0x1f0 [ 16.847834][ C3] vprintk_emit+0x37c/0x3e0 [ 16.847836][ C3] dev_vprintk_emit+0x27f/0x2c0 [ 16.847839][ C3] dev_printk_emit+0xb9/0xee [ 16.847841][ C3] _dev_info+0xe2/0x116 [ 16.847842][ C3] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 16.847845][ C3] cmos_do_probe+0x73b/0x98a [ 16.847847][ C3] platform_probe+0xfe/0x1f0 [ 16.847850][ C3] call_driver_probe+0x61/0x1c0 [ 16.847851][ C3] really_probe+0x199/0x760 [ 16.847853][ C3] __driver_probe_device+0x24f/0x440 [ 16.847854][ C3] driver_probe_device+0x4a/0xf0 [ 16.847856][ C3] __driver_attach+0x1b8/0x540 [ 16.847857][ C3] bus_for_each_dev+0x130/0x1e0 [ 16.847858][ C3] bus_add_driver+0x2c8/0x530 [ 16.847860][ C3] driver_register+0x1a3/0x390 [ 16.847861][ C3] __platform_driver_probe+0x13f/0x270 [ 16.847863][ C3] cmos_init+0x31/0x40 [ 16.847866][ C3] do_one_initcall+0x124/0x4f0 [ 16.847867][ C3] kernel_init_freeable+0x596/0x630 [ 16.847868][ C3] kernel_init+0x21/0x150 [ 16.847870][ C3] ret_from_fork+0x474/0x6b0 [ 16.847871][ C3] ret_from_fork_asm+0x11/0x20 [ 16.847872][ C3] [ 16.847872][ C3] -> (console_owner){..-.}-{0:0} { [ 16.847874][ C3] IN-SOFTIRQ-W at: [ 16.847875][ C3] __lock_acquire+0x388/0xc20 [ 16.847876][ C3] lock_acquire.part.0+0xd4/0x280 [ 16.847877][ C3] console_lock_spinning_enable+0x5c/0x60 [ 16.847879][ C3] console_emit_next_record+0x1d1/0x4c0 [ 16.847880][ C3] console_flush_one_record+0x46f/0x710 [ 16.847882][ C3] console_unlock+0xee/0x1f0 [ 16.847883][ C3] vprintk_emit+0x37c/0x3e0 [ 16.847885][ C3] _printk+0xc7/0x100 [ 16.847887][ C3] br_fdb_update.cold+0x74/0x90 [bridge] [ 16.847898][ C3] br_handle_frame_finish+0x801/0x1d20 [bridge] [ 16.847908][ C3] br_handle_frame+0x74d/0xf50 [bridge] [ 16.847915][ C3] __netif_receive_skb_core.constprop.0+0x536/0x2bb0 [ 16.847917][ C3] __netif_receive_skb_list_core+0x2fc/0x9e0 [ 16.847919][ C3] netif_receive_skb_list_internal+0x5f8/0xe20 [ 16.847920][ C3] gro_receive_skb+0x52f/0xbb0 [ 16.847922][ C3] gro_cell_poll+0x275/0x550 [ 16.847926][ C3] __napi_poll+0xd4/0x3b0 [ 16.847927][ C3] net_rx_action+0x513/0xf50 [ 16.847929][ C3] handle_softirqs+0x1d3/0x900 [ 16.847931][ C3] __irq_exit_rcu+0x145/0x1c0 [ 16.847932][ C3] irq_exit_rcu+0xe/0x30 [ 16.847933][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 16.847935][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.847937][ C3] pv_native_safe_halt+0xf/0x10 [ 16.847939][ C3] default_idle+0x9/0x10 [ 16.847940][ C3] default_idle_call+0x6e/0xb0 [ 16.847942][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.847944][ C3] do_idle+0xd8/0x190 [ 16.847945][ C3] cpu_startup_entry+0x53/0x70 [ 16.847946][ C3] start_secondary+0x204/0x2b0 [ 16.847948][ C3] common_startup_64+0x13e/0x148 [ 16.847951][ C3] INITIAL USE at: [ 16.847952][ C3] } [ 16.847952][ C3] ... key at: [] console_owner_dep_map+0x0/0x60 [ 16.847955][ C3] ... acquired at: [ 16.847955][ C3] mark_lock+0x1d7/0xa00 [ 16.847957][ C3] mark_usage+0x42/0x170 [ 16.847958][ C3] __lock_acquire+0x388/0xc20 [ 16.847959][ C3] lock_acquire.part.0+0xd4/0x280 [ 16.847960][ C3] console_lock_spinning_enable+0x5c/0x60 [ 16.847962][ C3] console_emit_next_record+0x1d1/0x4c0 [ 16.847963][ C3] console_flush_one_record+0x46f/0x710 [ 16.847965][ C3] console_unlock+0xee/0x1f0 [ 16.847966][ C3] vprintk_emit+0x37c/0x3e0 [ 16.847968][ C3] _printk+0xc7/0x100 [ 16.847969][ C3] br_fdb_update.cold+0x74/0x90 [bridge] [ 16.847976][ C3] br_handle_frame_finish+0x801/0x1d20 [bridge] [ 16.847982][ C3] br_handle_frame+0x74d/0xf50 [bridge] [ 16.847988][ C3] __netif_receive_skb_core.constprop.0+0x536/0x2bb0 [ 16.847990][ C3] __netif_receive_skb_list_core+0x2fc/0x9e0 [ 16.847992][ C3] netif_receive_skb_list_internal+0x5f8/0xe20 [ 16.847993][ C3] gro_receive_skb+0x52f/0xbb0 [ 16.847994][ C3] gro_cell_poll+0x275/0x550 [ 16.847996][ C3] __napi_poll+0xd4/0x3b0 [ 16.847997][ C3] net_rx_action+0x513/0xf50 [ 16.847999][ C3] handle_softirqs+0x1d3/0x900 [ 16.848000][ C3] __irq_exit_rcu+0x145/0x1c0 [ 16.848001][ C3] irq_exit_rcu+0xe/0x30 [ 16.848002][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 16.848003][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.848005][ C3] pv_native_safe_halt+0xf/0x10 [ 16.848006][ C3] default_idle+0x9/0x10 [ 16.848007][ C3] default_idle_call+0x6e/0xb0 [ 16.848009][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.848010][ C3] do_idle+0xd8/0x190 [ 16.848011][ C3] cpu_startup_entry+0x53/0x70 [ 16.848012][ C3] start_secondary+0x204/0x2b0 [ 16.848013][ C3] common_startup_64+0x13e/0x148 [ 16.848014][ C3] [ 16.848015][ C3] [ 16.848015][ C3] stack backtrace: [ 16.848018][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.848021][ C3] Tainted: [W]=WARN [ 16.848022][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.848023][ C3] Call Trace: [ 16.848025][ C3] [ 16.848026][ C3] dump_stack_lvl+0x6f/0xa0 [ 16.848030][ C3] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 16.848032][ C3] mark_lock_irq+0x989/0x9c0 [ 16.848035][ C3] mark_lock+0x1d7/0xa00 [ 16.848037][ C3] mark_usage+0x42/0x170 [ 16.848039][ C3] __lock_acquire+0x388/0xc20 [ 16.848041][ C3] lock_acquire.part.0+0xd4/0x280 [ 16.848042][ C3] ? console_lock_spinning_enable+0x40/0x60 [ 16.848044][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848048][ C3] ? lock_acquire+0x13c/0x160 [ 16.848049][ C3] console_lock_spinning_enable+0x5c/0x60 [ 16.848051][ C3] ? console_lock_spinning_enable+0x40/0x60 [ 16.848053][ C3] console_emit_next_record+0x1d1/0x4c0 [ 16.848055][ C3] ? devkmsg_read+0x4b0/0x4b0 [ 16.848056][ C3] ? console_flush_one_record+0x106/0x710 [ 16.848058][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848060][ C3] ? lock_acquire+0x13c/0x160 [ 16.848062][ C3] console_flush_one_record+0x46f/0x710 [ 16.848064][ C3] ? console_emit_next_record+0x4c0/0x4c0 [ 16.848066][ C3] ? __lock_acquire+0x518/0xc20 [ 16.848068][ C3] console_unlock+0xee/0x1f0 [ 16.848070][ C3] ? console_flush_one_record+0x710/0x710 [ 16.848071][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848073][ C3] ? lock_acquire+0x60/0x160 [ 16.848075][ C3] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.848076][ C3] ? vprintk_emit+0x320/0x3e0 [ 16.848078][ C3] vprintk_emit+0x37c/0x3e0 [ 16.848080][ C3] ? wake_up_klogd_work_func+0x90/0x90 [ 16.848082][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848084][ C3] _printk+0xc7/0x100 [ 16.848086][ C3] ? snapshot_read.cold+0x21/0x21 [ 16.848088][ C3] ? ___ratelimit+0x4e7/0xa00 [ 16.848091][ C3] ? fdb_notify.isra.0+0x120/0x120 [bridge] [ 16.848097][ C3] ? idr_get_free+0x830/0x830 [ 16.848100][ C3] br_fdb_update.cold+0x74/0x90 [bridge] [ 16.848107][ C3] ? br_fdb_add_local+0x60/0x60 [bridge] [ 16.848113][ C3] ? __allowed_ingress+0x58c/0xcc0 [bridge] [ 16.848121][ C3] ? br_allowed_ingress+0x6c/0x150 [bridge] [ 16.848128][ C3] br_handle_frame_finish+0x801/0x1d20 [bridge] [ 16.848135][ C3] ? br_handle_local_finish+0x20/0x20 [bridge] [ 16.848141][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848143][ C3] ? vxlan_rcv+0x1192/0x2f70 [vxlan] [ 16.848149][ C3] br_handle_frame+0x74d/0xf50 [bridge] [ 16.848155][ C3] ? br_handle_frame_finish+0x1d20/0x1d20 [bridge] [ 16.848161][ C3] __netif_receive_skb_core.constprop.0+0x536/0x2bb0 [ 16.848164][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848166][ C3] ? do_xdp_generic+0x4a0/0x4a0 [ 16.848168][ C3] ? udpv6_queue_rcv_one_skb+0x66a/0xbf0 [ 16.848171][ C3] ? udp6_unicast_rcv_skb+0x2b3/0x390 [ 16.848172][ C3] ? ip6_protocol_deliver_rcu+0xa78/0x1480 [ 16.848175][ C3] ? find_held_lock+0x2b/0x80 [ 16.848177][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848179][ C3] __netif_receive_skb_list_core+0x2fc/0x9e0 [ 16.848181][ C3] ? __netif_receive_skb_core.constprop.0+0x2bb0/0x2bb0 [ 16.848183][ C3] ? lock_acquire.part.0+0xd4/0x280 [ 16.848184][ C3] ? netif_receive_skb_list_internal+0x2f8/0xe20 [ 16.848187][ C3] ? lock_acquire+0x13c/0x160 [ 16.848188][ C3] netif_receive_skb_list_internal+0x5f8/0xe20 [ 16.848190][ C3] ? find_held_lock+0x2b/0x80 [ 16.848192][ C3] ? process_backlog+0x14c0/0x14c0 [ 16.848193][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848194][ C3] ? validate_chain+0x731/0xc20 [ 16.848196][ C3] ? dev_gro_receive+0x8a5/0x1740 [ 16.848197][ C3] ? dev_gro_receive+0x1f8/0x1740 [ 16.848199][ C3] gro_receive_skb+0x52f/0xbb0 [ 16.848201][ C3] gro_cell_poll+0x275/0x550 [ 16.848203][ C3] __napi_poll+0xd4/0x3b0 [ 16.848205][ C3] net_rx_action+0x513/0xf50 [ 16.848208][ C3] ? __napi_poll+0x3b0/0x3b0 [ 16.848210][ C3] ? reacquire_held_locks+0x1d0/0x240 [ 16.848211][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848213][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.848215][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 16.848217][ C3] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 16.848219][ C3] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 16.848221][ C3] ? _raw_spin_unlock_irq+0x33/0x50 [ 16.848223][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848225][ C3] handle_softirqs+0x1d3/0x900 [ 16.848227][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848228][ C3] ? _local_bh_enable+0xc0/0xc0 [ 16.848230][ C3] __irq_exit_rcu+0x145/0x1c0 [ 16.848231][ C3] irq_exit_rcu+0xe/0x30 [ 16.848233][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 16.848234][ C3] [ 16.848235][ C3] [ 16.848235][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 16.848237][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.848239][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 16.848241][ C3] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 16.848243][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 16.848245][ C3] RAX: 00000000000bcbc3 RBX: ff11000001c88040 RCX: ffffffff864f0307 [ 16.848247][ C3] RDX: ff11000001c88040 RSI: ffffffff89238af6 RDI: ffffffff88c8d8e0 [ 16.848248][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 16.848249][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 16.848249][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 16.848251][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.848253][ C3] default_idle+0x9/0x10 [ 16.848254][ C3] default_idle_call+0x6e/0xb0 [ 16.848256][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.848258][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 16.848259][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 16.848261][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.848262][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848264][ C3] do_idle+0xd8/0x190 [ 16.848265][ C3] cpu_startup_entry+0x53/0x70 [ 16.848267][ C3] start_secondary+0x204/0x2b0 [ 16.848268][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 16.848270][ C3] common_startup_64+0x13e/0x148 [ 16.848273][ C3] [ 16.848277][ C3] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.848278][ C3] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/3 [ 16.848279][ C3] preempt_count: 102, expected: 0 [ 16.848280][ C3] RCU nest depth: 1, expected: 0 [ 16.848281][ C3] INFO: lockdep is turned off. [ 16.848282][ C3] irq event stamp: 775029 [ 16.848282][ C3] hardirqs last enabled at (775028): [] __down_trylock_console_sem+0x86/0xa0 [ 16.848284][ C3] hardirqs last disabled at (775029): [] console_emit_next_record+0x3d4/0x4c0 [ 16.848286][ C3] softirqs last enabled at (773054): [] handle_softirqs+0x67c/0x900 [ 16.848288][ C3] softirqs last disabled at (773061): [] __irq_exit_rcu+0x145/0x1c0 [ 16.848289][ C3] Preemption disabled at: [ 16.848290][ C3] [<0000000000000000>] 0x0 [ 16.848293][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.848295][ C3] Tainted: [W]=WARN [ 16.848296][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.848296][ C3] Call Trace: [ 16.848297][ C3] [ 16.848298][ C3] dump_stack_lvl+0x6f/0xa0 [ 16.848300][ C3] __might_resched.cold+0x1fe/0x2c1 [ 16.848303][ C3] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.848306][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848308][ C3] __kmalloc_noprof+0x443/0x760 [ 16.848310][ C3] ? __rwlock_init+0x150/0x150 [ 16.848311][ C3] ? alloc_buf.isra.0+0x4b/0x260 [ 16.848313][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 16.848314][ C3] alloc_buf.isra.0+0x4b/0x260 [ 16.848316][ C3] put_chars+0x1e1/0x2f0 [ 16.848318][ C3] ? __send_to_port+0x420/0x420 [ 16.848320][ C3] ? mark_lock+0x1d7/0xa00 [ 16.848322][ C3] hvc_console_print+0x292/0x780 [ 16.848323][ C3] ? __lock_acquire+0x388/0xc20 [ 16.848325][ C3] ? hvc_write+0x3a0/0x3a0 [ 16.848326][ C3] ? console_lock_spinning_enable+0x40/0x60 [ 16.848328][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848330][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848332][ C3] ? lock_acquire+0x13c/0x160 [ 16.848334][ C3] console_emit_next_record+0x22f/0x4c0 [ 16.848336][ C3] ? devkmsg_read+0x4b0/0x4b0 [ 16.848337][ C3] ? console_flush_one_record+0x106/0x710 [ 16.848339][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848341][ C3] ? lock_acquire+0x13c/0x160 [ 16.848343][ C3] console_flush_one_record+0x46f/0x710 [ 16.848345][ C3] ? console_emit_next_record+0x4c0/0x4c0 [ 16.848346][ C3] ? __lock_acquire+0x518/0xc20 [ 16.848349][ C3] console_unlock+0xee/0x1f0 [ 16.848350][ C3] ? console_flush_one_record+0x710/0x710 [ 16.848352][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848354][ C3] ? lock_acquire+0x60/0x160 [ 16.848355][ C3] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.848357][ C3] ? vprintk_emit+0x320/0x3e0 [ 16.848359][ C3] vprintk_emit+0x37c/0x3e0 [ 16.848361][ C3] ? wake_up_klogd_work_func+0x90/0x90 [ 16.848363][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848365][ C3] _printk+0xc7/0x100 [ 16.848366][ C3] ? snapshot_read.cold+0x21/0x21 [ 16.848368][ C3] ? ___ratelimit+0x4e7/0xa00 [ 16.848370][ C3] ? fdb_notify.isra.0+0x120/0x120 [bridge] [ 16.848376][ C3] ? idr_get_free+0x830/0x830 [ 16.848379][ C3] br_fdb_update.cold+0x74/0x90 [bridge] [ 16.848386][ C3] ? br_fdb_add_local+0x60/0x60 [bridge] [ 16.848392][ C3] ? __allowed_ingress+0x58c/0xcc0 [bridge] [ 16.848399][ C3] ? br_allowed_ingress+0x6c/0x150 [bridge] [ 16.848406][ C3] br_handle_frame_finish+0x801/0x1d20 [bridge] [ 16.848413][ C3] ? br_handle_local_finish+0x20/0x20 [bridge] [ 16.848419][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848421][ C3] ? vxlan_rcv+0x1192/0x2f70 [vxlan] [ 16.848425][ C3] br_handle_frame+0x74d/0xf50 [bridge] [ 16.848431][ C3] ? br_handle_frame_finish+0x1d20/0x1d20 [bridge] [ 16.848438][ C3] __netif_receive_skb_core.constprop.0+0x536/0x2bb0 [ 16.848441][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848443][ C3] ? do_xdp_generic+0x4a0/0x4a0 [ 16.848444][ C3] ? udpv6_queue_rcv_one_skb+0x66a/0xbf0 [ 16.848446][ C3] ? udp6_unicast_rcv_skb+0x2b3/0x390 [ 16.848447][ C3] ? ip6_protocol_deliver_rcu+0xa78/0x1480 [ 16.848449][ C3] ? find_held_lock+0x2b/0x80 [ 16.848451][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848453][ C3] __netif_receive_skb_list_core+0x2fc/0x9e0 [ 16.848455][ C3] ? __netif_receive_skb_core.constprop.0+0x2bb0/0x2bb0 [ 16.848457][ C3] ? lock_acquire.part.0+0xd4/0x280 [ 16.848458][ C3] ? netif_receive_skb_list_internal+0x2f8/0xe20 [ 16.848460][ C3] ? lock_acquire+0x13c/0x160 [ 16.848462][ C3] netif_receive_skb_list_internal+0x5f8/0xe20 [ 16.848464][ C3] ? find_held_lock+0x2b/0x80 [ 16.848465][ C3] ? process_backlog+0x14c0/0x14c0 [ 16.848467][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848468][ C3] ? validate_chain+0x731/0xc20 [ 16.848470][ C3] ? dev_gro_receive+0x8a5/0x1740 [ 16.848471][ C3] ? dev_gro_receive+0x1f8/0x1740 [ 16.848473][ C3] gro_receive_skb+0x52f/0xbb0 [ 16.848475][ C3] gro_cell_poll+0x275/0x550 [ 16.848477][ C3] __napi_poll+0xd4/0x3b0 [ 16.848479][ C3] net_rx_action+0x513/0xf50 [ 16.848482][ C3] ? __napi_poll+0x3b0/0x3b0 [ 16.848483][ C3] ? reacquire_held_locks+0x1d0/0x240 [ 16.848485][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848487][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.848488][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 16.848490][ C3] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 16.848491][ C3] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 16.848493][ C3] ? _raw_spin_unlock_irq+0x33/0x50 [ 16.848495][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848497][ C3] handle_softirqs+0x1d3/0x900 [ 16.848499][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 16.848500][ C3] ? _local_bh_enable+0xc0/0xc0 [ 16.848502][ C3] __irq_exit_rcu+0x145/0x1c0 [ 16.848503][ C3] irq_exit_rcu+0xe/0x30 [ 16.848505][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 16.848506][ C3] [ 16.848507][ C3] [ 16.848507][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 16.848509][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.848510][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 16.848512][ C3] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 16.848513][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 16.848514][ C3] RAX: 00000000000bcbc3 RBX: ff11000001c88040 RCX: ffffffff864f0307 [ 16.848515][ C3] RDX: ff11000001c88040 RSI: ffffffff89238af6 RDI: ffffffff88c8d8e0 [ 16.848516][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 16.848517][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 16.848517][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 16.848519][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.848521][ C3] default_idle+0x9/0x10 [ 16.848523][ C3] default_idle_call+0x6e/0xb0 [ 16.848524][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.848526][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 16.848533][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 16.848534][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.848535][ C3] ? rcu_is_watching+0x16/0xd0 [ 16.848537][ C3] do_idle+0xd8/0x190 [ 16.848539][ C3] cpu_startup_entry+0x53/0x70 [ 16.848540][ C3] start_secondary+0x204/0x2b0 [ 16.848542][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 16.848543][ C3] common_startup_64+0x13e/0x148 [ 16.848546][ C3] [ 16.909271][ C0] br1: received packet on vx20 with own address as source address (addr:00:00:5e:00:01:01, vlan:20) [ 16.909405][ C3] br1: received packet on vx10 with own address as source address (addr:00:00:5e:00:01:01, vlan:10) [ 16.909646][ C0] br1: received packet on vx10 with own address as source address (addr:00:00:5e:00:01:01, vlan:10) [ 16.909856][ C3] br1: received packet on vx20 with own address as source address (addr:00:00:5e:00:01:01, vlan:20) [ 16.929374][ C3] br1: received packet on vx10 with own address as source address (addr:00:00:5e:00:01:01, vlan:10) [ 16.929723][ C3] br1: received packet on vx10 with own address as source address (addr:00:00:5e:00:01:01, vlan:10) [ 16.930014][ C3] br1: received packet on vx10 with own address as source address (addr:00:00:5e:00:01:01, vlan:10) [ 16.930436][ C3] br1: received packet on vx20 with own address as source address (addr:00:00:5e:00:01:01, vlan:20) [ 16.930837][ C3] br1: received packet on vx20 with own address as source address (addr:00:00:5e:00:01:01, vlan:20) [ 27.852049][ T12] w3: left allmulticast mode [ 27.852066][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.852068][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 27.852070][ T12] preempt_count: 1, expected: 0 [ 27.852071][ T12] RCU nest depth: 0, expected: 0 [ 27.852072][ T12] INFO: lockdep is turned off. [ 27.852073][ T12] irq event stamp: 31990 [ 27.852074][ T12] hardirqs last enabled at (31989): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.852083][ T12] hardirqs last disabled at (31990): [] __schedule+0x1246/0x20e0 [ 27.852085][ T12] softirqs last enabled at (31986): [] handle_softirqs+0x67c/0x900 [ 27.852089][ T12] softirqs last disabled at (31947): [] do_softirq+0xac/0xe0 [ 27.852092][ T12] Preemption disabled at: [ 27.852092][ T12] [] vprintk_emit+0x31b/0x3e0 [ 27.852100][ T12] CPU: 1 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.852104][ T12] Tainted: [W]=WARN [ 27.852105][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.852107][ T12] Workqueue: netns cleanup_net [ 27.852113][ T12] Call Trace: [ 27.852114][ T12] [ 27.852116][ T12] dump_stack_lvl+0x6f/0xa0 [ 27.852121][ T12] ? vprintk_emit+0x31b/0x3e0 [ 27.852123][ T12] __might_resched.cold+0x1fe/0x2c1 [ 27.852127][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.852131][ T12] ? rcu_is_watching+0x16/0xd0 [ 27.852135][ T12] __kmalloc_noprof+0x443/0x760 [ 27.852138][ T12] ? __rwlock_init+0x150/0x150 [ 27.852141][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 27.852145][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 27.852146][ T12] alloc_buf.isra.0+0x4b/0x260 [ 27.852148][ T12] put_chars+0x1e1/0x2f0 [ 27.852150][ T12] ? desc_read_finalized_seq+0x79/0x120 [ 27.852152][ T12] ? __send_to_port+0x420/0x420 [ 27.852154][ T12] ? console_prepend_replay+0x20/0x20 [ 27.852157][ T12] hvc_console_print+0x292/0x780 [ 27.852161][ T12] ? hvc_write+0x3a0/0x3a0 [ 27.852162][ T12] ? do_raw_spin_lock+0x131/0x280 [ 27.852164][ T12] ? __rwlock_init+0x150/0x150 [ 27.852166][ T12] ? rcu_is_watching+0x16/0xd0 [ 27.852168][ T12] ? rcu_is_watching+0x16/0xd0 [ 27.852169][ T12] ? lock_acquire+0x13c/0x160 [ 27.852172][ T12] console_emit_next_record+0x22f/0x4c0 [ 27.852174][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 27.852176][ T12] ? rcu_is_watching+0x16/0xd0 [ 27.852178][ T12] ? rcu_is_watching+0x16/0xd0 [ 27.852180][ T12] ? rcu_is_watching+0x16/0xd0 [ 27.852181][ T12] ? lock_acquire+0x13c/0x160 [ 27.852183][ T12] ? console_flush_one_record+0x111/0x710 [ 27.852185][ T12] console_flush_one_record+0x46f/0x710 [ 27.852188][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 27.852191][ T12] console_unlock+0xee/0x1f0 [ 27.852193][ T12] ? lock_acquire+0x13c/0x160 [ 27.852194][ T12] ? console_flush_one_record+0x710/0x710 [ 27.852196][ T12] ? rcu_is_watching+0x16/0xd0 [ 27.852198][ T12] ? lock_release+0x184/0x1f0 [ 27.852199][ T12] ? lock_acquire+0x60/0x160 [ 27.852201][ T12] ? __rcu_read_lock+0x30/0x70 [ 27.852203][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.852205][ T12] ? vprintk_emit+0x320/0x3e0 [ 27.852207][ T12] vprintk_emit+0x37c/0x3e0 [ 27.852210][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 27.852211][ T12] ? process_one_work+0xe3e/0x1560 [ 27.852214][ T12] ? kthread+0x367/0x460 [ 27.852216][ T12] ? ret_from_fork+0x474/0x6b0 [ 27.852219][ T12] ? ret_from_fork_asm+0x11/0x20 [ 27.852221][ T12] _printk+0xc7/0x100 [ 27.852224][ T12] ? snapshot_read.cold+0x21/0x21 [ 27.852227][ T12] ? netdev_name+0x43/0x60 [ 27.852230][ T12] ? __netdev_printk+0x17f/0x1d0 [ 27.852232][ T12] netdev_info+0xe2/0x116 [ 27.852234][ T12] ? netdev_notice+0x120/0x120 [ 27.852236][ T12] ? rcu_is_watching+0x16/0xd0 [ 27.852237][ T12] ? __up_read+0x355/0x9e0 [ 27.852239][ T12] ? trace_hardirqs_on+0x36/0x40 [ 27.852242][ T12] ? down_write_nested+0x200/0x200 [ 27.852244][ T12] ? mutex_is_locked+0x1c/0x50 [ 27.852247][ T12] netif_set_allmulti.cold+0x7c/0x166 [ 27.852250][ T12] dev_set_allmulti+0xa2/0x150 [ 27.852253][ T12] del_nbp+0xb7/0xc10 [bridge] [ 27.852267][ T12] br_dev_delete+0x9d/0x1e0 [bridge] [ 27.852276][ T12] br_net_exit_rtnl+0xb8/0x110 [bridge] [ 27.852284][ T12] ops_undo_list+0x5be/0x8f0 [ 27.852286][ T12] ? rtnl_net_dumpid_one+0x2d0/0x2d0 [ 27.852288][ T12] ? idr_destroy+0x121/0x250 [ 27.852292][ T12] cleanup_net+0x431/0x890 [ 27.852294][ T12] ? net_passive_dec+0x230/0x230 [ 27.852296][ T12] ? update_load_avg+0x141/0xf00 [ 27.852299][ T12] ? lock_acquire+0x13c/0x160 [ 27.852300][ T12] ? rcu_is_watching+0x16/0xd0 [ 27.852303][ T12] process_one_work+0xe3e/0x1560 [ 27.852305][ T12] ? __queue_delayed_work+0x470/0x470 [ 27.852308][ T12] ? find_held_lock+0x2b/0x80 [ 27.852310][ T12] ? __rwlock_init+0x150/0x150 [ 27.852312][ T12] worker_thread+0x4f1/0xd60 [ 27.852316][ T12] ? rescuer_thread+0x1340/0x1340 [ 27.852318][ T12] kthread+0x367/0x460 [ 27.852319][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.852321][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 27.852323][ T12] ret_from_fork+0x474/0x6b0 [ 27.852324][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 27.852326][ T12] ? __switch_to+0x5a3/0xe00 [ 27.852329][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 27.852331][ T12] ret_from_fork_asm+0x11/0x20 [ 27.852334][ T12] [ 27.881125][ T12] w3: left promiscuous mode [ 27.881482][ T12] br1: port 5(w3) entered disabled state [ 27.882430][ T12] w1: left allmulticast mode [ 27.882651][ T12] w1: left promiscuous mode [ 27.882967][ T12] br1: port 4(w1) entered disabled state [ 27.885089][ T12] vx4001: left allmulticast mode [ 27.885280][ T12] vx4001: left promiscuous mode [ 27.885544][ T12] br1: port 3(vx4001) entered disabled state [ 27.888947][ T12] vx20: left allmulticast mode [ 27.889169][ T12] vx20: left promiscuous mode [ 27.889469][ T12] br1: port 2(vx20) entered disabled state [ 27.890618][ T12] vx10: left allmulticast mode [ 27.891010][ T12] vx10: left promiscuous mode [ 27.891562][ T12] br1: port 1(vx10) entered disabled state [ 28.364253][ T72] br1: left promiscuous mode [ 28.504068][ T610] br1: port 4(veth2) entered disabled state [ 28.521106][ T611] veth2: left allmulticast mode [ 28.521330][ T611] veth2: left promiscuous mode [ 28.521616][ T611] br1: port 4(veth2) entered disabled state [ 28.565666][ T613] br1: port 3(veth1) entered disabled state [ 28.585686][ T614] veth1: left allmulticast mode [ 28.586126][ T614] veth1: left promiscuous mode [ 28.586392][ T614] br1: port 3(veth1) entered disabled state [ 28.631487][ T616] vx4001: left allmulticast mode [ 28.632334][ T616] vx4001: left promiscuous mode [ 28.632621][ T616] br1: port 5(vx4001) entered disabled state [ 28.732418][ T620] vx20: left allmulticast mode [ 28.732662][ T620] vx20: left promiscuous mode [ 28.732955][ T620] br1: port 2(vx20) entered disabled state [ 28.820679][ T624] vx10: left allmulticast mode [ 28.820879][ T624] vx10: left promiscuous mode [ 28.821104][ T624] br1: port 1(vx10) entered disabled state