[ 10.977157][ T224] 8021q: 802.1Q VLAN Support v1.8 [ 10.977178][ T224] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.977180][ T224] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 224, name: modprobe [ 10.977182][ T224] preempt_count: 1, expected: 0 [ 10.977183][ T224] RCU nest depth: 0, expected: 0 [ 10.977184][ T224] locks held by modprobe/224: 4, last CPU#2: [ 10.977186][ T224] #0: ffffffff92969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.977199][ T224] #1: ffffffff92969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.977203][ T224] #2: ffffffff92889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.977207][ T224] #3: ffffffff92889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.977211][ T224] irq event stamp: 26632 [ 10.977212][ T224] hardirqs last enabled at (26631): [] __down_trylock_console_sem+0x86/0xa0 [ 10.977214][ T224] hardirqs last disabled at (26632): [] console_emit_next_record+0x3d4/0x4c0 [ 10.977216][ T224] softirqs last enabled at (23468): [] handle_softirqs+0x67c/0x900 [ 10.977219][ T224] softirqs last disabled at (23461): [] __irq_exit_rcu+0x145/0x1c0 [ 10.977222][ T224] Preemption disabled at: [ 10.977222][ T224] [] vprintk_emit+0x31b/0x3e0 [ 10.977228][ T224] CPU: 2 UID: 0 PID: 224 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.977232][ T224] Tainted: [W]=WARN [ 10.977233][ T224] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.977235][ T224] Call Trace: [ 10.977236][ T224] [ 10.977238][ T224] dump_stack_lvl+0x6f/0xa0 [ 10.977243][ T224] ? vprintk_emit+0x31b/0x3e0 [ 10.977246][ T224] __might_resched.cold+0x1fe/0x2c1 [ 10.977250][ T224] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.977254][ T224] ? __kmalloc_noprof+0xdb/0x760 [ 10.977259][ T224] __kmalloc_noprof+0x443/0x760 [ 10.977261][ T224] ? alloc_buf.isra.0+0x4b/0x260 [ 10.977267][ T224] ? do_raw_spin_unlock+0x59/0x250 [ 10.977269][ T224] alloc_buf.isra.0+0x4b/0x260 [ 10.977273][ T224] put_chars+0x1e1/0x2f0 [ 10.977275][ T224] ? __send_to_port+0x420/0x420 [ 10.977277][ T224] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.977281][ T224] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.977284][ T224] ? validate_chain+0x38b/0xc20 [ 10.977288][ T224] hvc_console_print+0x292/0x780 [ 10.977291][ T224] ? __lock_acquire+0x518/0xc20 [ 10.977295][ T224] ? hvc_write+0x3a0/0x3a0 [ 10.977298][ T224] ? rcu_is_watching+0x16/0xd0 [ 10.977303][ T224] console_emit_next_record+0x22f/0x4c0 [ 10.977306][ T224] ? devkmsg_read+0x4b0/0x4b0 [ 10.977308][ T224] ? console_flush_one_record+0x106/0x710 [ 10.977311][ T224] ? rcu_is_watching+0x16/0xd0 [ 10.977314][ T224] ? lock_acquire+0x13c/0x160 [ 10.977317][ T224] console_flush_one_record+0x46f/0x710 [ 10.977321][ T224] ? console_emit_next_record+0x4c0/0x4c0 [ 10.977323][ T224] ? __lock_acquire+0x518/0xc20 [ 10.977328][ T224] console_unlock+0xee/0x1f0 [ 10.977331][ T224] ? console_flush_one_record+0x710/0x710 [ 10.977333][ T224] ? rcu_is_watching+0x16/0xd0 [ 10.977335][ T224] ? lock_acquire+0x60/0x160 [ 10.977338][ T224] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.977340][ T224] ? vprintk_emit+0x320/0x3e0 [ 10.977343][ T224] vprintk_emit+0x37c/0x3e0 [ 10.977347][ T224] ? wake_up_klogd_work_func+0x90/0x90 [ 10.977352][ T224] ? vlan_netlink_fini+0x960/0x960 [8021q] [ 10.977356][ T224] _printk+0xc7/0x100 [ 10.977360][ T224] ? snapshot_read.cold+0x21/0x21 [ 10.977366][ T224] ? do_one_initcall+0x113/0x4f0 [ 10.977369][ T224] vlan_proto_init+0x24/0x90 [8021q] [ 10.977372][ T224] do_one_initcall+0x124/0x4f0 [ 10.977375][ T224] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 10.977379][ T224] ? do_init_module+0xae/0x8a0 [ 10.977382][ T224] ? kasan_unpoison+0x44/0x70 [ 10.977387][ T224] do_init_module+0x2ad/0x8a0 [ 10.977390][ T224] ? lockdep_hardirqs_on+0x91/0x130 [ 10.977394][ T224] ? free_module+0x470/0x470 [ 10.977397][ T224] ? kfree+0x123/0x590 [ 10.977401][ T224] load_module+0x1424/0x1bb0 [ 10.977408][ T224] ? layout_and_allocate.isra.0+0x680/0x680 [ 10.977412][ T224] ? __ia32_sys_fsconfig+0x180/0x180 [ 10.977415][ T224] ? ovl_real_file+0x172/0x1e0 [ 10.977418][ T224] ? find_held_lock+0x2b/0x80 [ 10.977420][ T224] ? backing_file_read_iter+0x541/0x790 [ 10.977423][ T224] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.977427][ T224] init_module_from_file+0x11f/0x1a0 [ 10.977430][ T224] ? __do_sys_init_module+0x220/0x220 [ 10.977438][ T224] ? __rwlock_init+0x150/0x150 [ 10.977441][ T224] ? do_raw_spin_unlock+0x59/0x250 [ 10.977444][ T224] idempotent_init_module+0x37b/0x7c0 [ 10.977448][ T224] ? init_module_from_file+0x1a0/0x1a0 [ 10.977454][ T224] ? rcu_is_watching+0x16/0xd0 [ 10.977456][ T224] ? cap_capable+0x1d7/0x3d0 [ 10.977462][ T224] __x64_sys_finit_module+0xdf/0x190 [ 10.977464][ T224] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.977467][ T224] do_syscall_64+0xff/0x530 [ 10.977469][ T224] ? exc_page_fault+0xee/0x100 [ 10.977472][ T224] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.977474][ T224] RIP: 0033:0x7f6902bcc37d [ 10.977478][ T224] 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 [ 10.977479][ T224] RSP: 002b:00007ffc22e3b688 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 10.977482][ T224] RAX: ffffffffffffffda RBX: 0000555f1677d810 RCX: 00007f6902bcc37d [ 10.977483][ T224] RDX: 0000000000000000 RSI: 0000555edb352ffd RDI: 0000000000000000 [ 10.977484][ T224] RBP: 00007ffc22e3b720 R08: 0000000000000000 R09: 0000000000000000 [ 10.977485][ T224] R10: 0000000000000000 R11: 0000000000000246 R12: 0000555edb352ffd [ 10.977486][ T224] R13: 0000555f1677d960 R14: 0000000000040000 R15: 0000000000000000 [ 10.977492][ T224] [ 12.201229][ T258] br1: port 1(veth1) entered blocking state [ 12.201344][ T258] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.201346][ T258] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 258, name: ip [ 12.201348][ T258] preempt_count: 1, expected: 0 [ 12.201349][ T258] RCU nest depth: 0, expected: 0 [ 12.201350][ T258] locks held by ip/258: 5, last CPU#2: [ 12.201352][ T258] #0: ffffffff930d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 12.201364][ T258] #1: ffffffff92969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.201371][ T258] #2: ffffffff92969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.201375][ T258] #3: ffffffff92889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.201379][ T258] #4: ffffffff92889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.201383][ T258] irq event stamp: 20774 [ 12.201384][ T258] hardirqs last enabled at (20773): [] __down_trylock_console_sem+0x86/0xa0 [ 12.201387][ T258] hardirqs last disabled at (20774): [] console_emit_next_record+0x3d4/0x4c0 [ 12.201389][ T258] softirqs last enabled at (20684): [] __alloc_skb+0x4c2/0x5f0 [ 12.201392][ T258] softirqs last disabled at (20682): [] __alloc_skb+0x4c2/0x5f0 [ 12.201395][ T258] Preemption disabled at: [ 12.201395][ T258] [] vprintk_emit+0x31b/0x3e0 [ 12.201401][ T258] CPU: 2 UID: 0 PID: 258 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.201405][ T258] Tainted: [W]=WARN [ 12.201406][ T258] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.201408][ T258] Call Trace: [ 12.201409][ T258] [ 12.201411][ T258] dump_stack_lvl+0x6f/0xa0 [ 12.201417][ T258] ? vprintk_emit+0x31b/0x3e0 [ 12.201419][ T258] __might_resched.cold+0x1fe/0x2c1 [ 12.201424][ T258] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.201428][ T258] ? __kmalloc_noprof+0xdb/0x760 [ 12.201434][ T258] __kmalloc_noprof+0x443/0x760 [ 12.201436][ T258] ? alloc_buf.isra.0+0x4b/0x260 [ 12.201442][ T258] ? do_raw_spin_unlock+0x59/0x250 [ 12.201445][ T258] alloc_buf.isra.0+0x4b/0x260 [ 12.201448][ T258] put_chars+0x1e1/0x2f0 [ 12.201452][ T258] ? __send_to_port+0x420/0x420 [ 12.201456][ T258] ? validate_chain+0x34a/0xc20 [ 12.201460][ T258] hvc_console_print+0x292/0x780 [ 12.201463][ T258] ? mark_usage+0x61/0x170 [ 12.201465][ T258] ? __lock_acquire+0x518/0xc20 [ 12.201466][ T258] ? __lock_acquire+0x518/0xc20 [ 12.201470][ T258] ? hvc_write+0x3a0/0x3a0 [ 12.201473][ T258] ? console_emit_next_record+0x1df/0x4c0 [ 12.201476][ T258] ? rcu_is_watching+0x16/0xd0 [ 12.201480][ T258] ? lock_acquire+0x13c/0x160 [ 12.201484][ T258] console_emit_next_record+0x22f/0x4c0 [ 12.201488][ T258] ? devkmsg_read+0x4b0/0x4b0 [ 12.201490][ T258] ? console_flush_one_record+0x106/0x710 [ 12.201493][ T258] ? rcu_is_watching+0x16/0xd0 [ 12.201495][ T258] ? lock_acquire+0x13c/0x160 [ 12.201499][ T258] console_flush_one_record+0x46f/0x710 [ 12.201503][ T258] ? console_emit_next_record+0x4c0/0x4c0 [ 12.201505][ T258] ? __lock_acquire+0x518/0xc20 [ 12.201511][ T258] console_unlock+0xee/0x1f0 [ 12.201514][ T258] ? console_flush_one_record+0x710/0x710 [ 12.201516][ T258] ? rcu_is_watching+0x16/0xd0 [ 12.201518][ T258] ? lock_acquire+0x60/0x160 [ 12.201522][ T258] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.201523][ T258] ? vprintk_emit+0x320/0x3e0 [ 12.201527][ T258] vprintk_emit+0x37c/0x3e0 [ 12.201531][ T258] ? wake_up_klogd_work_func+0x90/0x90 [ 12.201533][ T258] ? __lock_release.isra.0+0x69/0x1a0 [ 12.201535][ T258] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.201539][ T258] ? mark_held_locks+0x40/0x70 [ 12.201543][ T258] _printk+0xc7/0x100 [ 12.201547][ T258] ? snapshot_read.cold+0x21/0x21 [ 12.201551][ T258] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 12.201564][ T258] ? do_setlink.isra.0+0xa31/0x2750 [ 12.201566][ T258] ? rtnl_newlink+0x9f1/0x11c0 [ 12.201567][ T258] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.201571][ T258] br_set_state+0x22f/0x430 [bridge] [ 12.201582][ T258] br_init_port+0xc4/0x200 [bridge] [ 12.201590][ T258] new_nbp+0x39c/0x580 [bridge] [ 12.201599][ T258] br_add_if+0x212/0x1320 [bridge] [ 12.201607][ T258] ? is_bpf_text_address+0x72/0x110 [ 12.201611][ T258] ? kernel_text_address+0x149/0x170 [ 12.201614][ T258] ? __kernel_text_address+0x12/0x30 [ 12.201618][ T258] do_set_master+0x357/0x580 [ 12.201623][ T258] do_setlink.isra.0+0xa31/0x2750 [ 12.201626][ T258] ? stack_trace_save+0x93/0xc0 [ 12.201629][ T258] ? rtnl_link_get_size+0x350/0x350 [ 12.201631][ T258] ? rcu_read_lock_any_held+0x66/0x90 [ 12.201633][ T258] ? stack_depot_save_flags+0x38e/0x790 [ 12.201637][ T258] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.201639][ T258] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.201641][ T258] ? validate_chain+0x38b/0xc20 [ 12.201644][ T258] ? kasan_save_stack+0x3d/0x50 [ 12.201647][ T258] ? kasan_save_stack+0x2f/0x50 [ 12.201648][ T258] ? kasan_save_track+0x14/0x30 [ 12.201651][ T258] ? __lock_acquire+0x518/0xc20 [ 12.201653][ T258] ? netlink_seq_next+0x4e/0x60 [ 12.201656][ T258] ? ___sys_sendmsg+0xb0/0x1d0 [ 12.201660][ T258] ? lock_acquire.part.0+0xd4/0x280 [ 12.201662][ T258] ? rtnl_newlink+0x9a8/0x11c0 [ 12.201665][ T258] ? rcu_is_watching+0x16/0xd0 [ 12.201667][ T258] ? lock_acquire+0x13c/0x160 [ 12.201669][ T258] ? rcu_is_watching+0x16/0xd0 [ 12.201671][ T258] ? rcu_is_watching+0x16/0xd0 [ 12.201673][ T258] ? trace_contention_end+0xb3/0x180 [ 12.201677][ T258] ? __mutex_lock+0x1db/0x1ea0 [ 12.201679][ T258] ? __mutex_lock+0x9a3/0x1ea0 [ 12.201681][ T258] ? rtnl_newlink+0x9a8/0x11c0 [ 12.201684][ T258] ? ww_mutex_lock+0x160/0x160 [ 12.201686][ T258] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.201691][ T258] ? __rtnl_newlink+0x3fa/0xa50 [ 12.201696][ T258] rtnl_newlink+0x9f1/0x11c0 [ 12.201702][ T258] ? rtnl_bridge_getlink+0x850/0x850 [ 12.201704][ T258] ? __lock_acquire+0x518/0xc20 [ 12.201708][ T258] ? lock_acquire.part.0+0xd4/0x280 [ 12.201710][ T258] ? find_held_lock+0x2b/0x80 [ 12.201713][ T258] ? rtnl_bridge_getlink+0x850/0x850 [ 12.201714][ T258] ? __lock_release.isra.0+0x69/0x1a0 [ 12.201718][ T258] ? rtnl_bridge_getlink+0x850/0x850 [ 12.201720][ T258] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.201723][ T258] ? rtnl_link_fill+0x920/0x920 [ 12.201725][ T258] ? __lock_acquire+0x518/0xc20 [ 12.201729][ T258] ? lock_acquire.part.0+0xd4/0x280 [ 12.201731][ T258] ? find_held_lock+0x2b/0x80 [ 12.201735][ T258] netlink_rcv_skb+0x14e/0x3a0 [ 12.201737][ T258] ? rtnl_link_fill+0x920/0x920 [ 12.201739][ T258] ? netlink_ack+0xcf0/0xcf0 [ 12.201746][ T258] ? netlink_deliver_tap+0xc5/0x330 [ 12.201747][ T258] ? netlink_deliver_tap+0x13c/0x330 [ 12.201752][ T258] netlink_unicast+0x486/0x750 [ 12.201756][ T258] ? netlink_attachskb+0x810/0x810 [ 12.201758][ T258] ? __lock_acquire+0x518/0xc20 [ 12.201762][ T258] netlink_sendmsg+0x735/0xc60 [ 12.201766][ T258] ? netlink_unicast+0x750/0x750 [ 12.201770][ T258] ? __might_fault+0x97/0x140 [ 12.201776][ T258] ____sys_sendmsg+0x415/0x880 [ 12.201778][ T258] ? copy_msghdr_from_user+0x279/0x420 [ 12.201780][ T258] ? get_timestamp.constprop.0+0x390/0x390 [ 12.201782][ T258] ? move_addr_to_kernel+0x40/0x40 [ 12.201790][ T258] ___sys_sendmsg+0x14e/0x1d0 [ 12.201792][ T258] ? copy_msghdr_from_user+0x420/0x420 [ 12.201809][ T258] __sys_sendmsg+0x12c/0x1d0 [ 12.201812][ T258] ? __sys_sendmsg_sock+0x20/0x20 [ 12.201818][ T258] ? rcu_is_watching+0x16/0xd0 [ 12.201822][ T258] do_syscall_64+0xff/0x530 [ 12.201825][ T258] ? exc_page_fault+0xee/0x100 [ 12.201828][ T258] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.201830][ T258] RIP: 0033:0x7f9788e4954e [ 12.201834][ T258] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 12.201836][ T258] RSP: 002b:00007fff0500aad0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.201839][ T258] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f9788e4954e [ 12.201845][ T258] RDX: 0000000000000000 RSI: 00007fff0500ab80 RDI: 0000000000000005 [ 12.201846][ T258] RBP: 00007fff0500aae0 R08: 0000000000000000 R09: 0000000000000000 [ 12.201847][ T258] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a913626 [ 12.201848][ T258] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff0500b240 [ 12.201855][ T258] [ 12.251460][ T258] br1: port 1(veth1) entered disabled state [ 12.252033][ T258] veth1: entered allmulticast mode [ 12.255503][ T258] veth1: entered promiscuous mode [ 12.267147][ T258] ip (258) used greatest stack depth: 23336 bytes left [ 12.302461][ T196] br1: port 1(veth1) entered blocking state [ 12.308304][ T196] br1: port 1(veth1) entered forwarding state [ 27.511939][ C2] [ 27.511956][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.511959][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 27.511960][ C2] preempt_count: 104, expected: 0 [ 27.511961][ C2] RCU nest depth: 0, expected: 0 [ 27.511962][ C2] INFO: lockdep is turned off. [ 27.511963][ C2] irq event stamp: 698126 [ 27.511964][ C2] hardirqs last enabled at (698126): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.511973][ C2] hardirqs last disabled at (698125): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.511975][ C2] softirqs last enabled at (698080): [] handle_softirqs+0x67c/0x900 [ 27.511979][ C2] softirqs last disabled at (698123): [] __irq_exit_rcu+0x145/0x1c0 [ 27.511980][ C2] Preemption disabled at: [ 27.511981][ C2] [<0000000000000000>] 0x0 [ 27.511989][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.511992][ C2] Tainted: [W]=WARN [ 27.511993][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.511995][ C2] Call Trace: [ 27.511997][ C2] [ 27.511998][ C2] dump_stack_lvl+0x6f/0xa0 [ 27.512004][ C2] __might_resched.cold+0x1fe/0x2c1 [ 27.512008][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.512011][ C2] ? __asan_memcpy+0x3c/0x60 [ 27.512014][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.512018][ C2] __kmalloc_noprof+0x443/0x760 [ 27.512022][ C2] ? __rwlock_init+0x150/0x150 [ 27.512025][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 27.512028][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 27.512030][ C2] alloc_buf.isra.0+0x4b/0x260 [ 27.512032][ C2] put_chars+0x1e1/0x2f0 [ 27.512034][ C2] ? __send_to_port+0x420/0x420 [ 27.512036][ C2] ? console_prepend_replay+0x20/0x20 [ 27.512039][ C2] hvc_console_print+0x292/0x780 [ 27.512043][ C2] ? hvc_write+0x3a0/0x3a0 [ 27.512044][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.512045][ C2] ? lock_acquire+0x13c/0x160 [ 27.512047][ C2] console_emit_next_record+0x22f/0x4c0 [ 27.512050][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 27.512052][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.512053][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.512055][ C2] ? lock_acquire+0x13c/0x160 [ 27.512057][ C2] ? console_flush_one_record+0x111/0x710 [ 27.512059][ C2] console_flush_one_record+0x46f/0x710 [ 27.512061][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 27.512064][ C2] console_unlock+0xee/0x1f0 [ 27.512065][ C2] ? lock_acquire+0x13c/0x160 [ 27.512067][ C2] ? console_flush_one_record+0x710/0x710 [ 27.512068][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.512070][ C2] ? lock_release+0x184/0x1f0 [ 27.512071][ C2] ? lock_acquire+0x60/0x160 [ 27.512073][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.512075][ C2] ? vprintk_emit+0x320/0x3e0 [ 27.512077][ C2] vprintk_emit+0x37c/0x3e0 [ 27.512079][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 27.512081][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.512093][ C2] ? lock_release+0x184/0x1f0 [ 27.512095][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.512101][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.512107][ C2] ? is_module_text_address+0x154/0x250 [ 27.512110][ C2] _printk+0xc7/0x100 [ 27.512114][ C2] ? snapshot_read.cold+0x21/0x21 [ 27.512115][ C2] ? arch_stack_walk+0xd7/0x130 [ 27.512119][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.512122][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.512123][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.512126][ C2] mark_lock_irq+0x989/0x9c0 [ 27.512128][ C2] mark_lock+0x1d7/0xa00 [ 27.512130][ C2] mark_usage+0x42/0x170 [ 27.512132][ C2] __lock_acquire+0x388/0xc20 [ 27.512134][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.512136][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.512142][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.512144][ C2] ? lock_acquire+0x13c/0x160 [ 27.512146][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.512151][ C2] _raw_spin_lock+0x33/0x40 [ 27.512153][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.512159][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.512166][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.512172][ C2] call_timer_fn+0x160/0x4d0 [ 27.512174][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 27.512176][ C2] ? debug_object_active_state+0x430/0x430 [ 27.512180][ C2] ? find_held_lock+0x2b/0x80 [ 27.512182][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.512183][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.512185][ C2] __run_timers+0x68f/0xaa0 [ 27.512187][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.512194][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.512196][ C2] ? __lock_acquire+0x518/0xc20 [ 27.512198][ C2] ? __rwlock_init+0x150/0x150 [ 27.512200][ C2] run_timer_softirq+0xf0/0x160 [ 27.512202][ C2] ? __run_timers+0xaa0/0xaa0 [ 27.512204][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.512206][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.512208][ C2] handle_softirqs+0x1d3/0x900 [ 27.512210][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.512211][ C2] ? _local_bh_enable+0xc0/0xc0 [ 27.512213][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.512215][ C2] irq_exit_rcu+0xe/0x30 [ 27.512216][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.512219][ C2] [ 27.512220][ C2] [ 27.512220][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 27.512222][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.512224][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.512227][ C2] 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.512229][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 27.512232][ C2] RAX: 00000000000aa709 RBX: ff11000001bec6c0 RCX: ffffffff8f6f0307 [ 27.512233][ C2] RDX: ff11000001bec6c0 RSI: ffffffff92438b01 RDI: ffffffff91e8d8e0 [ 27.512234][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.512235][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 27.512236][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.512237][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.512240][ C2] default_idle+0x9/0x10 [ 27.512242][ C2] default_idle_call+0x6e/0xb0 [ 27.512244][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.512245][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 27.512247][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 27.512249][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.512250][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.512252][ C2] do_idle+0xd8/0x190 [ 27.512254][ C2] cpu_startup_entry+0x53/0x70 [ 27.512255][ C2] start_secondary+0x204/0x2b0 [ 27.512257][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.512259][ C2] common_startup_64+0x13e/0x148 [ 27.512263][ C2] [ 27.534786][ C2] ======================================================== [ 27.535031][ C2] WARNING: possible irq lock inversion dependency detected [ 27.535226][ C2] 7.2.0-virtme #1 Tainted: G W [ 27.535437][ C2] -------------------------------------------------------- [ 27.535677][ C2] swapper/2/0 just changed the state of lock: [ 27.535841][ C2] ff1100000dadae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.536119][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.536359][ C2] (fs_reclaim){+.+.}-{0:0} [ 27.536362][ C2] [ 27.536362][ C2] [ 27.536362][ C2] and interrupts could create inverse lock ordering between them. [ 27.536362][ C2] [ 27.536938][ C2] [ 27.536938][ C2] other info that might help us debug this: [ 27.537179][ C2] Chain exists of: [ 27.537179][ C2] &br->lock --> console_owner --> fs_reclaim [ 27.537179][ C2] [ 27.537521][ C2] Possible interrupt unsafe locking scenario: [ 27.537521][ C2] [ 27.537766][ C2] CPU0 CPU1 [ 27.537906][ C2] ---- ---- [ 27.538085][ C2] lock(fs_reclaim); [ 27.538189][ C2] local_irq_disable(); [ 27.538400][ C2] lock(&br->lock); [ 27.538567][ C2] lock(console_owner); [ 27.538778][ C2] [ 27.538878][ C2] lock(&br->lock); [ 27.539031][ C2] [ 27.539031][ C2] *** DEADLOCK *** [ 27.539031][ C2] [ 27.539228][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 27.539438][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.539752][ C2] [ 27.539752][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 27.540031][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 27.540169][ C2] HARDIRQ-ON-W at: [ 27.540318][ C2] __lock_acquire+0x388/0xc20 [ 27.540489][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.540698][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.540916][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.541120][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.541368][ C2] workqueue_init+0x2a/0x680 [ 27.541581][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.541830][ C2] kernel_init+0x21/0x150 [ 27.542003][ C2] ret_from_fork+0x474/0x6b0 [ 27.542218][ C2] ret_from_fork_asm+0x11/0x20 [ 27.542385][ C2] SOFTIRQ-ON-W at: [ 27.542529][ C2] __lock_acquire+0x388/0xc20 [ 27.542714][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.542936][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.543150][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.543347][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.543592][ C2] workqueue_init+0x2a/0x680 [ 27.543802][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.544049][ C2] kernel_init+0x21/0x150 [ 27.544218][ C2] ret_from_fork+0x474/0x6b0 [ 27.544426][ C2] ret_from_fork_asm+0x11/0x20 [ 27.544590][ C2] INITIAL USE at: [ 27.544734][ C2] __lock_acquire+0x388/0xc20 [ 27.544904][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.545114][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.545325][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.545524][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.545768][ C2] workqueue_init+0x2a/0x680 [ 27.545986][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.546154][ C2] kernel_init+0x21/0x150 [ 27.546365][ C2] ret_from_fork+0x474/0x6b0 [ 27.546578][ C2] ret_from_fork_asm+0x11/0x20 [ 27.546745][ C2] } [ 27.546861][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.547062][ C2] ... acquired at: [ 27.547208][ C2] __lock_acquire+0x518/0xc20 [ 27.547349][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.547527][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.547658][ C2] __kmalloc_noprof+0xd3/0x760 [ 27.547831][ C2] alloc_buf.isra.0+0x4b/0x260 [ 27.547970][ C2] put_chars+0x1e1/0x2f0 [ 27.548142][ C2] hvc_console_print+0x292/0x780 [ 27.548274][ C2] console_emit_next_record+0x22f/0x4c0 [ 27.548452][ C2] console_flush_one_record+0x46f/0x710 [ 27.548585][ C2] console_unlock+0xee/0x1f0 [ 27.548759][ C2] vprintk_emit+0x37c/0x3e0 [ 27.548888][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 27.549069][ C2] dev_printk_emit+0xb9/0xee [ 27.549202][ C2] _dev_info+0xe2/0x116 [ 27.549343][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 27.549504][ C2] cmos_do_probe+0x73b/0x98a [ 27.549680][ C2] platform_probe+0xfe/0x1f0 [ 27.549811][ C2] call_driver_probe+0x61/0x1c0 [ 27.549946][ C2] really_probe+0x199/0x760 [ 27.550078][ C2] __driver_probe_device+0x24f/0x440 [ 27.550250][ C2] driver_probe_device+0x4a/0xf0 [ 27.550380][ C2] __driver_attach+0x1b8/0x540 [ 27.550556][ C2] bus_for_each_dev+0x130/0x1e0 [ 27.550688][ C2] bus_add_driver+0x2c8/0x530 [ 27.550860][ C2] driver_register+0x1a3/0x390 [ 27.551000][ C2] __platform_driver_probe+0x13f/0x270 [ 27.551172][ C2] cmos_init+0x31/0x40 [ 27.551273][ C2] do_one_initcall+0x124/0x4f0 [ 27.551446][ C2] kernel_init_freeable+0x596/0x630 [ 27.551576][ C2] kernel_init+0x21/0x150 [ 27.551749][ C2] ret_from_fork+0x474/0x6b0 [ 27.551882][ C2] ret_from_fork_asm+0x11/0x20 [ 27.552059][ C2] [ 27.552129][ C2] -> (console_owner){....}-{0:0} { [ 27.552266][ C2] INITIAL USE at: [ 27.552411][ C2] } [ 27.552477][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.552713][ C2] ... acquired at: [ 27.552812][ C2] __lock_acquire+0x518/0xc20 [ 27.552993][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.553125][ C2] console_lock_spinning_enable+0x5c/0x60 [ 27.553352][ C2] console_emit_next_record+0x1d1/0x4c0 [ 27.553485][ C2] console_flush_one_record+0x46f/0x710 [ 27.553659][ C2] console_unlock+0xee/0x1f0 [ 27.553790][ C2] vprintk_emit+0x37c/0x3e0 [ 27.553929][ C2] _printk+0xc7/0x100 [ 27.554030][ C2] br_set_state+0x22f/0x430 [bridge] [ 27.554213][ C2] br_init_port+0xc4/0x200 [bridge] [ 27.554349][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 27.554558][ C2] br_port_carrier_check+0x220/0x430 [bridge] [ 27.554729][ C2] br_device_event+0x52d/0x8f0 [bridge] [ 27.554913][ C2] notifier_call_chain+0xae/0x300 [ 27.555044][ C2] netif_state_change+0x139/0x340 [ 27.555220][ C2] __linkwatch_run_queue+0x34c/0x750 [ 27.555351][ C2] linkwatch_event+0x7f/0xb0 [ 27.555522][ C2] process_one_work+0xe3e/0x1560 [ 27.555653][ C2] worker_thread+0x4f1/0xd60 [ 27.555826][ C2] kthread+0x367/0x460 [ 27.555933][ C2] ret_from_fork+0x474/0x6b0 [ 27.556107][ C2] ret_from_fork_asm+0x11/0x20 [ 27.556238][ C2] [ 27.556302][ C2] -> (&br->lock){+.-.}-{3:3} { [ 27.556478][ C2] HARDIRQ-ON-W at: [ 27.556578][ C2] __lock_acquire+0x388/0xc20 [ 27.556792][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.557007][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.557177][ C2] recalculate_group_addr+0x51/0x120 [bridge] [ 27.557427][ C2] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.557674][ C2] br_changelink+0x575/0x16e0 [bridge] [ 27.557844][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 27.558060][ C2] rtnl_newlink_create+0x2d0/0x750 [ 27.558268][ C2] __rtnl_newlink+0x22b/0xa50 [ 27.558439][ C2] rtnl_newlink+0x9f1/0x11c0 [ 27.558646][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.558809][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.559022][ C2] netlink_unicast+0x486/0x750 [ 27.559229][ C2] netlink_sendmsg+0x735/0xc60 [ 27.559393][ C2] ____sys_sendmsg+0x415/0x880 [ 27.559605][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.559813][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.559985][ C2] do_syscall_64+0xff/0x530 [ 27.560194][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.560440][ C2] IN-SOFTIRQ-W at: [ 27.560540][ C2] __lock_acquire+0x388/0xc20 [ 27.560753][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.560925][ C2] _raw_spin_lock+0x33/0x40 [ 27.561133][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.561379][ C2] call_timer_fn+0x160/0x4d0 [ 27.561547][ C2] __run_timers+0x68f/0xaa0 [ 27.561758][ C2] run_timer_softirq+0xf0/0x160 [ 27.561929][ C2] handle_softirqs+0x1d3/0x900 [ 27.562140][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.562352][ C2] irq_exit_rcu+0xe/0x30 [ 27.562516][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.562760][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.562966][ C2] pv_native_safe_halt+0xf/0x10 [ 27.563134][ C2] default_idle+0x9/0x10 [ 27.563346][ C2] default_idle_call+0x6e/0xb0 [ 27.563555][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.563750][ C2] do_idle+0xd8/0x190 [ 27.563936][ C2] cpu_startup_entry+0x53/0x70 [ 27.564147][ C2] start_secondary+0x204/0x2b0 [ 27.564311][ C2] common_startup_64+0x13e/0x148 [ 27.564521][ C2] INITIAL USE at: [ 27.564622][ C2] __lock_acquire+0x388/0xc20 [ 27.564833][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.565004][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.565217][ C2] recalculate_group_addr+0x51/0x120 [bridge] [ 27.565462][ C2] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.565705][ C2] br_changelink+0x575/0x16e0 [bridge] [ 27.565875][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 27.566093][ C2] rtnl_newlink_create+0x2d0/0x750 [ 27.566259][ C2] __rtnl_newlink+0x22b/0xa50 [ 27.566470][ C2] rtnl_newlink+0x9f1/0x11c0 [ 27.566680][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.566841][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.567008][ C2] netlink_unicast+0x486/0x750 [ 27.567216][ C2] netlink_sendmsg+0x735/0xc60 [ 27.567381][ C2] ____sys_sendmsg+0x415/0x880 [ 27.567619][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.567783][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.567996][ C2] do_syscall_64+0xff/0x530 [ 27.568206][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.568405][ C2] } [ 27.568521][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.568717][ C2] ... acquired at: [ 27.568858][ C2] mark_lock+0x1d7/0xa00 [ 27.568995][ C2] mark_usage+0x42/0x170 [ 27.569170][ C2] __lock_acquire+0x388/0xc20 [ 27.569301][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.569474][ C2] _raw_spin_lock+0x33/0x40 [ 27.569608][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.569818][ C2] call_timer_fn+0x160/0x4d0 [ 27.569954][ C2] __run_timers+0x68f/0xaa0 [ 27.570127][ C2] run_timer_softirq+0xf0/0x160 [ 27.570261][ C2] handle_softirqs+0x1d3/0x900 [ 27.570435][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.570566][ C2] irq_exit_rcu+0xe/0x30 [ 27.570738][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.570908][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.571073][ C2] pv_native_safe_halt+0xf/0x10 [ 27.571247][ C2] default_idle+0x9/0x10 [ 27.571380][ C2] default_idle_call+0x6e/0xb0 [ 27.571555][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.571719][ C2] do_idle+0xd8/0x190 [ 27.571861][ C2] cpu_startup_entry+0x53/0x70 [ 27.572005][ C2] start_secondary+0x204/0x2b0 [ 27.572178][ C2] common_startup_64+0x13e/0x148 [ 27.572310][ C2] [ 27.572377][ C2] [ 27.572377][ C2] stack backtrace: [ 27.572588][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.572591][ C2] Tainted: [W]=WARN [ 27.572592][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.572592][ C2] Call Trace: [ 27.572594][ C2] [ 27.572595][ C2] dump_stack_lvl+0x6f/0xa0 [ 27.572599][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.572602][ C2] mark_lock_irq+0x989/0x9c0 [ 27.572605][ C2] mark_lock+0x1d7/0xa00 [ 27.572607][ C2] mark_usage+0x42/0x170 [ 27.572609][ C2] __lock_acquire+0x388/0xc20 [ 27.572611][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.572613][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.572619][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.572623][ C2] ? lock_acquire+0x13c/0x160 [ 27.572624][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.572630][ C2] _raw_spin_lock+0x33/0x40 [ 27.572632][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.572638][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.572644][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.572650][ C2] call_timer_fn+0x160/0x4d0 [ 27.572652][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 27.572653][ C2] ? debug_object_active_state+0x430/0x430 [ 27.572657][ C2] ? find_held_lock+0x2b/0x80 [ 27.572659][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.572661][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.572663][ C2] __run_timers+0x68f/0xaa0 [ 27.572665][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.572671][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.572673][ C2] ? __lock_acquire+0x518/0xc20 [ 27.572676][ C2] ? __rwlock_init+0x150/0x150 [ 27.572678][ C2] run_timer_softirq+0xf0/0x160 [ 27.572680][ C2] ? __run_timers+0xaa0/0xaa0 [ 27.572681][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.572684][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.572686][ C2] handle_softirqs+0x1d3/0x900 [ 27.572687][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.572689][ C2] ? _local_bh_enable+0xc0/0xc0 [ 27.572691][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.572692][ C2] irq_exit_rcu+0xe/0x30 [ 27.572694][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.572695][ C2] [ 27.572696][ C2] [ 27.572696][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 27.572698][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.572700][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.572702][ C2] 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.572703][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 27.572705][ C2] RAX: 00000000000aa709 RBX: ff11000001bec6c0 RCX: ffffffff8f6f0307 [ 27.572706][ C2] RDX: ff11000001bec6c0 RSI: ffffffff92438b01 RDI: ffffffff91e8d8e0 [ 27.572707][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.572708][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 27.572709][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.572711][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.572713][ C2] default_idle+0x9/0x10 [ 27.572714][ C2] default_idle_call+0x6e/0xb0 [ 27.572716][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.572718][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 27.572719][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 27.572721][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.572722][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.572724][ C2] do_idle+0xd8/0x190 [ 27.572726][ C2] cpu_startup_entry+0x53/0x70 [ 27.572727][ C2] start_secondary+0x204/0x2b0 [ 27.572728][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.572730][ C2] common_startup_64+0x13e/0x148 [ 27.572733][ C2] [ 39.149632][ T444] br1: port 1(veth1) entered disabled state [ 39.149649][ T444] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 39.149652][ T444] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 444, name: ip [ 39.149654][ T444] preempt_count: 202, expected: 0 [ 39.149655][ T444] RCU nest depth: 0, expected: 0 [ 39.149655][ T444] INFO: lockdep is turned off. [ 39.149657][ T444] irq event stamp: 0 [ 39.149658][ T444] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 39.149663][ T444] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 39.149669][ T444] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 39.149670][ T444] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 39.149672][ T444] Preemption disabled at: [ 39.149673][ T444] [<0000000000000000>] 0x0 [ 39.149678][ T444] CPU: 0 UID: 0 PID: 444 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 39.149681][ T444] Tainted: [W]=WARN [ 39.149682][ T444] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 39.149684][ T444] Call Trace: [ 39.149685][ T444] [ 39.149687][ T444] dump_stack_lvl+0x6f/0xa0 [ 39.149692][ T444] __might_resched.cold+0x1fe/0x2c1 [ 39.149696][ T444] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 39.149700][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149704][ T444] __kmalloc_noprof+0x443/0x760 [ 39.149707][ T444] ? __rwlock_init+0x150/0x150 [ 39.149710][ T444] ? alloc_buf.isra.0+0x4b/0x260 [ 39.149714][ T444] ? do_raw_spin_unlock+0x59/0x250 [ 39.149716][ T444] alloc_buf.isra.0+0x4b/0x260 [ 39.149718][ T444] put_chars+0x1e1/0x2f0 [ 39.149719][ T444] ? __send_to_port+0x420/0x420 [ 39.149721][ T444] ? printk_get_next_message+0x240/0x650 [ 39.149725][ T444] hvc_console_print+0x292/0x780 [ 39.149728][ T444] ? hvc_write+0x3a0/0x3a0 [ 39.149731][ T444] ? __rwlock_init+0x150/0x150 [ 39.149733][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149735][ T444] ? lock_acquire+0x13c/0x160 [ 39.149737][ T444] console_emit_next_record+0x22f/0x4c0 [ 39.149740][ T444] ? devkmsg_read+0x4b0/0x4b0 [ 39.149742][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149743][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149745][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149747][ T444] ? lock_acquire+0x13c/0x160 [ 39.149749][ T444] ? console_flush_one_record+0x111/0x710 [ 39.149750][ T444] console_flush_one_record+0x46f/0x710 [ 39.149753][ T444] ? console_emit_next_record+0x4c0/0x4c0 [ 39.149755][ T444] console_unlock+0xee/0x1f0 [ 39.149757][ T444] ? lock_acquire+0x13c/0x160 [ 39.149759][ T444] ? console_flush_one_record+0x710/0x710 [ 39.149760][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149762][ T444] ? lock_release+0x184/0x1f0 [ 39.149764][ T444] ? lock_acquire+0x60/0x160 [ 39.149765][ T444] ? __rcu_read_lock+0x30/0x70 [ 39.149767][ T444] ? __down_trylock_console_sem+0x5e/0xa0 [ 39.149769][ T444] ? vprintk_emit+0x320/0x3e0 [ 39.149771][ T444] vprintk_emit+0x37c/0x3e0 [ 39.149774][ T444] ? wake_up_klogd_work_func+0x90/0x90 [ 39.149775][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149777][ T444] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 39.149780][ T444] ? trace_hardirqs_on+0x36/0x40 [ 39.149781][ T444] _printk+0xc7/0x100 [ 39.149784][ T444] ? snapshot_read.cold+0x21/0x21 [ 39.149787][ T444] ? ipv6_mc_down+0x17e/0x2d0 [ 39.149792][ T444] br_set_state+0x22f/0x430 [bridge] [ 39.149805][ T444] br_stp_disable_port+0x11f/0x390 [bridge] [ 39.149814][ T444] br_device_event+0x7b5/0x8f0 [bridge] [ 39.149823][ T444] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 39.149827][ T444] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 39.149829][ T444] ? addrconf_notify+0x2c8/0xf30 [ 39.149832][ T444] notifier_call_chain+0xae/0x300 [ 39.149836][ T444] __dev_notify_flags+0x23c/0x3c0 [ 39.149839][ T444] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 39.149841][ T444] ? netif_change_name+0x8c0/0x8c0 [ 39.149843][ T444] ? netif_set_allmulti+0x250/0x250 [ 39.149845][ T444] ? __kernel_text_address+0x12/0x30 [ 39.149847][ T444] ? write_profile+0xf0/0xf0 [ 39.149849][ T444] ? unwind_get_return_address+0x67/0xd0 [ 39.149852][ T444] netif_change_flags+0xfe/0x190 [ 39.149855][ T444] do_setlink.isra.0+0x1cc3/0x2750 [ 39.149858][ T444] ? stack_trace_save+0x93/0xc0 [ 39.149860][ T444] ? rtnl_link_get_size+0x350/0x350 [ 39.149861][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149863][ T444] ? stack_depot_save_flags+0x1bc/0x790 [ 39.149867][ T444] ? kasan_save_stack+0x3d/0x50 [ 39.149869][ T444] ? kasan_save_stack+0x2f/0x50 [ 39.149871][ T444] ? kasan_save_track+0x14/0x30 [ 39.149872][ T444] ? __kasan_kmalloc+0x7b/0x90 [ 39.149874][ T444] ? __kmalloc_cache_noprof+0x29a/0x620 [ 39.149879][ T444] ? rtnl_newlink+0x124/0x11c0 [ 39.149880][ T444] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 39.149881][ T444] ? netlink_rcv_skb+0x14e/0x3a0 [ 39.149884][ T444] ? netlink_unicast+0x486/0x750 [ 39.149886][ T444] ? netlink_sendmsg+0x735/0xc60 [ 39.149887][ T444] ? ____sys_sendmsg+0x415/0x880 [ 39.149889][ T444] ? ___sys_sendmsg+0x14e/0x1d0 [ 39.149891][ T444] ? __sys_sendmsg+0x12c/0x1d0 [ 39.149893][ T444] ? do_syscall_64+0xff/0x530 [ 39.149895][ T444] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 39.149897][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149899][ T444] ? lock_acquire+0x13c/0x160 [ 39.149901][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149902][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149904][ T444] ? trace_contention_end+0xb3/0x180 [ 39.149907][ T444] ? __mutex_lock+0x1db/0x1ea0 [ 39.149909][ T444] ? __mutex_lock+0x9a3/0x1ea0 [ 39.149910][ T444] ? rtnl_newlink+0x9a8/0x11c0 [ 39.149912][ T444] ? ww_mutex_lock+0x160/0x160 [ 39.149914][ T444] ? __rtnl_newlink+0x3fa/0xa50 [ 39.149916][ T444] rtnl_newlink+0x9f1/0x11c0 [ 39.149918][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149920][ T444] ? rtnl_bridge_getlink+0x850/0x850 [ 39.149921][ T444] ? lock_acquire+0x13c/0x160 [ 39.149923][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149925][ T444] ? is_bpf_text_address+0x3e/0x110 [ 39.149928][ T444] ? is_bpf_text_address+0x72/0x110 [ 39.149930][ T444] ? kernel_text_address+0x149/0x170 [ 39.149931][ T444] ? __kernel_text_address+0x12/0x30 [ 39.149933][ T444] ? write_profile+0xf0/0xf0 [ 39.149940][ T444] ? unwind_get_return_address+0x67/0xd0 [ 39.149941][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149943][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149945][ T444] ? cap_capable+0x1d7/0x3d0 [ 39.149948][ T444] ? lock_acquire+0x13c/0x160 [ 39.149950][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149952][ T444] ? rtnl_bridge_getlink+0x850/0x850 [ 39.149953][ T444] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 39.149955][ T444] ? rtnl_link_fill+0x920/0x920 [ 39.149956][ T444] ? filter_irq_stacks+0xd0/0xd0 [ 39.149958][ T444] ? stack_depot_save_flags+0x1bc/0x790 [ 39.149960][ T444] netlink_rcv_skb+0x14e/0x3a0 [ 39.149962][ T444] ? rtnl_link_fill+0x920/0x920 [ 39.149963][ T444] ? lock_release+0x184/0x1f0 [ 39.149965][ T444] ? netlink_ack+0xcf0/0xcf0 [ 39.149966][ T444] ? __netlink_lookup+0xb9/0x6b0 [ 39.149969][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149971][ T444] ? lock_release+0x184/0x1f0 [ 39.149973][ T444] netlink_unicast+0x486/0x750 [ 39.149975][ T444] ? netlink_attachskb+0x810/0x810 [ 39.149976][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149978][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149980][ T444] ? lock_acquire+0x13c/0x160 [ 39.149982][ T444] netlink_sendmsg+0x735/0xc60 [ 39.149984][ T444] ? netlink_unicast+0x750/0x750 [ 39.149986][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.149987][ T444] ? lock_release+0x184/0x1f0 [ 39.149989][ T444] ____sys_sendmsg+0x415/0x880 [ 39.149991][ T444] ? copy_msghdr_from_user+0x279/0x420 [ 39.149993][ T444] ? get_timestamp.constprop.0+0x390/0x390 [ 39.149994][ T444] ? move_addr_to_kernel+0x40/0x40 [ 39.149996][ T444] ? filter_irq_stacks+0xd0/0xd0 [ 39.149998][ T444] ___sys_sendmsg+0x14e/0x1d0 [ 39.150000][ T444] ? copy_msghdr_from_user+0x420/0x420 [ 39.150005][ T444] __sys_sendmsg+0x12c/0x1d0 [ 39.150007][ T444] ? __sys_sendmsg_sock+0x20/0x20 [ 39.150010][ T444] ? rcu_is_watching+0x16/0xd0 [ 39.150012][ T444] do_syscall_64+0xff/0x530 [ 39.150013][ T444] ? exc_page_fault+0xee/0x100 [ 39.150016][ T444] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 39.150017][ T444] RIP: 0033:0x7fc9684e754e [ 39.150019][ T444] 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 [ 39.150021][ T444] RSP: 002b:00007ffcaffb3c80 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 39.150025][ T444] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fc9684e754e [ 39.150026][ T444] RDX: 0000000000000000 RSI: 00007ffcaffb3d30 RDI: 0000000000000005 [ 39.150027][ T444] RBP: 00007ffcaffb3c90 R08: 0000000000000000 R09: 0000000000000000 [ 39.150028][ T444] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a913641 [ 39.150029][ T444] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffcaffb43f0 [ 39.150031][ T444] [ 39.183414][ T444] ip (444) used greatest stack depth: 23016 bytes left [ 39.207536][ T445] veth1: left allmulticast mode [ 39.207781][ T445] veth1: left promiscuous mode [ 39.208423][ T445] br1: port 1(veth1) entered disabled state