[ 11.059554][ T308] br: port 1(veth1) entered blocking state [ 11.059620][ T308] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.059622][ T308] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 308, name: ip [ 11.059624][ T308] preempt_count: 1, expected: 0 [ 11.059625][ T308] RCU nest depth: 0, expected: 0 [ 11.059626][ T308] locks held by ip/308: 5, last CPU#1: [ 11.059628][ T308] #0: ffffffffa7cd2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.059640][ T308] #1: ffffffffa7569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.059646][ T308] #2: ffffffffa7569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.059650][ T308] #3: ffffffffa7489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.059654][ T308] #4: ffffffffa7489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.059658][ T308] irq event stamp: 22308 [ 11.059658][ T308] hardirqs last enabled at (22307): [] __down_trylock_console_sem+0x86/0xa0 [ 11.059661][ T308] hardirqs last disabled at (22308): [] console_emit_next_record+0x3d4/0x4c0 [ 11.059663][ T308] softirqs last enabled at (22238): [] addrconf_permanent_addr+0x2b1/0x9c0 [ 11.059667][ T308] softirqs last disabled at (22236): [] addrconf_permanent_addr+0x108/0x9c0 [ 11.059669][ T308] Preemption disabled at: [ 11.059670][ T308] [] vprintk_emit+0x31b/0x3e0 [ 11.059676][ T308] CPU: 1 UID: 0 PID: 308 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.059680][ T308] Tainted: [W]=WARN [ 11.059680][ T308] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.059682][ T308] Call Trace: [ 11.059684][ T308] [ 11.059685][ T308] dump_stack_lvl+0x6f/0xa0 [ 11.059691][ T308] ? vprintk_emit+0x31b/0x3e0 [ 11.059693][ T308] __might_resched.cold+0x1fe/0x2c1 [ 11.059698][ T308] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.059702][ T308] ? __kmalloc_noprof+0xdb/0x760 [ 11.059707][ T308] __kmalloc_noprof+0x443/0x760 [ 11.059710][ T308] ? alloc_buf.isra.0+0x4b/0x260 [ 11.059715][ T308] ? do_raw_spin_unlock+0x59/0x250 [ 11.059718][ T308] alloc_buf.isra.0+0x4b/0x260 [ 11.059721][ T308] put_chars+0x1e1/0x2f0 [ 11.059724][ T308] ? __send_to_port+0x420/0x420 [ 11.059728][ T308] ? validate_chain+0x34a/0xc20 [ 11.059731][ T308] hvc_console_print+0x292/0x780 [ 11.059734][ T308] ? mark_usage+0x61/0x170 [ 11.059736][ T308] ? __lock_acquire+0x518/0xc20 [ 11.059738][ T308] ? __lock_acquire+0x518/0xc20 [ 11.059742][ T308] ? hvc_write+0x3a0/0x3a0 [ 11.059744][ T308] ? console_emit_next_record+0x1df/0x4c0 [ 11.059747][ T308] ? rcu_is_watching+0x16/0xd0 [ 11.059751][ T308] ? lock_acquire+0x13c/0x160 [ 11.059755][ T308] console_emit_next_record+0x22f/0x4c0 [ 11.059759][ T308] ? devkmsg_read+0x4b0/0x4b0 [ 11.059761][ T308] ? console_flush_one_record+0x106/0x710 [ 11.059764][ T308] ? rcu_is_watching+0x16/0xd0 [ 11.059766][ T308] ? lock_acquire+0x13c/0x160 [ 11.059770][ T308] console_flush_one_record+0x46f/0x710 [ 11.059774][ T308] ? console_emit_next_record+0x4c0/0x4c0 [ 11.059776][ T308] ? __lock_acquire+0x518/0xc20 [ 11.059781][ T308] console_unlock+0xee/0x1f0 [ 11.059784][ T308] ? console_flush_one_record+0x710/0x710 [ 11.059786][ T308] ? rcu_is_watching+0x16/0xd0 [ 11.059789][ T308] ? lock_acquire+0x60/0x160 [ 11.059792][ T308] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.059794][ T308] ? vprintk_emit+0x320/0x3e0 [ 11.059797][ T308] vprintk_emit+0x37c/0x3e0 [ 11.059801][ T308] ? wake_up_klogd_work_func+0x90/0x90 [ 11.059803][ T308] ? __lock_release.isra.0+0x69/0x1a0 [ 11.059805][ T308] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.059809][ T308] ? mark_held_locks+0x40/0x70 [ 11.059813][ T308] _printk+0xc7/0x100 [ 11.059816][ T308] ? snapshot_read.cold+0x21/0x21 [ 11.059820][ T308] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.059832][ T308] ? do_setlink.isra.0+0xa31/0x2750 [ 11.059834][ T308] ? rtnl_newlink+0x9f1/0x11c0 [ 11.059835][ T308] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.059839][ T308] br_set_state+0x22f/0x430 [bridge] [ 11.059850][ T308] br_init_port+0xc4/0x200 [bridge] [ 11.059858][ T308] new_nbp+0x39c/0x580 [bridge] [ 11.059867][ T308] br_add_if+0x212/0x1320 [bridge] [ 11.059874][ T308] ? netif_set_allmulti+0x250/0x250 [ 11.059878][ T308] ? kernel_text_address+0x149/0x170 [ 11.059881][ T308] ? __kernel_text_address+0x12/0x30 [ 11.059885][ T308] do_set_master+0x357/0x580 [ 11.059887][ T308] ? netif_change_flags+0xfe/0x190 [ 11.059891][ T308] do_setlink.isra.0+0xa31/0x2750 [ 11.059894][ T308] ? stack_trace_save+0x93/0xc0 [ 11.059898][ T308] ? rtnl_link_get_size+0x350/0x350 [ 11.059899][ T308] ? rcu_read_lock_any_held+0x66/0x90 [ 11.059902][ T308] ? stack_depot_save_flags+0x38e/0x790 [ 11.059905][ T308] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.059908][ T308] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.059910][ T308] ? validate_chain+0x38b/0xc20 [ 11.059913][ T308] ? kasan_save_stack+0x3d/0x50 [ 11.059915][ T308] ? kasan_save_stack+0x2f/0x50 [ 11.059917][ T308] ? kasan_save_track+0x14/0x30 [ 11.059920][ T308] ? __lock_acquire+0x518/0xc20 [ 11.059922][ T308] ? netlink_seq_next+0x4e/0x60 [ 11.059925][ T308] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.059929][ T308] ? lock_acquire.part.0+0xd4/0x280 [ 11.059931][ T308] ? rtnl_newlink+0x9a8/0x11c0 [ 11.059934][ T308] ? rcu_is_watching+0x16/0xd0 [ 11.059936][ T308] ? lock_acquire+0x13c/0x160 [ 11.059938][ T308] ? rcu_is_watching+0x16/0xd0 [ 11.059940][ T308] ? rcu_is_watching+0x16/0xd0 [ 11.059942][ T308] ? trace_contention_end+0xb3/0x180 [ 11.059946][ T308] ? __mutex_lock+0x1db/0x1ea0 [ 11.059948][ T308] ? __mutex_lock+0x9a3/0x1ea0 [ 11.059950][ T308] ? rtnl_newlink+0x9a8/0x11c0 [ 11.059953][ T308] ? ww_mutex_lock+0x160/0x160 [ 11.059955][ T308] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.059960][ T308] ? __rtnl_newlink+0x3fa/0xa50 [ 11.059965][ T308] rtnl_newlink+0x9f1/0x11c0 [ 11.059971][ T308] ? rtnl_bridge_getlink+0x850/0x850 [ 11.059973][ T308] ? __lock_acquire+0x518/0xc20 [ 11.059977][ T308] ? lock_acquire.part.0+0xd4/0x280 [ 11.059979][ T308] ? find_held_lock+0x2b/0x80 [ 11.059981][ T308] ? rtnl_bridge_getlink+0x850/0x850 [ 11.059983][ T308] ? __lock_release.isra.0+0x69/0x1a0 [ 11.059987][ T308] ? rtnl_bridge_getlink+0x850/0x850 [ 11.059989][ T308] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.059992][ T308] ? rtnl_link_fill+0x920/0x920 [ 11.059993][ T308] ? __lock_acquire+0x518/0xc20 [ 11.059998][ T308] ? lock_acquire.part.0+0xd4/0x280 [ 11.059999][ T308] ? find_held_lock+0x2b/0x80 [ 11.060003][ T308] netlink_rcv_skb+0x14e/0x3a0 [ 11.060005][ T308] ? rtnl_link_fill+0x920/0x920 [ 11.060008][ T308] ? netlink_ack+0xcf0/0xcf0 [ 11.060014][ T308] ? netlink_deliver_tap+0xc5/0x330 [ 11.060015][ T308] ? netlink_deliver_tap+0x13c/0x330 [ 11.060020][ T308] netlink_unicast+0x486/0x750 [ 11.060024][ T308] ? netlink_attachskb+0x810/0x810 [ 11.060026][ T308] ? __lock_acquire+0x518/0xc20 [ 11.060030][ T308] netlink_sendmsg+0x735/0xc60 [ 11.060034][ T308] ? netlink_unicast+0x750/0x750 [ 11.060038][ T308] ? __might_fault+0x97/0x140 [ 11.060044][ T308] ____sys_sendmsg+0x415/0x880 [ 11.060046][ T308] ? copy_msghdr_from_user+0x279/0x420 [ 11.060048][ T308] ? get_timestamp.constprop.0+0x390/0x390 [ 11.060050][ T308] ? move_addr_to_kernel+0x40/0x40 [ 11.060057][ T308] ___sys_sendmsg+0x14e/0x1d0 [ 11.060060][ T308] ? copy_msghdr_from_user+0x420/0x420 [ 11.060076][ T308] __sys_sendmsg+0x12c/0x1d0 [ 11.060079][ T308] ? __sys_sendmsg_sock+0x20/0x20 [ 11.060086][ T308] ? rcu_is_watching+0x16/0xd0 [ 11.060090][ T308] do_syscall_64+0xff/0x530 [ 11.060092][ T308] ? exc_page_fault+0xee/0x100 [ 11.060096][ T308] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.060098][ T308] RIP: 0033:0x7fb5a26ee54e [ 11.060101][ T308] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 11.060103][ T308] RSP: 002b:00007ffe8e6a87f0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.060105][ T308] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fb5a26ee54e [ 11.060107][ T308] RDX: 0000000000000000 RSI: 00007ffe8e6a88a0 RDI: 0000000000000005 [ 11.060108][ T308] RBP: 00007ffe8e6a8800 R08: 0000000000000000 R09: 0000000000000000 [ 11.060108][ T308] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a90e1bc [ 11.060109][ T308] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe8e6a8f60 [ 11.060120][ T308] [ 11.098625][ T308] br: port 1(veth1) entered disabled state [ 11.099234][ T308] veth1: entered allmulticast mode [ 11.100815][ T308] veth1: entered promiscuous mode [ 11.103706][ T94] br: port 1(veth1) entered blocking state [ 11.104271][ T94] br: port 1(veth1) entered forwarding state [ 11.109650][ T308] ip (308) used greatest stack depth: 23336 bytes left [ 11.155977][ T313] br: port 2(veth3) entered blocking state [ 11.156867][ T313] br: port 2(veth3) entered disabled state [ 11.157169][ T313] veth3: entered allmulticast mode [ 11.158717][ T313] veth3: entered promiscuous mode [ 11.159998][ T36] br: port 2(veth3) entered blocking state [ 11.160346][ T36] br: port 2(veth3) entered forwarding state [ 11.200007][ T318] br: port 3(veth4) entered blocking state [ 11.200387][ T318] br: port 3(veth4) entered disabled state [ 11.200686][ T318] veth4: entered allmulticast mode [ 11.202191][ T318] veth4: entered promiscuous mode [ 11.203490][ T39] br: port 3(veth4) entered blocking state [ 11.203823][ T39] br: port 3(veth4) entered forwarding state [ 26.487480][ C0] [ 26.487499][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.487501][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 26.487503][ C0] preempt_count: 104, expected: 0 [ 26.487504][ C0] RCU nest depth: 0, expected: 0 [ 26.487505][ C0] INFO: lockdep is turned off. [ 26.487506][ C0] irq event stamp: 817808 [ 26.487506][ C0] hardirqs last enabled at (817808): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.487515][ C0] hardirqs last disabled at (817807): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.487517][ C0] softirqs last enabled at (817642): [] handle_softirqs+0x67c/0x900 [ 26.487521][ C0] softirqs last disabled at (817649): [] __irq_exit_rcu+0x145/0x1c0 [ 26.487523][ C0] Preemption disabled at: [ 26.487524][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 26.487529][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.487533][ C0] Tainted: [W]=WARN [ 26.487534][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.487536][ C0] Call Trace: [ 26.487538][ C0] [ 26.487539][ C0] dump_stack_lvl+0x6f/0xa0 [ 26.487545][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 26.487546][ C0] __might_resched.cold+0x1fe/0x2c1 [ 26.487550][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.487553][ C0] ? __asan_memcpy+0x3c/0x60 [ 26.487556][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487560][ C0] __kmalloc_noprof+0x443/0x760 [ 26.487562][ C0] ? __rwlock_init+0x150/0x150 [ 26.487565][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 26.487569][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 26.487570][ C0] alloc_buf.isra.0+0x4b/0x260 [ 26.487572][ C0] put_chars+0x1e1/0x2f0 [ 26.487574][ C0] ? __send_to_port+0x420/0x420 [ 26.487576][ C0] ? console_prepend_replay+0x20/0x20 [ 26.487579][ C0] hvc_console_print+0x292/0x780 [ 26.487583][ C0] ? hvc_write+0x3a0/0x3a0 [ 26.487584][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487586][ C0] ? lock_acquire+0x13c/0x160 [ 26.487588][ C0] console_emit_next_record+0x22f/0x4c0 [ 26.487590][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 26.487592][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487594][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487595][ C0] ? lock_acquire+0x13c/0x160 [ 26.487597][ C0] ? console_flush_one_record+0x111/0x710 [ 26.487599][ C0] console_flush_one_record+0x46f/0x710 [ 26.487601][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 26.487603][ C0] console_unlock+0xee/0x1f0 [ 26.487605][ C0] ? lock_acquire+0x13c/0x160 [ 26.487606][ C0] ? console_flush_one_record+0x710/0x710 [ 26.487608][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487610][ C0] ? lock_release+0x184/0x1f0 [ 26.487611][ C0] ? lock_acquire+0x60/0x160 [ 26.487613][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.487615][ C0] ? vprintk_emit+0x320/0x3e0 [ 26.487617][ C0] vprintk_emit+0x37c/0x3e0 [ 26.487619][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 26.487621][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487622][ C0] ? lock_release+0x184/0x1f0 [ 26.487624][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 26.487628][ C0] _printk+0xc7/0x100 [ 26.487632][ C0] ? snapshot_read.cold+0x21/0x21 [ 26.487633][ C0] ? arch_stack_walk+0xd7/0x130 [ 26.487638][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.487640][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.487641][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.487643][ C0] mark_lock_irq+0x989/0x9c0 [ 26.487646][ C0] mark_lock+0x1d7/0xa00 [ 26.487648][ C0] mark_usage+0x42/0x170 [ 26.487650][ C0] __lock_acquire+0x388/0xc20 [ 26.487652][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.487653][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.487665][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487667][ C0] ? lock_acquire+0x13c/0x160 [ 26.487669][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.487675][ C0] _raw_spin_lock+0x33/0x40 [ 26.487677][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.487683][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.487689][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.487695][ C0] call_timer_fn+0x160/0x4d0 [ 26.487699][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 26.487700][ C0] ? debug_object_active_state+0x430/0x430 [ 26.487704][ C0] ? find_held_lock+0x2b/0x80 [ 26.487706][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.487707][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487709][ C0] __run_timers+0x68f/0xaa0 [ 26.487711][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.487718][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.487719][ C0] ? __lock_acquire+0x518/0xc20 [ 26.487722][ C0] ? __rwlock_init+0x150/0x150 [ 26.487725][ C0] run_timer_softirq+0xf0/0x160 [ 26.487726][ C0] ? __run_timers+0xaa0/0xaa0 [ 26.487728][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.487731][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487732][ C0] handle_softirqs+0x1d3/0x900 [ 26.487734][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.487736][ C0] ? _local_bh_enable+0xc0/0xc0 [ 26.487738][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.487739][ C0] irq_exit_rcu+0xe/0x30 [ 26.487741][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.487743][ C0] [ 26.487744][ C0] [ 26.487745][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 26.487746][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.487749][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.487751][ C0] Code: 48 8b 3d 94 f2 f5 01 e8 1f 00 00 00 48 2b 05 58 b3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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 [ 26.487753][ C0] RSP: 0018:ffffffffa7207cf8 EFLAGS: 00000296 [ 26.487757][ C0] RAX: 00000000000c79ef RBX: ffffffffa721c600 RCX: ffffffffa42f0307 [ 26.487758][ C0] RDX: ffffffffa721c600 RSI: ffffffffa7038b1c RDI: ffffffffa6a8d8e0 [ 26.487759][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.487760][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff4e40fa2 [ 26.487760][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 26.487762][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.487765][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 26.487767][ C0] default_idle+0x9/0x10 [ 26.487768][ C0] default_idle_call+0x6e/0xb0 [ 26.487770][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.487772][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 26.487773][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 26.487775][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.487777][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.487778][ C0] do_idle+0xd8/0x190 [ 26.487780][ C0] cpu_startup_entry+0x53/0x70 [ 26.487781][ C0] rest_init+0x279/0x280 [ 26.487783][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 26.487786][ C0] ? rest_init+0x280/0x280 [ 26.487788][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 26.487792][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 26.487794][ C0] ? acpi_enable+0x1e4/0x330 [ 26.487797][ C0] start_kernel+0x3af/0x3b0 [ 26.487799][ C0] x86_64_start_reservations+0x24/0x30 [ 26.487801][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.487802][ C0] common_startup_64+0x13e/0x148 [ 26.487806][ C0] [ 26.508763][ C0] ======================================================== [ 26.509031][ C0] WARNING: possible irq lock inversion dependency detected [ 26.509296][ C0] 7.2.0-virtme #1 Tainted: G W [ 26.509533][ C0] -------------------------------------------------------- [ 26.509747][ C0] swapper/0/0 just changed the state of lock: [ 26.509927][ C0] ff1100000ce42e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.510279][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.510554][ C0] (fs_reclaim){+.+.}-{0:0} [ 26.510556][ C0] [ 26.510556][ C0] [ 26.510556][ C0] and interrupts could create inverse lock ordering between them. [ 26.510556][ C0] [ 26.511132][ C0] [ 26.511132][ C0] other info that might help us debug this: [ 26.511351][ C0] Chain exists of: [ 26.511351][ C0] &br->lock --> console_owner --> fs_reclaim [ 26.511351][ C0] [ 26.511742][ C0] Possible interrupt unsafe locking scenario: [ 26.511742][ C0] [ 26.512012][ C0] CPU0 CPU1 [ 26.512213][ C0] ---- ---- [ 26.512360][ C0] lock(fs_reclaim); [ 26.512534][ C0] local_irq_disable(); [ 26.515309][ C0] lock(&br->lock); [ 26.515556][ C0] lock(console_owner); [ 26.515735][ C0] [ 26.515847][ C0] lock(&br->lock); [ 26.515961][ C0] [ 26.515961][ C0] *** DEADLOCK *** [ 26.515961][ C0] [ 26.516231][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 26.516466][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.516815][ C0] [ 26.516815][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 26.517117][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 26.517318][ C0] HARDIRQ-ON-W at: [ 26.517437][ C0] __lock_acquire+0x388/0xc20 [ 26.517688][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.517923][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.518113][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 26.518334][ C0] kthread_create_worker_on_node+0xea/0x210 [ 26.518616][ C0] workqueue_init+0x2a/0x680 [ 26.518797][ C0] kernel_init_freeable+0x2fe/0x630 [ 26.519065][ C0] kernel_init+0x21/0x150 [ 26.519249][ C0] ret_from_fork+0x474/0x6b0 [ 26.519435][ C0] ret_from_fork_asm+0x11/0x20 [ 26.519671][ C0] SOFTIRQ-ON-W at: [ 26.519778][ C0] __lock_acquire+0x388/0xc20 [ 26.520011][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.520243][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.520430][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 26.520702][ C0] kthread_create_worker_on_node+0xea/0x210 [ 26.520971][ C0] workqueue_init+0x2a/0x680 [ 26.521152][ C0] kernel_init_freeable+0x2fe/0x630 [ 26.521372][ C0] kernel_init+0x21/0x150 [ 26.521563][ C0] ret_from_fork+0x474/0x6b0 [ 26.521740][ C0] ret_from_fork_asm+0x11/0x20 [ 26.521976][ C0] INITIAL USE at: [ 26.522085][ C0] __lock_acquire+0x388/0xc20 [ 26.522319][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.522560][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.522738][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 26.523008][ C0] kthread_create_worker_on_node+0xea/0x210 [ 26.523272][ C0] workqueue_init+0x2a/0x680 [ 26.523454][ C0] kernel_init_freeable+0x2fe/0x630 [ 26.523690][ C0] kernel_init+0x21/0x150 [ 26.523868][ C0] ret_from_fork+0x474/0x6b0 [ 26.524052][ C0] ret_from_fork_asm+0x11/0x20 [ 26.524286][ C0] } [ 26.524361][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.524637][ C0] ... acquired at: [ 26.524748][ C0] __lock_acquire+0x518/0xc20 [ 26.524947][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.525092][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.525286][ C0] __kmalloc_noprof+0xd3/0x760 [ 26.525434][ C0] alloc_buf.isra.0+0x4b/0x260 [ 26.525636][ C0] put_chars+0x1e1/0x2f0 [ 26.525777][ C0] hvc_console_print+0x292/0x780 [ 26.525972][ C0] console_emit_next_record+0x22f/0x4c0 [ 26.526118][ C0] console_flush_one_record+0x46f/0x710 [ 26.526365][ C0] console_unlock+0xee/0x1f0 [ 26.526524][ C0] vprintk_emit+0x37c/0x3e0 [ 26.526723][ C0] _printk+0xc7/0x100 [ 26.526831][ C0] dm_interface_init+0x50/0x60 [ 26.527024][ C0] dm_init+0x51/0xd0 [ 26.527136][ C0] do_one_initcall+0x124/0x4f0 [ 26.527278][ C0] kernel_init_freeable+0x596/0x630 [ 26.527477][ C0] kernel_init+0x21/0x150 [ 26.527624][ C0] ret_from_fork+0x474/0x6b0 [ 26.527821][ C0] ret_from_fork_asm+0x11/0x20 [ 26.527962][ C0] [ 26.528088][ C0] -> (console_owner){....}-{0:0} { [ 26.528235][ C0] INITIAL USE at: [ 26.528397][ C0] } [ 26.528474][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.528740][ C0] ... acquired at: [ 26.528849][ C0] __lock_acquire+0x518/0xc20 [ 26.528993][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.529187][ C0] console_lock_spinning_enable+0x5c/0x60 [ 26.529415][ C0] console_emit_next_record+0x1d1/0x4c0 [ 26.529565][ C0] console_flush_one_record+0x46f/0x710 [ 26.529708][ C0] console_unlock+0xee/0x1f0 [ 26.529854][ C0] vprintk_emit+0x37c/0x3e0 [ 26.530048][ C0] _printk+0xc7/0x100 [ 26.530156][ C0] br_set_state+0x22f/0x430 [bridge] [ 26.530307][ C0] br_init_port+0xc4/0x200 [bridge] [ 26.530519][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 26.530756][ C0] br_port_carrier_check+0x220/0x430 [bridge] [ 26.530939][ C0] br_device_event+0x52d/0x8f0 [bridge] [ 26.531144][ C0] notifier_call_chain+0xae/0x300 [ 26.531288][ C0] netif_state_change+0x139/0x340 [ 26.531493][ C0] __linkwatch_run_queue+0x34c/0x750 [ 26.531634][ C0] linkwatch_event+0x7f/0xb0 [ 26.531827][ C0] process_one_work+0xe3e/0x1560 [ 26.531970][ C0] worker_thread+0x4f1/0xd60 [ 26.532115][ C0] kthread+0x367/0x460 [ 26.532223][ C0] ret_from_fork+0x474/0x6b0 [ 26.532418][ C0] ret_from_fork_asm+0x11/0x20 [ 26.532567][ C0] [ 26.532638][ C0] -> (&br->lock){+.-.}-{3:3} { [ 26.532835][ C0] HARDIRQ-ON-W at: [ 26.532941][ C0] __lock_acquire+0x388/0xc20 [ 26.533177][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.533356][ C0] _raw_spin_lock_bh+0x38/0x50 [ 26.533600][ C0] br_stp_enable_bridge+0x23/0x340 [bridge] [ 26.533870][ C0] br_dev_open+0x94/0x120 [bridge] [ 26.534053][ C0] __dev_open+0x2f3/0x610 [ 26.534283][ C0] __dev_change_flags+0x4a4/0x660 [ 26.534528][ C0] rtnl_configure_link+0x15f/0x410 [ 26.534707][ C0] rtnl_newlink_create+0x2ec/0x750 [ 26.534936][ C0] __rtnl_newlink+0x22b/0xa50 [ 26.535171][ C0] rtnl_newlink+0x9f1/0x11c0 [ 26.535352][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.535588][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 26.535767][ C0] netlink_unicast+0x486/0x750 [ 26.536001][ C0] netlink_sendmsg+0x735/0xc60 [ 26.536233][ C0] ____sys_sendmsg+0x415/0x880 [ 26.536413][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 26.536653][ C0] __sys_sendmsg+0x12c/0x1d0 [ 26.536881][ C0] do_syscall_64+0xff/0x530 [ 26.537061][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.537330][ C0] IN-SOFTIRQ-W at: [ 26.537441][ C0] __lock_acquire+0x388/0xc20 [ 26.537675][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.537854][ C0] _raw_spin_lock+0x33/0x40 [ 26.538037][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.538309][ C0] call_timer_fn+0x160/0x4d0 [ 26.538550][ C0] __run_timers+0x68f/0xaa0 [ 26.538757][ C0] run_timer_softirq+0xf0/0x160 [ 26.538990][ C0] handle_softirqs+0x1d3/0x900 [ 26.539171][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.539403][ C0] irq_exit_rcu+0xe/0x30 [ 26.539643][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.539861][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.540133][ C0] pv_native_safe_halt+0xf/0x10 [ 26.540312][ C0] default_idle+0x9/0x10 [ 26.540500][ C0] default_idle_call+0x6e/0xb0 [ 26.540734][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.541000][ C0] do_idle+0xd8/0x190 [ 26.541144][ C0] cpu_startup_entry+0x53/0x70 [ 26.541327][ C0] rest_init+0x279/0x280 [ 26.541569][ C0] start_kernel+0x3af/0x3b0 [ 26.541752][ C0] x86_64_start_reservations+0x24/0x30 [ 26.541984][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.542174][ C0] common_startup_64+0x13e/0x148 [ 26.542355][ C0] INITIAL USE at: [ 26.542468][ C0] __lock_acquire+0x388/0xc20 [ 26.542653][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.542885][ C0] _raw_spin_lock_bh+0x38/0x50 [ 26.543063][ C0] br_stp_enable_bridge+0x23/0x340 [bridge] [ 26.543337][ C0] br_dev_open+0x94/0x120 [bridge] [ 26.543581][ C0] __dev_open+0x2f3/0x610 [ 26.543761][ C0] __dev_change_flags+0x4a4/0x660 [ 26.543994][ C0] rtnl_configure_link+0x15f/0x410 [ 26.544227][ C0] rtnl_newlink_create+0x2ec/0x750 [ 26.544409][ C0] __rtnl_newlink+0x22b/0xa50 [ 26.544652][ C0] rtnl_newlink+0x9f1/0x11c0 [ 26.544832][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.545011][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 26.545245][ C0] netlink_unicast+0x486/0x750 [ 26.545428][ C0] netlink_sendmsg+0x735/0xc60 [ 26.545664][ C0] ____sys_sendmsg+0x415/0x880 [ 26.545892][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 26.548732][ C0] __sys_sendmsg+0x12c/0x1d0 [ 26.548964][ C0] do_syscall_64+0xff/0x530 [ 26.549143][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.549412][ C0] } [ 26.549495][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.549764][ C0] ... acquired at: [ 26.549922][ C0] mark_lock+0x1d7/0xa00 [ 26.550079][ C0] mark_usage+0x42/0x170 [ 26.550222][ C0] __lock_acquire+0x388/0xc20 [ 26.550364][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.550570][ C0] _raw_spin_lock+0x33/0x40 [ 26.550717][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.550952][ C0] call_timer_fn+0x160/0x4d0 [ 26.551097][ C0] __run_timers+0x68f/0xaa0 [ 26.551292][ C0] run_timer_softirq+0xf0/0x160 [ 26.551443][ C0] handle_softirqs+0x1d3/0x900 [ 26.551642][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.551786][ C0] irq_exit_rcu+0xe/0x30 [ 26.551980][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.552162][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.552337][ C0] pv_native_safe_halt+0xf/0x10 [ 26.552488][ C0] default_idle+0x9/0x10 [ 26.552682][ C0] default_idle_call+0x6e/0xb0 [ 26.552825][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.553003][ C0] do_idle+0xd8/0x190 [ 26.553112][ C0] cpu_startup_entry+0x53/0x70 [ 26.553306][ C0] rest_init+0x279/0x280 [ 26.553457][ C0] start_kernel+0x3af/0x3b0 [ 26.553652][ C0] x86_64_start_reservations+0x24/0x30 [ 26.553793][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.553989][ C0] common_startup_64+0x13e/0x148 [ 26.554135][ C0] [ 26.554207][ C0] [ 26.554207][ C0] stack backtrace: [ 26.554385][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.554388][ C0] Tainted: [W]=WARN [ 26.554389][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.554390][ C0] Call Trace: [ 26.554391][ C0] [ 26.554393][ C0] dump_stack_lvl+0x6f/0xa0 [ 26.554397][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.554400][ C0] mark_lock_irq+0x989/0x9c0 [ 26.554403][ C0] mark_lock+0x1d7/0xa00 [ 26.554405][ C0] mark_usage+0x42/0x170 [ 26.554406][ C0] __lock_acquire+0x388/0xc20 [ 26.554409][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.554410][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.554417][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.554426][ C0] ? lock_acquire+0x13c/0x160 [ 26.554428][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.554434][ C0] _raw_spin_lock+0x33/0x40 [ 26.554435][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.554441][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.554447][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.554454][ C0] call_timer_fn+0x160/0x4d0 [ 26.554456][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 26.554457][ C0] ? debug_object_active_state+0x430/0x430 [ 26.554461][ C0] ? find_held_lock+0x2b/0x80 [ 26.554463][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.554465][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.554467][ C0] __run_timers+0x68f/0xaa0 [ 26.554469][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.554475][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.554477][ C0] ? __lock_acquire+0x518/0xc20 [ 26.554481][ C0] ? __rwlock_init+0x150/0x150 [ 26.554485][ C0] run_timer_softirq+0xf0/0x160 [ 26.554488][ C0] ? __run_timers+0xaa0/0xaa0 [ 26.554490][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.554492][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.554494][ C0] handle_softirqs+0x1d3/0x900 [ 26.554496][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.554498][ C0] ? _local_bh_enable+0xc0/0xc0 [ 26.554500][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.554501][ C0] irq_exit_rcu+0xe/0x30 [ 26.554502][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.554504][ C0] [ 26.554505][ C0] [ 26.554506][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 26.554507][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.554509][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.554511][ C0] Code: 48 8b 3d 94 f2 f5 01 e8 1f 00 00 00 48 2b 05 58 b3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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 [ 26.554512][ C0] RSP: 0018:ffffffffa7207cf8 EFLAGS: 00000296 [ 26.554514][ C0] RAX: 00000000000c79ef RBX: ffffffffa721c600 RCX: ffffffffa42f0307 [ 26.554516][ C0] RDX: ffffffffa721c600 RSI: ffffffffa7038b1c RDI: ffffffffa6a8d8e0 [ 26.554517][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.554518][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff4e40fa2 [ 26.554518][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 26.554520][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.554522][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 26.554524][ C0] default_idle+0x9/0x10 [ 26.554525][ C0] default_idle_call+0x6e/0xb0 [ 26.554527][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.554528][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 26.554530][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 26.554532][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.554533][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.554535][ C0] do_idle+0xd8/0x190 [ 26.554537][ C0] cpu_startup_entry+0x53/0x70 [ 26.554538][ C0] rest_init+0x279/0x280 [ 26.554540][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 26.554543][ C0] ? rest_init+0x280/0x280 [ 26.554545][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 26.554549][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 26.554551][ C0] ? acpi_enable+0x1e4/0x330 [ 26.554553][ C0] start_kernel+0x3af/0x3b0 [ 26.554555][ C0] x86_64_start_reservations+0x24/0x30 [ 26.554557][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.554558][ C0] common_startup_64+0x13e/0x148 [ 26.554561][ C0] [ 87.235135][ T750] Mirror/redirect action on [ 87.235148][ T750] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 87.235150][ T750] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 750, name: modprobe [ 87.235152][ T750] preempt_count: 1, expected: 0 [ 87.235153][ T750] RCU nest depth: 0, expected: 0 [ 87.235154][ T750] INFO: lockdep is turned off. [ 87.235155][ T750] irq event stamp: 0 [ 87.235156][ T750] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 87.235159][ T750] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 87.235166][ T750] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 87.235167][ T750] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 87.235169][ T750] Preemption disabled at: [ 87.235169][ T750] [] vprintk_emit+0x31b/0x3e0 [ 87.235177][ T750] CPU: 1 UID: 0 PID: 750 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 87.235180][ T750] Tainted: [W]=WARN [ 87.235181][ T750] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 87.235182][ T750] Call Trace: [ 87.235184][ T750] [ 87.235185][ T750] dump_stack_lvl+0x6f/0xa0 [ 87.235190][ T750] ? vprintk_emit+0x31b/0x3e0 [ 87.235192][ T750] __might_resched.cold+0x1fe/0x2c1 [ 87.235199][ T750] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 87.235201][ T750] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 87.235204][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235208][ T750] __kmalloc_noprof+0x443/0x760 [ 87.235211][ T750] ? __rwlock_init+0x150/0x150 [ 87.235214][ T750] ? alloc_buf.isra.0+0x4b/0x260 [ 87.235218][ T750] ? do_raw_spin_unlock+0x59/0x250 [ 87.235219][ T750] alloc_buf.isra.0+0x4b/0x260 [ 87.235221][ T750] put_chars+0x1e1/0x2f0 [ 87.235223][ T750] ? __send_to_port+0x420/0x420 [ 87.235225][ T750] ? console_prepend_replay+0x20/0x20 [ 87.235227][ T750] hvc_console_print+0x292/0x780 [ 87.235230][ T750] ? hvc_write+0x3a0/0x3a0 [ 87.235231][ T750] ? vsnprintf+0x668/0x1700 [ 87.235235][ T750] ? __rwlock_init+0x150/0x150 [ 87.235236][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235238][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235240][ T750] console_emit_next_record+0x22f/0x4c0 [ 87.235243][ T750] ? devkmsg_read+0x4b0/0x4b0 [ 87.235245][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235246][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235248][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235250][ T750] ? lock_acquire+0x13c/0x160 [ 87.235252][ T750] ? console_flush_one_record+0x111/0x710 [ 87.235253][ T750] console_flush_one_record+0x46f/0x710 [ 87.235255][ T750] ? console_emit_next_record+0x4c0/0x4c0 [ 87.235258][ T750] console_unlock+0xee/0x1f0 [ 87.235260][ T750] ? lock_acquire+0x13c/0x160 [ 87.235261][ T750] ? console_flush_one_record+0x710/0x710 [ 87.235263][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235264][ T750] ? lock_release+0x184/0x1f0 [ 87.235266][ T750] ? lock_acquire+0x60/0x160 [ 87.235267][ T750] ? __rcu_read_lock+0x30/0x70 [ 87.235269][ T750] ? __down_trylock_console_sem+0x5e/0xa0 [ 87.235271][ T750] ? vprintk_emit+0x320/0x3e0 [ 87.235273][ T750] vprintk_emit+0x37c/0x3e0 [ 87.235276][ T750] ? wake_up_klogd_work_func+0x90/0x90 [ 87.235278][ T750] ? netdev_run_todo+0x2a8/0xfd0 [ 87.235282][ T750] ? mirred_device_event+0x3d/0x1d0 [act_mirred] [ 87.235285][ T750] ? mirred_cleanup_module+0x750/0x750 [act_mirred] [ 87.235286][ T750] _printk+0xc7/0x100 [ 87.235289][ T750] ? snapshot_read.cold+0x21/0x21 [ 87.235291][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235293][ T750] ? mirred_cleanup_module+0x750/0x750 [act_mirred] [ 87.235295][ T750] ? __up_write+0x23d/0x5f0 [ 87.235296][ T750] ? __up_write+0x2e5/0x5f0 [ 87.235297][ T750] ? lock_release+0x184/0x1f0 [ 87.235299][ T750] ? __up_write+0x2c7/0x5f0 [ 87.235300][ T750] mirred_init_module+0x28/0x1000 [act_mirred] [ 87.235302][ T750] do_one_initcall+0x124/0x4f0 [ 87.235305][ T750] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 87.235307][ T750] ? do_init_module+0xae/0x8a0 [ 87.235310][ T750] ? kasan_unpoison+0x44/0x70 [ 87.235313][ T750] do_init_module+0x2ad/0x8a0 [ 87.235315][ T750] ? free_module+0x470/0x470 [ 87.235317][ T750] ? kfree+0x123/0x590 [ 87.235319][ T750] load_module+0x1424/0x1bb0 [ 87.235322][ T750] ? layout_and_allocate.isra.0+0x680/0x680 [ 87.235325][ T750] ? __ia32_sys_fsconfig+0x180/0x180 [ 87.235327][ T750] ? ovl_real_file+0x172/0x1e0 [ 87.235330][ T750] ? backing_file_read_iter+0x541/0x790 [ 87.235333][ T750] init_module_from_file+0x11f/0x1a0 [ 87.235335][ T750] ? __do_sys_init_module+0x220/0x220 [ 87.235337][ T750] ? ovl_mmap+0x270/0x270 [ 87.235340][ T750] ? __rwlock_init+0x150/0x150 [ 87.235341][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235343][ T750] ? lock_release+0x184/0x1f0 [ 87.235345][ T750] ? do_raw_spin_unlock+0x59/0x250 [ 87.235346][ T750] idempotent_init_module+0x37b/0x7c0 [ 87.235349][ T750] ? init_module_from_file+0x1a0/0x1a0 [ 87.235351][ T750] ? rcu_is_watching+0x16/0xd0 [ 87.235353][ T750] ? cap_capable+0x1d7/0x3d0 [ 87.235357][ T750] __x64_sys_finit_module+0xdf/0x190 [ 87.235359][ T750] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 87.235361][ T750] do_syscall_64+0xff/0x530 [ 87.235363][ T750] ? exc_page_fault+0xee/0x100 [ 87.235365][ T750] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 87.235367][ T750] RIP: 0033:0x7fc791dee37d [ 87.235369][ T750] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 87.235371][ T750] RSP: 002b:00007fff37a7f858 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 87.235375][ T750] RAX: ffffffffffffffda RBX: 00005557a14da810 RCX: 00007fc791dee37d [ 87.235376][ T750] RDX: 0000000000000000 RSI: 0000555772841ffd RDI: 0000000000000000 [ 87.235377][ T750] RBP: 00007fff37a7f8f0 R08: 0000000000000000 R09: 0000000000000000 [ 87.235377][ T750] R10: 0000000000000000 R11: 0000000000000246 R12: 0000555772841ffd [ 87.235378][ T750] R13: 00005557a14da960 R14: 0000000000040000 R15: 0000000000000000 [ 87.235381][ T750] [ 115.894802][ T928] br: port 3(veth4) entered disabled state [ 115.894817][ T928] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 115.894819][ T928] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 928, name: ip [ 115.894821][ T928] preempt_count: 202, expected: 0 [ 115.894822][ T928] RCU nest depth: 0, expected: 0 [ 115.894823][ T928] INFO: lockdep is turned off. [ 115.894824][ T928] irq event stamp: 0 [ 115.894825][ T928] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 115.894830][ T928] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 115.894836][ T928] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 115.894837][ T928] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 115.894839][ T928] Preemption disabled at: [ 115.894840][ T928] [<0000000000000000>] 0x0 [ 115.894844][ T928] CPU: 3 UID: 0 PID: 928 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 115.894848][ T928] Tainted: [W]=WARN [ 115.894849][ T928] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 115.894850][ T928] Call Trace: [ 115.894852][ T928] [ 115.894853][ T928] dump_stack_lvl+0x6f/0xa0 [ 115.894859][ T928] __might_resched.cold+0x1fe/0x2c1 [ 115.894863][ T928] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 115.894867][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.894871][ T928] __kmalloc_noprof+0x443/0x760 [ 115.894874][ T928] ? __rwlock_init+0x150/0x150 [ 115.894877][ T928] ? alloc_buf.isra.0+0x4b/0x260 [ 115.894881][ T928] ? do_raw_spin_unlock+0x59/0x250 [ 115.894883][ T928] alloc_buf.isra.0+0x4b/0x260 [ 115.894884][ T928] put_chars+0x1e1/0x2f0 [ 115.894887][ T928] ? __send_to_port+0x420/0x420 [ 115.894888][ T928] ? printk_get_next_message+0x240/0x650 [ 115.894892][ T928] hvc_console_print+0x292/0x780 [ 115.894895][ T928] ? hvc_write+0x3a0/0x3a0 [ 115.894898][ T928] ? __rwlock_init+0x150/0x150 [ 115.894899][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.894901][ T928] ? lock_acquire+0x13c/0x160 [ 115.894903][ T928] console_emit_next_record+0x22f/0x4c0 [ 115.894905][ T928] ? devkmsg_read+0x4b0/0x4b0 [ 115.894907][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.894909][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.894910][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.894912][ T928] ? lock_acquire+0x13c/0x160 [ 115.894914][ T928] ? console_flush_one_record+0x111/0x710 [ 115.894916][ T928] console_flush_one_record+0x46f/0x710 [ 115.894918][ T928] ? console_emit_next_record+0x4c0/0x4c0 [ 115.894920][ T928] console_unlock+0xee/0x1f0 [ 115.894922][ T928] ? lock_acquire+0x13c/0x160 [ 115.894923][ T928] ? console_flush_one_record+0x710/0x710 [ 115.894925][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.894926][ T928] ? lock_release+0x184/0x1f0 [ 115.894928][ T928] ? lock_acquire+0x60/0x160 [ 115.894929][ T928] ? __rcu_read_lock+0x30/0x70 [ 115.894931][ T928] ? __down_trylock_console_sem+0x5e/0xa0 [ 115.894933][ T928] ? vprintk_emit+0x320/0x3e0 [ 115.894935][ T928] vprintk_emit+0x37c/0x3e0 [ 115.894938][ T928] ? wake_up_klogd_work_func+0x90/0x90 [ 115.894940][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.894941][ T928] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 115.894944][ T928] ? trace_hardirqs_on+0x36/0x40 [ 115.894945][ T928] _printk+0xc7/0x100 [ 115.894949][ T928] ? snapshot_read.cold+0x21/0x21 [ 115.894951][ T928] ? ipv6_mc_down+0x17e/0x2d0 [ 115.894955][ T928] br_set_state+0x22f/0x430 [bridge] [ 115.894968][ T928] br_stp_disable_port+0x11f/0x390 [bridge] [ 115.894976][ T928] br_device_event+0x7b5/0x8f0 [bridge] [ 115.894984][ T928] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 115.894987][ T928] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 115.894989][ T928] ? addrconf_notify+0x2c8/0xf30 [ 115.894994][ T928] notifier_call_chain+0xae/0x300 [ 115.894997][ T928] __dev_notify_flags+0x23c/0x3c0 [ 115.895001][ T928] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 115.895002][ T928] ? netif_change_name+0x8c0/0x8c0 [ 115.895004][ T928] ? netif_set_allmulti+0x250/0x250 [ 115.895006][ T928] ? __kernel_text_address+0x12/0x30 [ 115.895008][ T928] ? write_profile+0xf0/0xf0 [ 115.895010][ T928] ? unwind_get_return_address+0x67/0xd0 [ 115.895013][ T928] netif_change_flags+0xfe/0x190 [ 115.895015][ T928] do_setlink.isra.0+0x1cc3/0x2750 [ 115.895018][ T928] ? stack_trace_save+0x93/0xc0 [ 115.895020][ T928] ? rtnl_link_get_size+0x350/0x350 [ 115.895021][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895022][ T928] ? stack_depot_save_flags+0x1bc/0x790 [ 115.895026][ T928] ? kasan_save_stack+0x3d/0x50 [ 115.895029][ T928] ? kasan_save_stack+0x2f/0x50 [ 115.895030][ T928] ? kasan_save_track+0x14/0x30 [ 115.895032][ T928] ? __kasan_kmalloc+0x7b/0x90 [ 115.895033][ T928] ? __kmalloc_cache_noprof+0x29a/0x620 [ 115.895035][ T928] ? rtnl_newlink+0x124/0x11c0 [ 115.895036][ T928] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 115.895037][ T928] ? netlink_rcv_skb+0x14e/0x3a0 [ 115.895040][ T928] ? netlink_unicast+0x486/0x750 [ 115.895041][ T928] ? netlink_sendmsg+0x735/0xc60 [ 115.895042][ T928] ? ____sys_sendmsg+0x415/0x880 [ 115.895045][ T928] ? ___sys_sendmsg+0x14e/0x1d0 [ 115.895046][ T928] ? __sys_sendmsg+0x12c/0x1d0 [ 115.895048][ T928] ? do_syscall_64+0xff/0x530 [ 115.895050][ T928] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 115.895052][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895054][ T928] ? lock_acquire+0x13c/0x160 [ 115.895055][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895057][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895059][ T928] ? trace_contention_end+0xb3/0x180 [ 115.895062][ T928] ? __mutex_lock+0x1db/0x1ea0 [ 115.895063][ T928] ? __mutex_lock+0x9a3/0x1ea0 [ 115.895064][ T928] ? rtnl_newlink+0x9a8/0x11c0 [ 115.895066][ T928] ? ww_mutex_lock+0x160/0x160 [ 115.895067][ T928] ? nla_get_range_signed+0x3d0/0x3d0 [ 115.895069][ T928] ? __rtnl_newlink+0x3fa/0xa50 [ 115.895071][ T928] rtnl_newlink+0x9f1/0x11c0 [ 115.895073][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895075][ T928] ? rtnl_bridge_getlink+0x850/0x850 [ 115.895076][ T928] ? lock_acquire+0x13c/0x160 [ 115.895077][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895079][ T928] ? is_bpf_text_address+0x3e/0x110 [ 115.895083][ T928] ? is_bpf_text_address+0x72/0x110 [ 115.895084][ T928] ? kernel_text_address+0x149/0x170 [ 115.895086][ T928] ? __kernel_text_address+0x12/0x30 [ 115.895087][ T928] ? write_profile+0xf0/0xf0 [ 115.895088][ T928] ? unwind_get_return_address+0x67/0xd0 [ 115.895089][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895091][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895092][ T928] ? cap_capable+0x1d7/0x3d0 [ 115.895096][ T928] ? lock_acquire+0x13c/0x160 [ 115.895097][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895099][ T928] ? rtnl_bridge_getlink+0x850/0x850 [ 115.895100][ T928] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 115.895102][ T928] ? rtnl_link_fill+0x920/0x920 [ 115.895103][ T928] ? filter_irq_stacks+0xd0/0xd0 [ 115.895105][ T928] ? stack_depot_save_flags+0x1bc/0x790 [ 115.895107][ T928] netlink_rcv_skb+0x14e/0x3a0 [ 115.895108][ T928] ? rtnl_link_fill+0x920/0x920 [ 115.895110][ T928] ? lock_release+0x184/0x1f0 [ 115.895111][ T928] ? netlink_ack+0xcf0/0xcf0 [ 115.895112][ T928] ? __netlink_lookup+0xb9/0x6b0 [ 115.895115][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895117][ T928] ? lock_release+0x184/0x1f0 [ 115.895118][ T928] netlink_unicast+0x486/0x750 [ 115.895120][ T928] ? netlink_attachskb+0x810/0x810 [ 115.895122][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895123][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895125][ T928] ? lock_acquire+0x13c/0x160 [ 115.895127][ T928] netlink_sendmsg+0x735/0xc60 [ 115.895129][ T928] ? netlink_unicast+0x750/0x750 [ 115.895131][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895132][ T928] ? lock_release+0x184/0x1f0 [ 115.895134][ T928] ____sys_sendmsg+0x415/0x880 [ 115.895136][ T928] ? copy_msghdr_from_user+0x279/0x420 [ 115.895137][ T928] ? get_timestamp.constprop.0+0x390/0x390 [ 115.895139][ T928] ? move_addr_to_kernel+0x40/0x40 [ 115.895140][ T928] ? filter_irq_stacks+0xd0/0xd0 [ 115.895143][ T928] ___sys_sendmsg+0x14e/0x1d0 [ 115.895144][ T928] ? copy_msghdr_from_user+0x420/0x420 [ 115.895149][ T928] __sys_sendmsg+0x12c/0x1d0 [ 115.895151][ T928] ? __sys_sendmsg_sock+0x20/0x20 [ 115.895153][ T928] ? rcu_is_watching+0x16/0xd0 [ 115.895155][ T928] do_syscall_64+0xff/0x530 [ 115.895157][ T928] ? exc_page_fault+0xee/0x100 [ 115.895159][ T928] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 115.895160][ T928] RIP: 0033:0x7ff02288a54e [ 115.895162][ T928] 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 [ 115.895164][ T928] RSP: 002b:00007ffdd7c03840 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 115.895168][ T928] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007ff02288a54e [ 115.895169][ T928] RDX: 0000000000000000 RSI: 00007ffdd7c038f0 RDI: 0000000000000005 [ 115.895170][ T928] RBP: 00007ffdd7c03850 R08: 0000000000000000 R09: 0000000000000000 [ 115.895171][ T928] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a90e225 [ 115.895171][ T928] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffdd7c03fb0 [ 115.895174][ T928] [ 115.919062][ T928] veth4: left allmulticast mode [ 115.919248][ T928] veth4: left promiscuous mode [ 115.919446][ T928] br: port 3(veth4) entered disabled state [ 115.925691][ T928] ip (928) used greatest stack depth: 23016 bytes left [ 115.946265][ T930] br: port 2(veth3) entered disabled state [ 115.946626][ T930] veth3: left allmulticast mode [ 115.946809][ T930] veth3: left promiscuous mode [ 115.947048][ T930] br: port 2(veth3) entered disabled state [ 115.964439][ T932] br: port 1(veth1) entered disabled state [ 115.964814][ T932] veth1: left allmulticast mode [ 115.964987][ T932] veth1: left promiscuous mode [ 115.965773][ T932] br: port 1(veth1) entered disabled state