[ 11.472356][ T235] br1: port 1(veth1) entered blocking state [ 11.472426][ T235] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.472428][ T235] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 235, name: ip [ 11.472430][ T235] preempt_count: 1, expected: 0 [ 11.472431][ T235] RCU nest depth: 0, expected: 0 [ 11.472432][ T235] locks held by ip/235: 5, last CPU#2: [ 11.472434][ T235] #0: ffffffff93ed2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.472447][ T235] #1: ffffffff93769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.472453][ T235] #2: ffffffff93769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.472457][ T235] #3: ffffffff93689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.472461][ T235] #4: ffffffff93689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.472465][ T235] irq event stamp: 13006 [ 11.472466][ T235] hardirqs last enabled at (13005): [] __down_trylock_console_sem+0x86/0xa0 [ 11.472468][ T235] hardirqs last disabled at (13006): [] console_emit_next_record+0x3d4/0x4c0 [ 11.472471][ T235] softirqs last enabled at (12916): [] __alloc_skb+0x4c2/0x5f0 [ 11.472474][ T235] softirqs last disabled at (12914): [] __alloc_skb+0x4c2/0x5f0 [ 11.472476][ T235] Preemption disabled at: [ 11.472477][ T235] [] vprintk_emit+0x31b/0x3e0 [ 11.472483][ T235] CPU: 2 UID: 0 PID: 235 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.472487][ T235] Tainted: [W]=WARN [ 11.472488][ T235] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.472489][ T235] Call Trace: [ 11.472491][ T235] [ 11.472493][ T235] dump_stack_lvl+0x6f/0xa0 [ 11.472499][ T235] ? vprintk_emit+0x31b/0x3e0 [ 11.472501][ T235] __might_resched.cold+0x1fe/0x2c1 [ 11.472506][ T235] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.472510][ T235] ? __kmalloc_noprof+0xdb/0x760 [ 11.472515][ T235] __kmalloc_noprof+0x443/0x760 [ 11.472518][ T235] ? alloc_buf.isra.0+0x4b/0x260 [ 11.472523][ T235] ? do_raw_spin_unlock+0x59/0x250 [ 11.472526][ T235] alloc_buf.isra.0+0x4b/0x260 [ 11.472530][ T235] put_chars+0x1e1/0x2f0 [ 11.472532][ T235] ? __send_to_port+0x420/0x420 [ 11.472537][ T235] ? validate_chain+0x34a/0xc20 [ 11.472540][ T235] hvc_console_print+0x292/0x780 [ 11.472543][ T235] ? mark_usage+0x61/0x170 [ 11.472545][ T235] ? __lock_acquire+0x518/0xc20 [ 11.472547][ T235] ? __lock_acquire+0x518/0xc20 [ 11.472551][ T235] ? hvc_write+0x3a0/0x3a0 [ 11.472553][ T235] ? console_emit_next_record+0x1df/0x4c0 [ 11.472556][ T235] ? rcu_is_watching+0x16/0xd0 [ 11.472560][ T235] ? lock_acquire+0x13c/0x160 [ 11.472564][ T235] console_emit_next_record+0x22f/0x4c0 [ 11.472568][ T235] ? devkmsg_read+0x4b0/0x4b0 [ 11.472570][ T235] ? console_flush_one_record+0x106/0x710 [ 11.472573][ T235] ? rcu_is_watching+0x16/0xd0 [ 11.472576][ T235] ? lock_acquire+0x13c/0x160 [ 11.472580][ T235] console_flush_one_record+0x46f/0x710 [ 11.472584][ T235] ? console_emit_next_record+0x4c0/0x4c0 [ 11.472586][ T235] ? __lock_acquire+0x518/0xc20 [ 11.472591][ T235] console_unlock+0xee/0x1f0 [ 11.472594][ T235] ? console_flush_one_record+0x710/0x710 [ 11.472596][ T235] ? rcu_is_watching+0x16/0xd0 [ 11.472598][ T235] ? lock_acquire+0x60/0x160 [ 11.472602][ T235] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.472603][ T235] ? vprintk_emit+0x320/0x3e0 [ 11.472607][ T235] vprintk_emit+0x37c/0x3e0 [ 11.472611][ T235] ? wake_up_klogd_work_func+0x90/0x90 [ 11.472613][ T235] ? __lock_release.isra.0+0x69/0x1a0 [ 11.472615][ T235] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.472619][ T235] ? mark_held_locks+0x40/0x70 [ 11.472623][ T235] _printk+0xc7/0x100 [ 11.472626][ T235] ? snapshot_read.cold+0x21/0x21 [ 11.472630][ T235] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.472643][ T235] ? do_setlink.isra.0+0xa31/0x2750 [ 11.472645][ T235] ? rtnl_newlink+0x9f1/0x11c0 [ 11.472646][ T235] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.472650][ T235] br_set_state+0x22f/0x430 [bridge] [ 11.472660][ T235] br_init_port+0xc4/0x200 [bridge] [ 11.472669][ T235] new_nbp+0x39c/0x580 [bridge] [ 11.472678][ T235] br_add_if+0x212/0x1320 [bridge] [ 11.472686][ T235] ? is_bpf_text_address+0x72/0x110 [ 11.472690][ T235] ? kernel_text_address+0x149/0x170 [ 11.472693][ T235] ? __kernel_text_address+0x12/0x30 [ 11.472697][ T235] do_set_master+0x357/0x580 [ 11.472702][ T235] do_setlink.isra.0+0xa31/0x2750 [ 11.472705][ T235] ? stack_trace_save+0x93/0xc0 [ 11.472708][ T235] ? rtnl_link_get_size+0x350/0x350 [ 11.472709][ T235] ? rcu_read_lock_any_held+0x66/0x90 [ 11.472712][ T235] ? stack_depot_save_flags+0x38e/0x790 [ 11.472715][ T235] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.472718][ T235] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.472720][ T235] ? validate_chain+0x38b/0xc20 [ 11.472722][ T235] ? kasan_save_stack+0x3d/0x50 [ 11.472725][ T235] ? kasan_save_stack+0x2f/0x50 [ 11.472727][ T235] ? kasan_save_track+0x14/0x30 [ 11.472730][ T235] ? __lock_acquire+0x518/0xc20 [ 11.472732][ T235] ? netlink_seq_next+0x4e/0x60 [ 11.472735][ T235] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.472739][ T235] ? lock_acquire.part.0+0xd4/0x280 [ 11.472741][ T235] ? rtnl_newlink+0x9a8/0x11c0 [ 11.472744][ T235] ? rcu_is_watching+0x16/0xd0 [ 11.472746][ T235] ? lock_acquire+0x13c/0x160 [ 11.472748][ T235] ? rcu_is_watching+0x16/0xd0 [ 11.472749][ T235] ? rcu_is_watching+0x16/0xd0 [ 11.472752][ T235] ? trace_contention_end+0xb3/0x180 [ 11.472756][ T235] ? __mutex_lock+0x1db/0x1ea0 [ 11.472757][ T235] ? __mutex_lock+0x9a3/0x1ea0 [ 11.472759][ T235] ? rtnl_newlink+0x9a8/0x11c0 [ 11.472763][ T235] ? ww_mutex_lock+0x160/0x160 [ 11.472764][ T235] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.472769][ T235] ? __rtnl_newlink+0x3fa/0xa50 [ 11.472775][ T235] rtnl_newlink+0x9f1/0x11c0 [ 11.472780][ T235] ? rtnl_bridge_getlink+0x850/0x850 [ 11.472782][ T235] ? __lock_acquire+0x518/0xc20 [ 11.472787][ T235] ? lock_acquire.part.0+0xd4/0x280 [ 11.472789][ T235] ? find_held_lock+0x2b/0x80 [ 11.472791][ T235] ? rtnl_bridge_getlink+0x850/0x850 [ 11.472793][ T235] ? __lock_release.isra.0+0x69/0x1a0 [ 11.472796][ T235] ? rtnl_bridge_getlink+0x850/0x850 [ 11.472798][ T235] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.472801][ T235] ? rtnl_link_fill+0x920/0x920 [ 11.472803][ T235] ? __lock_acquire+0x518/0xc20 [ 11.472807][ T235] ? lock_acquire.part.0+0xd4/0x280 [ 11.472809][ T235] ? find_held_lock+0x2b/0x80 [ 11.472813][ T235] netlink_rcv_skb+0x14e/0x3a0 [ 11.472814][ T235] ? rtnl_link_fill+0x920/0x920 [ 11.472817][ T235] ? netlink_ack+0xcf0/0xcf0 [ 11.472824][ T235] ? netlink_deliver_tap+0xc5/0x330 [ 11.472825][ T235] ? netlink_deliver_tap+0x13c/0x330 [ 11.472829][ T235] netlink_unicast+0x486/0x750 [ 11.472833][ T235] ? netlink_attachskb+0x810/0x810 [ 11.472836][ T235] ? __lock_acquire+0x518/0xc20 [ 11.472840][ T235] netlink_sendmsg+0x735/0xc60 [ 11.472844][ T235] ? netlink_unicast+0x750/0x750 [ 11.472848][ T235] ? __might_fault+0x97/0x140 [ 11.472853][ T235] ____sys_sendmsg+0x415/0x880 [ 11.472856][ T235] ? copy_msghdr_from_user+0x279/0x420 [ 11.472858][ T235] ? get_timestamp.constprop.0+0x390/0x390 [ 11.472860][ T235] ? move_addr_to_kernel+0x40/0x40 [ 11.472867][ T235] ___sys_sendmsg+0x14e/0x1d0 [ 11.472870][ T235] ? copy_msghdr_from_user+0x420/0x420 [ 11.472886][ T235] __sys_sendmsg+0x12c/0x1d0 [ 11.472889][ T235] ? __sys_sendmsg_sock+0x20/0x20 [ 11.472895][ T235] ? rcu_is_watching+0x16/0xd0 [ 11.472899][ T235] do_syscall_64+0xff/0x530 [ 11.472901][ T235] ? exc_page_fault+0xee/0x100 [ 11.472905][ T235] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.472913][ T235] RIP: 0033:0x7fd767df154e [ 11.472917][ T235] 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.472919][ T235] RSP: 002b:00007fff9a76ac50 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.472922][ T235] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fd767df154e [ 11.472923][ T235] RDX: 0000000000000000 RSI: 00007fff9a76ad00 RDI: 0000000000000005 [ 11.472924][ T235] RBP: 00007fff9a76ac60 R08: 0000000000000000 R09: 0000000000000000 [ 11.472925][ T235] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a912d88 [ 11.472926][ T235] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff9a76b3c0 [ 11.472932][ T235] [ 11.522262][ T235] br1: port 1(veth1) entered disabled state [ 11.522691][ T235] veth1: entered allmulticast mode [ 11.524716][ T235] veth1: entered promiscuous mode [ 11.538656][ T235] ip (235) used greatest stack depth: 23336 bytes left [ 11.560408][ T72] br1: port 1(veth1) entered blocking state [ 11.560888][ T72] br1: port 1(veth1) entered forwarding state [ 11.585288][ T238] br1: port 2(veth2) entered blocking state [ 11.585648][ T238] br1: port 2(veth2) entered disabled state [ 11.586012][ T238] veth2: entered allmulticast mode [ 11.587590][ T238] veth2: entered promiscuous mode [ 11.620836][ T37] br1: port 2(veth2) entered blocking state [ 11.621180][ T37] br1: port 2(veth2) entered forwarding state [ 17.082046][ T263] GACT probability NOT on [ 17.082064][ T263] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 17.082071][ T263] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 263, name: modprobe [ 17.082072][ T263] preempt_count: 1, expected: 0 [ 17.082073][ T263] RCU nest depth: 0, expected: 0 [ 17.082074][ T263] locks held by modprobe/263: 4, last CPU#1: [ 17.082077][ T263] #0: ffffffff93769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 17.082089][ T263] #1: ffffffff93769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 17.082094][ T263] #2: ffffffff93689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 17.082098][ T263] #3: ffffffff93689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 17.082102][ T263] irq event stamp: 17504 [ 17.082103][ T263] hardirqs last enabled at (17503): [] __down_trylock_console_sem+0x86/0xa0 [ 17.082105][ T263] hardirqs last disabled at (17504): [] console_emit_next_record+0x3d4/0x4c0 [ 17.082107][ T263] softirqs last enabled at (16292): [] handle_softirqs+0x67c/0x900 [ 17.082110][ T263] softirqs last disabled at (15375): [] __irq_exit_rcu+0x145/0x1c0 [ 17.082113][ T263] Preemption disabled at: [ 17.082113][ T263] [] vprintk_emit+0x31b/0x3e0 [ 17.082119][ T263] CPU: 1 UID: 0 PID: 263 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 17.082123][ T263] Tainted: [W]=WARN [ 17.082124][ T263] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.082125][ T263] Call Trace: [ 17.082127][ T263] [ 17.082128][ T263] dump_stack_lvl+0x6f/0xa0 [ 17.082134][ T263] ? vprintk_emit+0x31b/0x3e0 [ 17.082136][ T263] __might_resched.cold+0x1fe/0x2c1 [ 17.082140][ T263] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 17.082144][ T263] ? __kmalloc_noprof+0xdb/0x760 [ 17.082149][ T263] __kmalloc_noprof+0x443/0x760 [ 17.082152][ T263] ? alloc_buf.isra.0+0x4b/0x260 [ 17.082158][ T263] ? do_raw_spin_unlock+0x59/0x250 [ 17.082160][ T263] alloc_buf.isra.0+0x4b/0x260 [ 17.082163][ T263] put_chars+0x1e1/0x2f0 [ 17.082165][ T263] ? desc_read_finalized_seq+0x79/0x120 [ 17.082168][ T263] ? __send_to_port+0x420/0x420 [ 17.082171][ T263] ? rcu_read_lock_any_held+0x3c/0x90 [ 17.082174][ T263] ? validate_chain+0x38b/0xc20 [ 17.082178][ T263] hvc_console_print+0x292/0x780 [ 17.082181][ T263] ? __lock_acquire+0x518/0xc20 [ 17.082182][ T263] ? __lock_acquire+0x518/0xc20 [ 17.082187][ T263] ? hvc_write+0x3a0/0x3a0 [ 17.082188][ T263] ? console_emit_next_record+0x1df/0x4c0 [ 17.082191][ T263] ? rcu_is_watching+0x16/0xd0 [ 17.082194][ T263] ? lock_acquire+0x13c/0x160 [ 17.082198][ T263] console_emit_next_record+0x22f/0x4c0 [ 17.082202][ T263] ? devkmsg_read+0x4b0/0x4b0 [ 17.082204][ T263] ? console_flush_one_record+0x106/0x710 [ 17.082207][ T263] ? rcu_is_watching+0x16/0xd0 [ 17.082210][ T263] ? lock_acquire+0x13c/0x160 [ 17.082214][ T263] console_flush_one_record+0x46f/0x710 [ 17.082218][ T263] ? console_emit_next_record+0x4c0/0x4c0 [ 17.082220][ T263] ? __lock_acquire+0x518/0xc20 [ 17.082225][ T263] console_unlock+0xee/0x1f0 [ 17.082228][ T263] ? console_flush_one_record+0x710/0x710 [ 17.082230][ T263] ? rcu_is_watching+0x16/0xd0 [ 17.082232][ T263] ? lock_acquire+0x60/0x160 [ 17.082236][ T263] ? __down_trylock_console_sem+0x5e/0xa0 [ 17.082238][ T263] ? vprintk_emit+0x320/0x3e0 [ 17.082241][ T263] vprintk_emit+0x37c/0x3e0 [ 17.082245][ T263] ? wake_up_klogd_work_func+0x90/0x90 [ 17.082251][ T263] ? llc_exit+0x3e0/0x3e0 [llc] [ 17.082254][ T263] _printk+0xc7/0x100 [ 17.082258][ T263] ? snapshot_read.cold+0x21/0x21 [ 17.082264][ T263] ? do_one_initcall+0x113/0x4f0 [ 17.082267][ T263] gact_init_module+0x15/0x1000 [act_gact] [ 17.082270][ T263] do_one_initcall+0x124/0x4f0 [ 17.082273][ T263] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 17.082277][ T263] ? do_init_module+0xae/0x8a0 [ 17.082280][ T263] ? kasan_unpoison+0x44/0x70 [ 17.082285][ T263] do_init_module+0x2ad/0x8a0 [ 17.082289][ T263] ? lockdep_hardirqs_on+0x91/0x130 [ 17.082293][ T263] ? free_module+0x470/0x470 [ 17.082297][ T263] ? kfree+0x123/0x590 [ 17.082301][ T263] load_module+0x1424/0x1bb0 [ 17.082308][ T263] ? layout_and_allocate.isra.0+0x680/0x680 [ 17.082312][ T263] ? __ia32_sys_fsconfig+0x180/0x180 [ 17.082315][ T263] ? ovl_real_file+0x172/0x1e0 [ 17.082319][ T263] ? backing_file_read_iter+0x541/0x790 [ 17.082322][ T263] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.082326][ T263] init_module_from_file+0x11f/0x1a0 [ 17.082329][ T263] ? __do_sys_init_module+0x220/0x220 [ 17.082338][ T263] ? __rwlock_init+0x150/0x150 [ 17.082341][ T263] ? do_raw_spin_unlock+0x59/0x250 [ 17.082345][ T263] idempotent_init_module+0x37b/0x7c0 [ 17.082349][ T263] ? init_module_from_file+0x1a0/0x1a0 [ 17.082355][ T263] ? rcu_is_watching+0x16/0xd0 [ 17.082357][ T263] ? cap_capable+0x1d7/0x3d0 [ 17.082364][ T263] __x64_sys_finit_module+0xdf/0x190 [ 17.082366][ T263] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 17.082369][ T263] do_syscall_64+0xff/0x530 [ 17.082371][ T263] ? exc_page_fault+0xee/0x100 [ 17.082374][ T263] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 17.082376][ T263] RIP: 0033:0x7f9126dcf37d [ 17.082380][ T263] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 17.082382][ T263] RSP: 002b:00007fffcf09ade8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 17.082384][ T263] RAX: ffffffffffffffda RBX: 000055d511416810 RCX: 00007f9126dcf37d [ 17.082386][ T263] RDX: 0000000000000000 RSI: 000055d500662ffd RDI: 0000000000000000 [ 17.082387][ T263] RBP: 00007fffcf09ae80 R08: 0000000000000000 R09: 0000000000000000 [ 17.082388][ T263] R10: 0000000000000000 R11: 0000000000000246 R12: 000055d500662ffd [ 17.082389][ T263] R13: 000055d511416960 R14: 0000000000040000 R15: 0000000000000000 [ 17.082395][ T263] [ 26.998171][ C1] [ 26.998194][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.998197][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 26.998199][ C1] preempt_count: 104, expected: 0 [ 26.998201][ C1] RCU nest depth: 0, expected: 0 [ 26.998203][ C1] INFO: lockdep is turned off. [ 26.998204][ C1] irq event stamp: 608554 [ 26.998205][ C1] hardirqs last enabled at (608554): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.998216][ C1] hardirqs last disabled at (608553): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.998219][ C1] softirqs last enabled at (608502): [] handle_softirqs+0x67c/0x900 [ 26.998224][ C1] softirqs last disabled at (608551): [] __irq_exit_rcu+0x145/0x1c0 [ 26.998227][ C1] Preemption disabled at: [ 26.998228][ C1] [<0000000000000000>] 0x0 [ 26.998237][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.998242][ C1] Tainted: [W]=WARN [ 26.998243][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.998246][ C1] Call Trace: [ 26.998248][ C1] [ 26.998251][ C1] dump_stack_lvl+0x6f/0xa0 [ 26.998258][ C1] __might_resched.cold+0x1fe/0x2c1 [ 26.998263][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.998267][ C1] ? __asan_memcpy+0x3c/0x60 [ 26.998271][ C1] ? rcu_is_watching+0x16/0xd0 [ 26.998276][ C1] __kmalloc_noprof+0x443/0x760 [ 26.998280][ C1] ? __rwlock_init+0x150/0x150 [ 26.998284][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 26.998288][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 26.998291][ C1] alloc_buf.isra.0+0x4b/0x260 [ 26.998294][ C1] put_chars+0x1e1/0x2f0 [ 26.998297][ C1] ? __send_to_port+0x420/0x420 [ 26.998299][ C1] ? console_prepend_replay+0x20/0x20 [ 26.998304][ C1] hvc_console_print+0x292/0x780 [ 26.998309][ C1] ? hvc_write+0x3a0/0x3a0 [ 26.998311][ C1] ? rcu_is_watching+0x16/0xd0 [ 26.998313][ C1] ? lock_acquire+0x13c/0x160 [ 26.998317][ C1] console_emit_next_record+0x22f/0x4c0 [ 26.998320][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 26.998323][ C1] ? rcu_is_watching+0x16/0xd0 [ 26.998326][ C1] ? rcu_is_watching+0x16/0xd0 [ 26.998328][ C1] ? lock_acquire+0x13c/0x160 [ 26.998331][ C1] ? console_flush_one_record+0x111/0x710 [ 26.998334][ C1] console_flush_one_record+0x46f/0x710 [ 26.998337][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 26.998341][ C1] console_unlock+0xee/0x1f0 [ 26.998344][ C1] ? lock_acquire+0x13c/0x160 [ 26.998346][ C1] ? console_flush_one_record+0x710/0x710 [ 26.998348][ C1] ? rcu_is_watching+0x16/0xd0 [ 26.998351][ C1] ? lock_release+0x184/0x1f0 [ 26.998353][ C1] ? lock_acquire+0x60/0x160 [ 26.998356][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.998359][ C1] ? vprintk_emit+0x320/0x3e0 [ 26.998362][ C1] vprintk_emit+0x37c/0x3e0 [ 26.998365][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 26.998368][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.998385][ C1] ? lock_release+0x184/0x1f0 [ 26.998388][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.998399][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.998411][ C1] ? is_module_text_address+0x154/0x250 [ 26.998416][ C1] _printk+0xc7/0x100 [ 26.998420][ C1] ? snapshot_read.cold+0x21/0x21 [ 26.998422][ C1] ? arch_stack_walk+0xd7/0x130 [ 26.998428][ C1] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.998431][ C1] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.998434][ C1] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.998437][ C1] mark_lock_irq+0x989/0x9c0 [ 26.998442][ C1] mark_lock+0x1d7/0xa00 [ 26.998445][ C1] mark_usage+0x42/0x170 [ 26.998447][ C1] __lock_acquire+0x388/0xc20 [ 26.998451][ C1] lock_acquire.part.0+0xd4/0x280 [ 26.998453][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.998465][ C1] ? rcu_is_watching+0x16/0xd0 [ 26.998467][ C1] ? lock_acquire+0x13c/0x160 [ 26.998470][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.998481][ C1] _raw_spin_lock+0x33/0x40 [ 26.998484][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.998495][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.998507][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.998518][ C1] call_timer_fn+0x160/0x4d0 [ 26.998522][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 26.998524][ C1] ? debug_object_active_state+0x430/0x430 [ 26.998528][ C1] ? find_held_lock+0x2b/0x80 [ 26.998531][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 26.998534][ C1] ? rcu_is_watching+0x16/0xd0 [ 26.998538][ C1] __run_timers+0x68f/0xaa0 [ 26.998540][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.998553][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.998555][ C1] ? __lock_acquire+0x518/0xc20 [ 26.998559][ C1] ? __rwlock_init+0x150/0x150 [ 26.998563][ C1] run_timer_softirq+0xf0/0x160 [ 26.998566][ C1] ? __run_timers+0xaa0/0xaa0 [ 26.998568][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.998572][ C1] ? rcu_is_watching+0x16/0xd0 [ 26.998574][ C1] handle_softirqs+0x1d3/0x900 [ 26.998577][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 26.998580][ C1] ? _local_bh_enable+0xc0/0xc0 [ 26.998583][ C1] __irq_exit_rcu+0x145/0x1c0 [ 26.998585][ C1] irq_exit_rcu+0xe/0x30 [ 26.998588][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.998591][ C1] [ 26.998592][ C1] [ 26.998593][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 26.998596][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.998599][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.998603][ C1] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 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.998606][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000282 [ 26.998610][ C1] RAX: 0000000000094925 RBX: ff11000001bea380 RCX: ffffffff904f0307 [ 26.998612][ C1] RDX: ff11000001bea380 RSI: ffffffff93238b01 RDI: ffffffff92c8d8e0 [ 26.998613][ C1] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.998615][ C1] R10: 0000000000000001 R11: 0000000000000001 R12: 1ff4000000028fc3 [ 26.998616][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.998619][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.998623][ C1] default_idle+0x9/0x10 [ 26.998626][ C1] default_idle_call+0x6e/0xb0 [ 26.998628][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.998631][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 26.998633][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 26.998636][ C1] ? default_idle_call+0x98/0xb0 [ 26.998638][ C1] ? rcu_is_watching+0x16/0xd0 [ 26.998641][ C1] do_idle+0xd8/0x190 [ 26.998644][ C1] cpu_startup_entry+0x53/0x70 [ 26.998646][ C1] start_secondary+0x204/0x2b0 [ 26.998649][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.998652][ C1] common_startup_64+0x13e/0x148 [ 26.998658][ C1] [ 27.033741][ C1] ======================================================== [ 27.034072][ C1] WARNING: possible irq lock inversion dependency detected [ 27.034457][ C1] 7.2.0-virtme #1 Tainted: G W [ 27.034793][ C1] -------------------------------------------------------- [ 27.035187][ C1] swapper/1/0 just changed the state of lock: [ 27.035445][ C1] ff1100000a5dae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.035953][ C1] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.036347][ C1] (fs_reclaim){+.+.}-{0:0} [ 27.036351][ C1] [ 27.036351][ C1] [ 27.036351][ C1] and interrupts could create inverse lock ordering between them. [ 27.036351][ C1] [ 27.037278][ C1] [ 27.037278][ C1] other info that might help us debug this: [ 27.037666][ C1] Chain exists of: [ 27.037666][ C1] &br->lock --> console_owner --> fs_reclaim [ 27.037666][ C1] [ 27.038233][ C1] Possible interrupt unsafe locking scenario: [ 27.038233][ C1] [ 27.038548][ C1] CPU0 CPU1 [ 27.038835][ C1] ---- ---- [ 27.039057][ C1] lock(fs_reclaim); [ 27.039297][ C1] local_irq_disable(); [ 27.039559][ C1] lock(&br->lock); [ 27.039904][ C1] lock(console_owner); [ 27.040252][ C1] [ 27.040412][ C1] lock(&br->lock); [ 27.040573][ C1] [ 27.040573][ C1] *** DEADLOCK *** [ 27.040573][ C1] [ 27.040958][ C1] locks held by swapper/1/0: 1, last CPU#1: [ 27.041303][ C1] #0: ffa00000001d0c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.041810][ C1] [ 27.041810][ C1] the shortest dependencies between 2nd lock and 1st lock: [ 27.042258][ C1] -> (fs_reclaim){+.+.}-{0:0} { [ 27.042476][ C1] HARDIRQ-ON-W at: [ 27.042718][ C1] __lock_acquire+0x388/0xc20 [ 27.042997][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.043346][ C1] fs_reclaim_acquire+0xd5/0x120 [ 27.043611][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 27.044013][ C1] kthread_create_worker_on_node+0xea/0x210 [ 27.044413][ C1] workqueue_init+0x2a/0x680 [ 27.044760][ C1] kernel_init_freeable+0x2fe/0x630 [ 27.045090][ C1] kernel_init+0x21/0x150 [ 27.045435][ C1] ret_from_fork+0x474/0x6b0 [ 27.045780][ C1] ret_from_fork_asm+0x11/0x20 [ 27.046053][ C1] SOFTIRQ-ON-W at: [ 27.046289][ C1] __lock_acquire+0x388/0xc20 [ 27.046557][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.046902][ C1] fs_reclaim_acquire+0xd5/0x120 [ 27.047176][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 27.047571][ C1] kthread_create_worker_on_node+0xea/0x210 [ 27.047967][ C1] workqueue_init+0x2a/0x680 [ 27.048319][ C1] kernel_init_freeable+0x2fe/0x630 [ 27.048638][ C1] kernel_init+0x21/0x150 [ 27.048987][ C1] ret_from_fork+0x474/0x6b0 [ 27.049334][ C1] ret_from_fork_asm+0x11/0x20 [ 27.049603][ C1] INITIAL USE at: [ 27.049838][ C1] __lock_acquire+0x388/0xc20 [ 27.050118][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.050461][ C1] fs_reclaim_acquire+0xd5/0x120 [ 27.050726][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 27.051129][ C1] kthread_create_worker_on_node+0xea/0x210 [ 27.051525][ C1] workqueue_init+0x2a/0x680 [ 27.051872][ C1] kernel_init_freeable+0x2fe/0x630 [ 27.052144][ C1] kernel_init+0x21/0x150 [ 27.052490][ C1] ret_from_fork+0x474/0x6b0 [ 27.052752][ C1] ret_from_fork_asm+0x11/0x20 [ 27.053097][ C1] } [ 27.053213][ C1] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.053604][ C1] ... acquired at: [ 27.053764][ C1] __lock_acquire+0x518/0xc20 [ 27.054058][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.054271][ C1] fs_reclaim_acquire+0xd5/0x120 [ 27.054555][ C1] __kmalloc_noprof+0xd3/0x760 [ 27.054764][ C1] alloc_buf.isra.0+0x4b/0x260 [ 27.055059][ C1] put_chars+0x1e1/0x2f0 [ 27.055269][ C1] hvc_console_print+0x292/0x780 [ 27.055554][ C1] console_emit_next_record+0x22f/0x4c0 [ 27.055765][ C1] console_flush_one_record+0x46f/0x710 [ 27.056053][ C1] console_unlock+0xee/0x1f0 [ 27.056263][ C1] vprintk_emit+0x37c/0x3e0 [ 27.056547][ C1] _printk+0xc7/0x100 [ 27.056708][ C1] sched_clock_init_late+0x14c/0x170 [ 27.057000][ C1] do_one_initcall+0x124/0x4f0 [ 27.057216][ C1] kernel_init_freeable+0x596/0x630 [ 27.057503][ C1] kernel_init+0x21/0x150 [ 27.057714][ C1] ret_from_fork+0x474/0x6b0 [ 27.058007][ C1] ret_from_fork_asm+0x11/0x20 [ 27.058221][ C1] [ 27.058405][ C1] -> (console_owner){....}-{0:0} { [ 27.058625][ C1] INITIAL USE at: [ 27.058783][ C1] } [ 27.058967][ C1] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.059284][ C1] ... acquired at: [ 27.059513][ C1] __lock_acquire+0x518/0xc20 [ 27.059723][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.060014][ C1] console_lock_spinning_enable+0x5c/0x60 [ 27.060281][ C1] console_emit_next_record+0x1d1/0x4c0 [ 27.060570][ C1] console_flush_one_record+0x46f/0x710 [ 27.060781][ C1] console_unlock+0xee/0x1f0 [ 27.061074][ C1] vprintk_emit+0x37c/0x3e0 [ 27.061287][ C1] _printk+0xc7/0x100 [ 27.061523][ C1] br_set_state+0x22f/0x430 [bridge] [ 27.061744][ C1] br_init_port+0xc4/0x200 [bridge] [ 27.062048][ C1] br_stp_enable_port+0x12/0x50 [bridge] [ 27.062320][ C1] br_port_carrier_check+0x220/0x430 [bridge] [ 27.062673][ C1] br_device_event+0x52d/0x8f0 [bridge] [ 27.062970][ C1] notifier_call_chain+0xae/0x300 [ 27.063186][ C1] netif_state_change+0x139/0x340 [ 27.063478][ C1] __linkwatch_run_queue+0x34c/0x750 [ 27.063694][ C1] linkwatch_event+0x7f/0xb0 [ 27.063984][ C1] process_one_work+0xe3e/0x1560 [ 27.064197][ C1] worker_thread+0x4f1/0xd60 [ 27.064489][ C1] kthread+0x367/0x460 [ 27.064649][ C1] ret_from_fork+0x474/0x6b0 [ 27.064864][ C1] ret_from_fork_asm+0x11/0x20 [ 27.065157][ C1] [ 27.065265][ C1] -> (&br->lock){+.-.}-{3:3} { [ 27.065556][ C1] HARDIRQ-ON-W at: [ 27.065721][ C1] __lock_acquire+0x388/0xc20 [ 27.066073][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.066341][ C1] _raw_spin_lock_bh+0x38/0x50 [ 27.066689][ C1] br_stp_enable_bridge+0x23/0x340 [bridge] [ 27.067103][ C1] br_dev_open+0x94/0x120 [bridge] [ 27.067385][ C1] __dev_open+0x2f3/0x610 [ 27.067729][ C1] __dev_change_flags+0x4a4/0x660 [ 27.068077][ C1] rtnl_configure_link+0x15f/0x410 [ 27.068347][ C1] rtnl_newlink_create+0x2ec/0x750 [ 27.068691][ C1] __rtnl_newlink+0x22b/0xa50 [ 27.068957][ C1] rtnl_newlink+0x9f1/0x11c0 [ 27.069311][ C1] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.069659][ C1] netlink_rcv_skb+0x14e/0x3a0 [ 27.069926][ C1] netlink_unicast+0x486/0x750 [ 27.070274][ C1] netlink_sendmsg+0x735/0xc60 [ 27.070623][ C1] ____sys_sendmsg+0x415/0x880 [ 27.070890][ C1] ___sys_sendmsg+0x14e/0x1d0 [ 27.071243][ C1] __sys_sendmsg+0x12c/0x1d0 [ 27.071513][ C1] do_syscall_64+0xff/0x530 [ 27.071859][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.072257][ C1] IN-SOFTIRQ-W at: [ 27.072418][ C1] __lock_acquire+0x388/0xc20 [ 27.072759][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.073032][ C1] _raw_spin_lock+0x33/0x40 [ 27.073380][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.073786][ C1] call_timer_fn+0x160/0x4d0 [ 27.074057][ C1] __run_timers+0x68f/0xaa0 [ 27.074402][ C1] run_timer_softirq+0xf0/0x160 [ 27.074771][ C1] handle_softirqs+0x1d3/0x900 [ 27.075047][ C1] __irq_exit_rcu+0x145/0x1c0 [ 27.075392][ C1] irq_exit_rcu+0xe/0x30 [ 27.075735][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.076058][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.076451][ C1] pv_native_safe_halt+0xf/0x10 [ 27.076797][ C1] default_idle+0x9/0x10 [ 27.077068][ C1] default_idle_call+0x6e/0xb0 [ 27.077408][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.077806][ C1] do_idle+0xd8/0x190 [ 27.078024][ C1] cpu_startup_entry+0x53/0x70 [ 27.078366][ C1] start_secondary+0x204/0x2b0 [ 27.078636][ C1] common_startup_64+0x13e/0x148 [ 27.078989][ C1] INITIAL USE at: [ 27.079147][ C1] __lock_acquire+0x388/0xc20 [ 27.079490][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.079835][ C1] _raw_spin_lock_bh+0x38/0x50 [ 27.080102][ C1] br_stp_enable_bridge+0x23/0x340 [bridge] [ 27.080511][ C1] br_dev_open+0x94/0x120 [bridge] [ 27.080881][ C1] __dev_open+0x2f3/0x610 [ 27.081151][ C1] __dev_change_flags+0x4a4/0x660 [ 27.081486][ C1] rtnl_configure_link+0x15f/0x410 [ 27.081826][ C1] rtnl_newlink_create+0x2ec/0x750 [ 27.082091][ C1] __rtnl_newlink+0x22b/0xa50 [ 27.082427][ C1] rtnl_newlink+0x9f1/0x11c0 [ 27.082689][ C1] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.083028][ C1] netlink_rcv_skb+0x14e/0x3a0 [ 27.083365][ C1] netlink_unicast+0x486/0x750 [ 27.083625][ C1] netlink_sendmsg+0x735/0xc60 [ 27.083963][ C1] ____sys_sendmsg+0x415/0x880 [ 27.084306][ C1] ___sys_sendmsg+0x14e/0x1d0 [ 27.084567][ C1] __sys_sendmsg+0x12c/0x1d0 [ 27.084906][ C1] do_syscall_64+0xff/0x530 [ 27.085172][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.085563][ C1] } [ 27.085671][ C1] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.086064][ C1] ... acquired at: [ 27.086291][ C1] mark_lock+0x1d7/0xa00 [ 27.086518][ C1] mark_usage+0x42/0x170 [ 27.093439][ C1] __lock_acquire+0x388/0xc20 [ 27.093652][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.093938][ C1] _raw_spin_lock+0x33/0x40 [ 27.094148][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.094501][ C1] call_timer_fn+0x160/0x4d0 [ 27.094711][ C1] __run_timers+0x68f/0xaa0 [ 27.094999][ C1] run_timer_softirq+0xf0/0x160 [ 27.095207][ C1] handle_softirqs+0x1d3/0x900 [ 27.095493][ C1] __irq_exit_rcu+0x145/0x1c0 [ 27.095701][ C1] irq_exit_rcu+0xe/0x30 [ 27.095986][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.096242][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.096578][ C1] pv_native_safe_halt+0xf/0x10 [ 27.096864][ C1] default_idle+0x9/0x10 [ 27.097073][ C1] default_idle_call+0x6e/0xb0 [ 27.097359][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.097618][ C1] do_idle+0xd8/0x190 [ 27.097851][ C1] cpu_startup_entry+0x53/0x70 [ 27.098064][ C1] start_secondary+0x204/0x2b0 [ 27.098350][ C1] common_startup_64+0x13e/0x148 [ 27.098557][ C1] [ 27.098662][ C1] [ 27.098662][ C1] stack backtrace: [ 27.098996][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.099001][ C1] Tainted: [W]=WARN [ 27.099002][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.099004][ C1] Call Trace: [ 27.099005][ C1] [ 27.099007][ C1] dump_stack_lvl+0x6f/0xa0 [ 27.099012][ C1] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.099016][ C1] mark_lock_irq+0x989/0x9c0 [ 27.099021][ C1] mark_lock+0x1d7/0xa00 [ 27.099024][ C1] mark_usage+0x42/0x170 [ 27.099026][ C1] __lock_acquire+0x388/0xc20 [ 27.099030][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.099032][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.099046][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.099049][ C1] ? lock_acquire+0x13c/0x160 [ 27.099052][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.099064][ C1] _raw_spin_lock+0x33/0x40 [ 27.099066][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.099078][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.099089][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.099102][ C1] call_timer_fn+0x160/0x4d0 [ 27.099105][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 27.099108][ C1] ? debug_object_active_state+0x430/0x430 [ 27.099111][ C1] ? find_held_lock+0x2b/0x80 [ 27.099115][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 27.099118][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.099121][ C1] __run_timers+0x68f/0xaa0 [ 27.099124][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.099136][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.099139][ C1] ? __lock_acquire+0x518/0xc20 [ 27.099143][ C1] ? __rwlock_init+0x150/0x150 [ 27.099147][ C1] run_timer_softirq+0xf0/0x160 [ 27.099150][ C1] ? __run_timers+0xaa0/0xaa0 [ 27.099153][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.099156][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.099159][ C1] handle_softirqs+0x1d3/0x900 [ 27.099162][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 27.099164][ C1] ? _local_bh_enable+0xc0/0xc0 [ 27.099167][ C1] __irq_exit_rcu+0x145/0x1c0 [ 27.099170][ C1] irq_exit_rcu+0xe/0x30 [ 27.099172][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.099174][ C1] [ 27.099175][ C1] [ 27.099176][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 27.099179][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.099181][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.099184][ C1] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 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 [ 27.099187][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000282 [ 27.099189][ C1] RAX: 0000000000094925 RBX: ff11000001bea380 RCX: ffffffff904f0307 [ 27.099191][ C1] RDX: ff11000001bea380 RSI: ffffffff93238b01 RDI: ffffffff92c8d8e0 [ 27.099193][ C1] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.099194][ C1] R10: 0000000000000001 R11: 0000000000000001 R12: 1ff4000000028fc3 [ 27.099195][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.099198][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.099201][ C1] default_idle+0x9/0x10 [ 27.099204][ C1] default_idle_call+0x6e/0xb0 [ 27.099206][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.099209][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 27.099211][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 27.099214][ C1] ? default_idle_call+0x98/0xb0 [ 27.099216][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.099219][ C1] do_idle+0xd8/0x190 [ 27.099222][ C1] cpu_startup_entry+0x53/0x70 [ 27.099224][ C1] start_secondary+0x204/0x2b0 [ 27.099226][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.099230][ C1] common_startup_64+0x13e/0x148 [ 27.099234][ C1] [ 29.596908][ T482] br1: port 2(veth2) entered disabled state [ 29.596924][ T482] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.596927][ T482] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 482, name: ip [ 29.596928][ T482] preempt_count: 202, expected: 0 [ 29.596929][ T482] RCU nest depth: 0, expected: 0 [ 29.596930][ T482] INFO: lockdep is turned off. [ 29.596931][ T482] irq event stamp: 0 [ 29.596932][ T482] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 29.596937][ T482] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 29.596943][ T482] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 29.596945][ T482] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 29.596947][ T482] Preemption disabled at: [ 29.596948][ T482] [<0000000000000000>] 0x0 [ 29.596952][ T482] CPU: 0 UID: 0 PID: 482 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.596956][ T482] Tainted: [W]=WARN [ 29.596957][ T482] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.596959][ T482] Call Trace: [ 29.596960][ T482] [ 29.596962][ T482] dump_stack_lvl+0x6f/0xa0 [ 29.596967][ T482] __might_resched.cold+0x1fe/0x2c1 [ 29.596971][ T482] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.596975][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.596979][ T482] __kmalloc_noprof+0x443/0x760 [ 29.596983][ T482] ? __rwlock_init+0x150/0x150 [ 29.596990][ T482] ? alloc_buf.isra.0+0x4b/0x260 [ 29.596993][ T482] ? do_raw_spin_unlock+0x59/0x250 [ 29.596995][ T482] alloc_buf.isra.0+0x4b/0x260 [ 29.596997][ T482] put_chars+0x1e1/0x2f0 [ 29.596999][ T482] ? __send_to_port+0x420/0x420 [ 29.597001][ T482] ? printk_get_next_message+0x240/0x650 [ 29.597004][ T482] hvc_console_print+0x292/0x780 [ 29.597008][ T482] ? hvc_write+0x3a0/0x3a0 [ 29.597010][ T482] ? __rwlock_init+0x150/0x150 [ 29.597012][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597014][ T482] ? lock_acquire+0x13c/0x160 [ 29.597016][ T482] console_emit_next_record+0x22f/0x4c0 [ 29.597019][ T482] ? devkmsg_read+0x4b0/0x4b0 [ 29.597021][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597022][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597024][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597026][ T482] ? lock_acquire+0x13c/0x160 [ 29.597027][ T482] ? console_flush_one_record+0x111/0x710 [ 29.597029][ T482] console_flush_one_record+0x46f/0x710 [ 29.597031][ T482] ? console_emit_next_record+0x4c0/0x4c0 [ 29.597034][ T482] console_unlock+0xee/0x1f0 [ 29.597036][ T482] ? lock_acquire+0x13c/0x160 [ 29.597037][ T482] ? console_flush_one_record+0x710/0x710 [ 29.597039][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597041][ T482] ? lock_release+0x184/0x1f0 [ 29.597042][ T482] ? lock_acquire+0x60/0x160 [ 29.597043][ T482] ? __rcu_read_lock+0x30/0x70 [ 29.597045][ T482] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.597047][ T482] ? vprintk_emit+0x320/0x3e0 [ 29.597049][ T482] vprintk_emit+0x37c/0x3e0 [ 29.597052][ T482] ? wake_up_klogd_work_func+0x90/0x90 [ 29.597054][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597055][ T482] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.597058][ T482] ? trace_hardirqs_on+0x36/0x40 [ 29.597059][ T482] _printk+0xc7/0x100 [ 29.597063][ T482] ? snapshot_read.cold+0x21/0x21 [ 29.597065][ T482] ? ipv6_mc_down+0x17e/0x2d0 [ 29.597072][ T482] br_set_state+0x22f/0x430 [bridge] [ 29.597085][ T482] br_stp_disable_port+0x11f/0x390 [bridge] [ 29.597093][ T482] br_device_event+0x7b5/0x8f0 [bridge] [ 29.597101][ T482] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 29.597105][ T482] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 29.597107][ T482] ? addrconf_notify+0x2c8/0xf30 [ 29.597111][ T482] notifier_call_chain+0xae/0x300 [ 29.597114][ T482] __dev_notify_flags+0x23c/0x3c0 [ 29.597118][ T482] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.597119][ T482] ? netif_change_name+0x8c0/0x8c0 [ 29.597121][ T482] ? netif_set_allmulti+0x250/0x250 [ 29.597123][ T482] ? __kernel_text_address+0x12/0x30 [ 29.597126][ T482] ? write_profile+0xf0/0xf0 [ 29.597128][ T482] ? unwind_get_return_address+0x67/0xd0 [ 29.597131][ T482] netif_change_flags+0xfe/0x190 [ 29.597133][ T482] do_setlink.isra.0+0x1cc3/0x2750 [ 29.597136][ T482] ? stack_trace_save+0x93/0xc0 [ 29.597137][ T482] ? rtnl_link_get_size+0x350/0x350 [ 29.597138][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597140][ T482] ? stack_depot_save_flags+0x1bc/0x790 [ 29.597144][ T482] ? kasan_save_stack+0x3d/0x50 [ 29.597147][ T482] ? kasan_save_stack+0x2f/0x50 [ 29.597149][ T482] ? kasan_save_track+0x14/0x30 [ 29.597150][ T482] ? __kasan_kmalloc+0x7b/0x90 [ 29.597152][ T482] ? __kmalloc_cache_noprof+0x29a/0x620 [ 29.597153][ T482] ? rtnl_newlink+0x124/0x11c0 [ 29.597154][ T482] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.597155][ T482] ? netlink_rcv_skb+0x14e/0x3a0 [ 29.597158][ T482] ? netlink_unicast+0x486/0x750 [ 29.597159][ T482] ? netlink_sendmsg+0x735/0xc60 [ 29.597161][ T482] ? ____sys_sendmsg+0x415/0x880 [ 29.597163][ T482] ? ___sys_sendmsg+0x14e/0x1d0 [ 29.597165][ T482] ? __sys_sendmsg+0x12c/0x1d0 [ 29.597166][ T482] ? do_syscall_64+0xff/0x530 [ 29.597168][ T482] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.597171][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597173][ T482] ? lock_acquire+0x13c/0x160 [ 29.597174][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597176][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597177][ T482] ? trace_contention_end+0xb3/0x180 [ 29.597181][ T482] ? __mutex_lock+0x1db/0x1ea0 [ 29.597182][ T482] ? __mutex_lock+0x9a3/0x1ea0 [ 29.597183][ T482] ? rtnl_newlink+0x9a8/0x11c0 [ 29.597185][ T482] ? ww_mutex_lock+0x160/0x160 [ 29.597187][ T482] ? __rtnl_newlink+0x3fa/0xa50 [ 29.597189][ T482] rtnl_newlink+0x9f1/0x11c0 [ 29.597191][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597193][ T482] ? rtnl_bridge_getlink+0x850/0x850 [ 29.597194][ T482] ? lock_acquire+0x13c/0x160 [ 29.597195][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597197][ T482] ? is_bpf_text_address+0x3e/0x110 [ 29.597201][ T482] ? is_bpf_text_address+0x72/0x110 [ 29.597202][ T482] ? kernel_text_address+0x149/0x170 [ 29.597204][ T482] ? __kernel_text_address+0x12/0x30 [ 29.597205][ T482] ? write_profile+0xf0/0xf0 [ 29.597206][ T482] ? unwind_get_return_address+0x67/0xd0 [ 29.597207][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597209][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597211][ T482] ? cap_capable+0x1d7/0x3d0 [ 29.597214][ T482] ? lock_acquire+0x13c/0x160 [ 29.597216][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597218][ T482] ? rtnl_bridge_getlink+0x850/0x850 [ 29.597219][ T482] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.597221][ T482] ? rtnl_link_fill+0x920/0x920 [ 29.597222][ T482] ? filter_irq_stacks+0xd0/0xd0 [ 29.597223][ T482] ? stack_depot_save_flags+0x1bc/0x790 [ 29.597226][ T482] netlink_rcv_skb+0x14e/0x3a0 [ 29.597228][ T482] ? rtnl_link_fill+0x920/0x920 [ 29.597229][ T482] ? lock_release+0x184/0x1f0 [ 29.597230][ T482] ? netlink_ack+0xcf0/0xcf0 [ 29.597232][ T482] ? __netlink_lookup+0xb9/0x6b0 [ 29.597234][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597236][ T482] ? lock_release+0x184/0x1f0 [ 29.597238][ T482] netlink_unicast+0x486/0x750 [ 29.597240][ T482] ? netlink_attachskb+0x810/0x810 [ 29.597241][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597243][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597245][ T482] ? lock_acquire+0x13c/0x160 [ 29.597246][ T482] netlink_sendmsg+0x735/0xc60 [ 29.597248][ T482] ? netlink_unicast+0x750/0x750 [ 29.597250][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597252][ T482] ? lock_release+0x184/0x1f0 [ 29.597254][ T482] ____sys_sendmsg+0x415/0x880 [ 29.597255][ T482] ? copy_msghdr_from_user+0x279/0x420 [ 29.597257][ T482] ? get_timestamp.constprop.0+0x390/0x390 [ 29.597259][ T482] ? move_addr_to_kernel+0x40/0x40 [ 29.597260][ T482] ? filter_irq_stacks+0xd0/0xd0 [ 29.597263][ T482] ___sys_sendmsg+0x14e/0x1d0 [ 29.597265][ T482] ? copy_msghdr_from_user+0x420/0x420 [ 29.597270][ T482] __sys_sendmsg+0x12c/0x1d0 [ 29.597271][ T482] ? __sys_sendmsg_sock+0x20/0x20 [ 29.597274][ T482] ? rcu_is_watching+0x16/0xd0 [ 29.597276][ T482] do_syscall_64+0xff/0x530 [ 29.597277][ T482] ? exc_page_fault+0xee/0x100 [ 29.597280][ T482] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.597281][ T482] RIP: 0033:0x7fb18454554e [ 29.597283][ T482] 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 [ 29.597285][ T482] RSP: 002b:00007ffd492da5a0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 29.597289][ T482] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fb18454554e [ 29.597290][ T482] RDX: 0000000000000000 RSI: 00007ffd492da650 RDI: 0000000000000005 [ 29.597291][ T482] RBP: 00007ffd492da5b0 R08: 0000000000000000 R09: 0000000000000000 [ 29.597292][ T482] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a912d9b [ 29.597293][ T482] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd492dad10 [ 29.597295][ T482] [ 29.632797][ T482] ip (482) used greatest stack depth: 23016 bytes left [ 29.644439][ T483] veth2: left allmulticast mode [ 29.644616][ T483] veth2: left promiscuous mode [ 29.644828][ T483] br1: port 2(veth2) entered disabled state [ 29.662498][ T484] br1: port 1(veth1) entered disabled state [ 29.685888][ T486] veth1: left allmulticast mode [ 29.686115][ T486] veth1: left promiscuous mode [ 29.686376][ T486] br1: port 1(veth1) entered disabled state