[ 11.914549][ T225] 8021q: 802.1Q VLAN Support v1.8 [ 11.914570][ T225] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.914572][ T225] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 225, name: modprobe [ 11.914574][ T225] preempt_count: 1, expected: 0 [ 11.914575][ T225] RCU nest depth: 0, expected: 0 [ 11.914576][ T225] locks held by modprobe/225: 4, last CPU#0: [ 11.914579][ T225] #0: ffffffffad769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.914592][ T225] #1: ffffffffad769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.914596][ T225] #2: ffffffffad689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.914600][ T225] #3: ffffffffad689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.914604][ T225] irq event stamp: 29448 [ 11.914605][ T225] hardirqs last enabled at (29447): [] __down_trylock_console_sem+0x86/0xa0 [ 11.914608][ T225] hardirqs last disabled at (29448): [] console_emit_next_record+0x3d4/0x4c0 [ 11.914610][ T225] softirqs last enabled at (26644): [] handle_softirqs+0x67c/0x900 [ 11.914613][ T225] softirqs last disabled at (26113): [] __irq_exit_rcu+0x145/0x1c0 [ 11.914615][ T225] Preemption disabled at: [ 11.914616][ T225] [] vprintk_emit+0x31b/0x3e0 [ 11.914622][ T225] CPU: 0 UID: 0 PID: 225 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.914625][ T225] Tainted: [W]=WARN [ 11.914626][ T225] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.914628][ T225] Call Trace: [ 11.914632][ T225] [ 11.914634][ T225] dump_stack_lvl+0x6f/0xa0 [ 11.914640][ T225] ? vprintk_emit+0x31b/0x3e0 [ 11.914642][ T225] __might_resched.cold+0x1fe/0x2c1 [ 11.914647][ T225] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.914651][ T225] ? __kmalloc_noprof+0xdb/0x760 [ 11.914656][ T225] __kmalloc_noprof+0x443/0x760 [ 11.914658][ T225] ? alloc_buf.isra.0+0x4b/0x260 [ 11.914664][ T225] ? do_raw_spin_unlock+0x59/0x250 [ 11.914667][ T225] alloc_buf.isra.0+0x4b/0x260 [ 11.914670][ T225] put_chars+0x1e1/0x2f0 [ 11.914673][ T225] ? __send_to_port+0x420/0x420 [ 11.914674][ T225] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.914679][ T225] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.914682][ T225] ? validate_chain+0x38b/0xc20 [ 11.914686][ T225] hvc_console_print+0x292/0x780 [ 11.914689][ T225] ? __lock_acquire+0x518/0xc20 [ 11.914694][ T225] ? hvc_write+0x3a0/0x3a0 [ 11.914696][ T225] ? rcu_is_watching+0x16/0xd0 [ 11.914701][ T225] console_emit_next_record+0x22f/0x4c0 [ 11.914705][ T225] ? devkmsg_read+0x4b0/0x4b0 [ 11.914707][ T225] ? console_flush_one_record+0x106/0x710 [ 11.914710][ T225] ? rcu_is_watching+0x16/0xd0 [ 11.914712][ T225] ? lock_acquire+0x13c/0x160 [ 11.914716][ T225] console_flush_one_record+0x46f/0x710 [ 11.914720][ T225] ? console_emit_next_record+0x4c0/0x4c0 [ 11.914722][ T225] ? __lock_acquire+0x518/0xc20 [ 11.914727][ T225] console_unlock+0xee/0x1f0 [ 11.914730][ T225] ? console_flush_one_record+0x710/0x710 [ 11.914732][ T225] ? rcu_is_watching+0x16/0xd0 [ 11.914734][ T225] ? lock_acquire+0x60/0x160 [ 11.914738][ T225] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.914740][ T225] ? vprintk_emit+0x320/0x3e0 [ 11.914743][ T225] vprintk_emit+0x37c/0x3e0 [ 11.914747][ T225] ? wake_up_klogd_work_func+0x90/0x90 [ 11.914753][ T225] ? vlan_netlink_fini+0x960/0x960 [8021q] [ 11.914757][ T225] _printk+0xc7/0x100 [ 11.914761][ T225] ? snapshot_read.cold+0x21/0x21 [ 11.914767][ T225] ? do_one_initcall+0x113/0x4f0 [ 11.914770][ T225] vlan_proto_init+0x24/0x90 [8021q] [ 11.914773][ T225] do_one_initcall+0x124/0x4f0 [ 11.914776][ T225] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 11.914781][ T225] ? do_init_module+0xae/0x8a0 [ 11.914784][ T225] ? kasan_unpoison+0x44/0x70 [ 11.914789][ T225] do_init_module+0x2ad/0x8a0 [ 11.914792][ T225] ? lockdep_hardirqs_on+0x91/0x130 [ 11.914797][ T225] ? free_module+0x470/0x470 [ 11.914800][ T225] ? kfree+0x123/0x590 [ 11.914804][ T225] load_module+0x1424/0x1bb0 [ 11.914811][ T225] ? layout_and_allocate.isra.0+0x680/0x680 [ 11.914815][ T225] ? __ia32_sys_fsconfig+0x180/0x180 [ 11.914818][ T225] ? ovl_real_file+0x172/0x1e0 [ 11.914822][ T225] ? backing_file_read_iter+0x541/0x790 [ 11.914825][ T225] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.914829][ T225] init_module_from_file+0x11f/0x1a0 [ 11.914832][ T225] ? __do_sys_init_module+0x220/0x220 [ 11.914840][ T225] ? __rwlock_init+0x150/0x150 [ 11.914844][ T225] ? do_raw_spin_unlock+0x59/0x250 [ 11.914847][ T225] idempotent_init_module+0x37b/0x7c0 [ 11.914850][ T225] ? init_module_from_file+0x1a0/0x1a0 [ 11.914856][ T225] ? rcu_is_watching+0x16/0xd0 [ 11.914859][ T225] ? cap_capable+0x1d7/0x3d0 [ 11.914865][ T225] __x64_sys_finit_module+0xdf/0x190 [ 11.914867][ T225] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.914870][ T225] do_syscall_64+0xff/0x530 [ 11.914872][ T225] ? exc_page_fault+0xee/0x100 [ 11.914875][ T225] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.914878][ T225] RIP: 0033:0x7f82b380637d [ 11.914881][ T225] 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 [ 11.914883][ T225] RSP: 002b:00007fffbca28d08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 11.914886][ T225] RAX: ffffffffffffffda RBX: 00005605e8947810 RCX: 00007f82b380637d [ 11.914888][ T225] RDX: 0000000000000000 RSI: 00005605d3520ffd RDI: 0000000000000000 [ 11.914889][ T225] RBP: 00007fffbca28da0 R08: 0000000000000000 R09: 0000000000000000 [ 11.914890][ T225] R10: 0000000000000000 R11: 0000000000000246 R12: 00005605d3520ffd [ 11.914891][ T225] R13: 00005605e8947960 R14: 0000000000040000 R15: 0000000000000000 [ 11.914897][ T225] [ 12.753853][ T247] br0: port 1(veth1) entered blocking state [ 12.754424][ T247] br0: port 1(veth1) entered disabled state [ 12.754926][ T247] veth1: entered allmulticast mode [ 12.756593][ T247] veth1: entered promiscuous mode [ 12.805579][ T249] br0: port 2(veth2) entered blocking state [ 12.806425][ T249] br0: port 2(veth2) entered disabled state [ 12.806992][ T249] veth2: entered allmulticast mode [ 12.809904][ T249] veth2: entered promiscuous mode [ 12.912833][ T39] br0: port 1(veth1) entered blocking state [ 12.913507][ T39] br0: port 1(veth1) entered forwarding state [ 12.913639][ T39] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.913642][ T39] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 39, name: kworker/u20:0 [ 12.913644][ T39] preempt_count: 202, expected: 0 [ 12.913645][ T39] RCU nest depth: 0, expected: 0 [ 12.913647][ T39] locks held by kworker/u20:0/39: 8, last CPU#1: [ 12.913650][ T39] #0: ff1100000114a940 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0xd9a/0x1560 [ 12.913666][ T39] #1: ffa00000002bfd10 ((linkwatch_work).work){+.+.}-{0:0}, at: process_one_work+0xdfc/0x1560 [ 12.913672][ T39] #2: ffffffffaded2c40 (rtnl_mutex){+.+.}-{4:4}, at: linkwatch_event+0x4e/0xb0 [ 12.913680][ T39] #3: ff11000008c2ae58 (&br->lock){+...}-{3:3}, at: br_port_carrier_check+0x161/0x430 [bridge] [ 12.913700][ T39] #4: ffffffffad769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.913708][ T39] #5: ffffffffad769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.913713][ T39] #6: ffffffffad689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.913719][ T39] #7: ffffffffad689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.913725][ T39] irq event stamp: 62407 [ 12.913727][ T39] hardirqs last enabled at (62406): [] __down_trylock_console_sem+0x86/0xa0 [ 12.913730][ T39] hardirqs last disabled at (62407): [] console_emit_next_record+0x3d4/0x4c0 [ 12.913734][ T39] softirqs last enabled at (62350): [] inet6_fill_ifla6_attrs+0x1b3e/0x21e0 [ 12.913738][ T39] softirqs last disabled at (62392): [] br_port_carrier_check+0x161/0x430 [bridge] [ 12.913751][ T39] Preemption disabled at: [ 12.913752][ T39] [<0000000000000000>] 0x0 [ 12.913761][ T39] CPU: 1 UID: 0 PID: 39 Comm: kworker/u20:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.913766][ T39] Tainted: [W]=WARN [ 12.913767][ T39] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.913770][ T39] Workqueue: events_unbound linkwatch_event [ 12.913774][ T39] Call Trace: [ 12.913776][ T39] [ 12.913779][ T39] dump_stack_lvl+0x6f/0xa0 [ 12.913788][ T39] __might_resched.cold+0x1fe/0x2c1 [ 12.913794][ T39] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.913800][ T39] ? __kmalloc_noprof+0xdb/0x760 [ 12.913808][ T39] __kmalloc_noprof+0x443/0x760 [ 12.913812][ T39] ? alloc_buf.isra.0+0x4b/0x260 [ 12.913821][ T39] ? do_raw_spin_unlock+0x59/0x250 [ 12.913825][ T39] alloc_buf.isra.0+0x4b/0x260 [ 12.913831][ T39] put_chars+0x1e1/0x2f0 [ 12.913835][ T39] ? __send_to_port+0x420/0x420 [ 12.913838][ T39] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.913845][ T39] ? validate_chain+0x38b/0xc20 [ 12.913855][ T39] hvc_console_print+0x292/0x780 [ 12.913866][ T39] ? hvc_write+0x3a0/0x3a0 [ 12.913870][ T39] ? rcu_is_watching+0x16/0xd0 [ 12.913874][ T39] ? lock_acquire+0x13c/0x160 [ 12.913882][ T39] console_emit_next_record+0x22f/0x4c0 [ 12.913889][ T39] ? devkmsg_read+0x4b0/0x4b0 [ 12.913891][ T39] ? console_flush_one_record+0x106/0x710 [ 12.913897][ T39] ? rcu_is_watching+0x16/0xd0 [ 12.913901][ T39] ? lock_acquire+0x13c/0x160 [ 12.913908][ T39] console_flush_one_record+0x46f/0x710 [ 12.913916][ T39] ? console_emit_next_record+0x4c0/0x4c0 [ 12.913919][ T39] ? __lock_acquire+0x518/0xc20 [ 12.913930][ T39] console_unlock+0xee/0x1f0 [ 12.913934][ T39] ? console_flush_one_record+0x710/0x710 [ 12.913937][ T39] ? rcu_is_watching+0x16/0xd0 [ 12.913941][ T39] ? lock_acquire+0x60/0x160 [ 12.913948][ T39] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.913951][ T39] ? vprintk_emit+0x320/0x3e0 [ 12.913957][ T39] vprintk_emit+0x37c/0x3e0 [ 12.913964][ T39] ? wake_up_klogd_work_func+0x90/0x90 [ 12.913968][ T39] ? mark_held_locks+0x40/0x70 [ 12.913973][ T39] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 12.913980][ T39] _printk+0xc7/0x100 [ 12.913985][ T39] ? snapshot_read.cold+0x21/0x21 [ 12.914001][ T39] br_set_state+0x22f/0x430 [bridge] [ 12.914018][ T39] br_make_forwarding+0xe2/0x200 [bridge] [ 12.914033][ T39] br_port_state_selection+0x389/0x6a0 [bridge] [ 12.914052][ T39] br_stp_enable_port+0x31/0x50 [bridge] [ 12.914065][ T39] br_port_carrier_check+0x220/0x430 [bridge] [ 12.914081][ T39] br_device_event+0x52d/0x8f0 [bridge] [ 12.914096][ T39] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 12.914099][ T39] ? nlmsg_notify+0x62/0x250 [ 12.914112][ T39] notifier_call_chain+0xae/0x300 [ 12.914122][ T39] netif_state_change+0x139/0x340 [ 12.914129][ T39] ? call_netdevice_notifiers_info+0xd0/0xd0 [ 12.914138][ T39] ? linkwatch_do_dev+0xae/0xd0 [ 12.914142][ T39] __linkwatch_run_queue+0x34c/0x750 [ 12.914148][ T39] ? linkwatch_do_dev+0xd0/0xd0 [ 12.914152][ T39] ? lock_acquire.part.0+0xd4/0x280 [ 12.914161][ T39] linkwatch_event+0x7f/0xb0 [ 12.914165][ T39] ? __linkwatch_run_queue+0x750/0x750 [ 12.914171][ T39] process_one_work+0xe3e/0x1560 [ 12.914184][ T39] ? __queue_delayed_work+0x470/0x470 [ 12.914189][ T39] ? lock_acquire.part.0+0xd4/0x280 [ 12.914204][ T39] worker_thread+0x4f1/0xd60 [ 12.914218][ T39] ? rescuer_thread+0x1340/0x1340 [ 12.914221][ T39] kthread+0x367/0x460 [ 12.914225][ T39] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.914228][ T39] ? kthread_affine_preferred+0x4c0/0x4c0 [ 12.914233][ T39] ret_from_fork+0x474/0x6b0 [ 12.914240][ T39] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 12.914246][ T39] ? __switch_to+0x5a3/0xe00 [ 12.914250][ T39] ? kthread_affine_preferred+0x4c0/0x4c0 [ 12.914255][ T39] ret_from_fork_asm+0x11/0x20 [ 12.914271][ T39] [ 12.993050][ T38] br0: port 2(veth2) entered blocking state [ 12.993412][ T38] br0: port 2(veth2) entered forwarding state [ 28.100699][ C1] [ 28.100717][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 28.100720][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 28.100722][ C1] preempt_count: 104, expected: 0 [ 28.100724][ C1] RCU nest depth: 0, expected: 0 [ 28.100725][ C1] INFO: lockdep is turned off. [ 28.100726][ C1] irq event stamp: 601776 [ 28.100727][ C1] hardirqs last enabled at (601776): [] _raw_spin_unlock_irq+0x28/0x50 [ 28.100737][ C1] hardirqs last disabled at (601775): [] _raw_spin_lock_irq+0x4a/0x50 [ 28.100739][ C1] softirqs last enabled at (601742): [] handle_softirqs+0x67c/0x900 [ 28.100743][ C1] softirqs last disabled at (601773): [] __irq_exit_rcu+0x145/0x1c0 [ 28.100744][ C1] Preemption disabled at: [ 28.100745][ C1] [<0000000000000000>] 0x0 [ 28.100752][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.100755][ C1] Tainted: [W]=WARN [ 28.100756][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.100758][ C1] Call Trace: [ 28.100760][ C1] [ 28.100761][ C1] dump_stack_lvl+0x6f/0xa0 [ 28.100767][ C1] __might_resched.cold+0x1fe/0x2c1 [ 28.100770][ C1] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 28.100774][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 28.100777][ C1] ? __asan_memcpy+0x3c/0x60 [ 28.100780][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.100782][ C1] __kmalloc_noprof+0x443/0x760 [ 28.100785][ C1] ? __rwlock_init+0x150/0x150 [ 28.100788][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 28.100791][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 28.100793][ C1] alloc_buf.isra.0+0x4b/0x260 [ 28.100795][ C1] put_chars+0x1e1/0x2f0 [ 28.100797][ C1] ? __send_to_port+0x420/0x420 [ 28.100798][ C1] ? console_prepend_replay+0x20/0x20 [ 28.100802][ C1] hvc_console_print+0x292/0x780 [ 28.100805][ C1] ? hvc_write+0x3a0/0x3a0 [ 28.100807][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.100808][ C1] ? lock_acquire+0x13c/0x160 [ 28.100811][ C1] console_emit_next_record+0x22f/0x4c0 [ 28.100813][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 28.100815][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.100817][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.100819][ C1] ? lock_acquire+0x13c/0x160 [ 28.100821][ C1] ? console_flush_one_record+0x111/0x710 [ 28.100822][ C1] console_flush_one_record+0x46f/0x710 [ 28.100825][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 28.100827][ C1] console_unlock+0xee/0x1f0 [ 28.100829][ C1] ? lock_acquire+0x13c/0x160 [ 28.100831][ C1] ? console_flush_one_record+0x710/0x710 [ 28.100832][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.100834][ C1] ? lock_release+0x184/0x1f0 [ 28.100835][ C1] ? lock_acquire+0x60/0x160 [ 28.100837][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 28.100839][ C1] ? vprintk_emit+0x320/0x3e0 [ 28.100841][ C1] vprintk_emit+0x37c/0x3e0 [ 28.100843][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 28.100845][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.100858][ C1] ? lock_release+0x184/0x1f0 [ 28.100859][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.100866][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.100872][ C1] ? is_module_text_address+0x154/0x250 [ 28.100876][ C1] _printk+0xc7/0x100 [ 28.100879][ C1] ? snapshot_read.cold+0x21/0x21 [ 28.100882][ C1] ? arch_stack_walk+0xd7/0x130 [ 28.100887][ C1] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 28.100890][ C1] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 28.100892][ C1] print_irq_inversion_bug.part.0+0x32/0xc0 [ 28.100896][ C1] mark_lock_irq+0x989/0x9c0 [ 28.100901][ C1] mark_lock+0x1d7/0xa00 [ 28.100903][ C1] mark_usage+0x42/0x170 [ 28.100906][ C1] __lock_acquire+0x388/0xc20 [ 28.100909][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.100912][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.100923][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.100926][ C1] ? lock_acquire+0x13c/0x160 [ 28.100929][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.100940][ C1] _raw_spin_lock+0x33/0x40 [ 28.100942][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.100954][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.100966][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.100977][ C1] call_timer_fn+0x160/0x4d0 [ 28.100981][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 28.100984][ C1] ? debug_object_active_state+0x430/0x430 [ 28.100988][ C1] ? find_held_lock+0x2b/0x80 [ 28.100991][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 28.100994][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.100997][ C1] __run_timers+0x68f/0xaa0 [ 28.101000][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.101012][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 28.101014][ C1] ? __lock_acquire+0x518/0xc20 [ 28.101019][ C1] ? __rwlock_init+0x150/0x150 [ 28.101023][ C1] run_timer_softirq+0xf0/0x160 [ 28.101026][ C1] ? __run_timers+0xaa0/0xaa0 [ 28.101028][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.101032][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.101034][ C1] handle_softirqs+0x1d3/0x900 [ 28.101037][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 28.101040][ C1] ? _local_bh_enable+0xc0/0xc0 [ 28.101043][ C1] __irq_exit_rcu+0x145/0x1c0 [ 28.101045][ C1] irq_exit_rcu+0xe/0x30 [ 28.101048][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.101052][ C1] [ 28.101053][ C1] [ 28.101054][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 28.101057][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.101060][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 28.101064][ 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 [ 28.101066][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000282 [ 28.101070][ C1] RAX: 0000000000092eab RBX: ff11000001bea380 RCX: ffffffffaa4f0307 [ 28.101072][ C1] RDX: ff11000001bea380 RSI: ffffffffad238b01 RDI: ffffffffacc8d8e0 [ 28.101073][ C1] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 28.101075][ C1] R10: 0000000000000001 R11: 0000000000000001 R12: 1ff4000000028fc3 [ 28.101076][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 28.101079][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.101083][ C1] default_idle+0x9/0x10 [ 28.101086][ C1] default_idle_call+0x6e/0xb0 [ 28.101088][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.101091][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 28.101093][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 28.101096][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.101098][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.101101][ C1] do_idle+0xd8/0x190 [ 28.101103][ C1] cpu_startup_entry+0x53/0x70 [ 28.101106][ C1] start_secondary+0x204/0x2b0 [ 28.101108][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 28.101112][ C1] common_startup_64+0x13e/0x148 [ 28.101118][ C1] [ 28.123886][ C1] ======================================================== [ 28.124093][ C1] WARNING: possible irq lock inversion dependency detected [ 28.124344][ C1] 7.2.0-virtme #1 Tainted: G W [ 28.124513][ C1] -------------------------------------------------------- [ 28.124766][ C1] swapper/1/0 just changed the state of lock: [ 28.124985][ C1] ff11000008c2ae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.125330][ C1] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 28.125536][ C1] (fs_reclaim){+.+.}-{0:0} [ 28.125538][ C1] [ 28.125538][ C1] [ 28.125538][ C1] and interrupts could create inverse lock ordering between them. [ 28.125538][ C1] [ 28.126133][ C1] [ 28.126133][ C1] other info that might help us debug this: [ 28.126381][ C1] Chain exists of: [ 28.126381][ C1] &br->lock --> console_owner --> fs_reclaim [ 28.126381][ C1] [ 28.126813][ C1] Possible interrupt unsafe locking scenario: [ 28.126813][ C1] [ 28.127199][ C1] CPU0 CPU1 [ 28.127404][ C1] ---- ---- [ 28.127694][ C1] lock(fs_reclaim); [ 28.127867][ C1] local_irq_disable(); [ 28.128200][ C1] lock(&br->lock); [ 28.128380][ C1] lock(console_owner); [ 28.128611][ C1] [ 28.128717][ C1] lock(&br->lock); [ 28.128869][ C1] [ 28.128869][ C1] *** DEADLOCK *** [ 28.128869][ C1] [ 28.129128][ C1] locks held by swapper/1/0: 1, last CPU#1: [ 28.129356][ C1] #0: ffa00000001d0c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 28.129856][ C1] [ 28.129856][ C1] the shortest dependencies between 2nd lock and 1st lock: [ 28.130284][ C1] -> (fs_reclaim){+.+.}-{0:0} { [ 28.130506][ C1] HARDIRQ-ON-W at: [ 28.130623][ C1] __lock_acquire+0x388/0xc20 [ 28.130856][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.131031][ C1] fs_reclaim_acquire+0xd5/0x120 [ 28.131274][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 28.131625][ C1] kthread_create_worker_on_node+0xea/0x210 [ 28.131945][ C1] workqueue_init+0x2a/0x680 [ 28.132292][ C1] kernel_init_freeable+0x2fe/0x630 [ 28.132691][ C1] kernel_init+0x21/0x150 [ 28.132956][ C1] ret_from_fork+0x474/0x6b0 [ 28.133270][ C1] ret_from_fork_asm+0x11/0x20 [ 28.133496][ C1] SOFTIRQ-ON-W at: [ 28.133613][ C1] __lock_acquire+0x388/0xc20 [ 28.133838][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.134014][ C1] fs_reclaim_acquire+0xd5/0x120 [ 28.134280][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 28.134691][ C1] kthread_create_worker_on_node+0xea/0x210 [ 28.135009][ C1] workqueue_init+0x2a/0x680 [ 28.135350][ C1] kernel_init_freeable+0x2fe/0x630 [ 28.135636][ C1] kernel_init+0x21/0x150 [ 28.135811][ C1] ret_from_fork+0x474/0x6b0 [ 28.136043][ C1] ret_from_fork_asm+0x11/0x20 [ 28.136280][ C1] INITIAL USE at: [ 28.136385][ C1] __lock_acquire+0x388/0xc20 [ 28.136611][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.136787][ C1] fs_reclaim_acquire+0xd5/0x120 [ 28.137005][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 28.137265][ C1] kthread_create_worker_on_node+0xea/0x210 [ 28.137475][ C1] workqueue_init+0x2a/0x680 [ 28.137702][ C1] kernel_init_freeable+0x2fe/0x630 [ 28.137921][ C1] kernel_init+0x21/0x150 [ 28.138096][ C1] ret_from_fork+0x474/0x6b0 [ 28.138316][ C1] ret_from_fork_asm+0x11/0x20 [ 28.138492][ C1] } [ 28.138632][ C1] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 28.138845][ C1] ... acquired at: [ 28.139004][ C1] __lock_acquire+0x518/0xc20 [ 28.139148][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.139344][ C1] fs_reclaim_acquire+0xd5/0x120 [ 28.139494][ C1] __kmalloc_noprof+0xd3/0x760 [ 28.139690][ C1] alloc_buf.isra.0+0x4b/0x260 [ 28.139829][ C1] put_chars+0x1e1/0x2f0 [ 28.140016][ C1] hvc_console_print+0x292/0x780 [ 28.140156][ C1] console_emit_next_record+0x22f/0x4c0 [ 28.140340][ C1] console_flush_one_record+0x46f/0x710 [ 28.140474][ C1] console_unlock+0xee/0x1f0 [ 28.140670][ C1] vprintk_emit+0x37c/0x3e0 [ 28.140807][ C1] _printk+0xc7/0x100 [ 28.140959][ C1] dm_interface_init+0x50/0x60 [ 28.141119][ C1] dm_init+0x51/0xd0 [ 28.141325][ C1] do_one_initcall+0x124/0x4f0 [ 28.141461][ C1] kernel_init_freeable+0x596/0x630 [ 28.141657][ C1] kernel_init+0x21/0x150 [ 28.141804][ C1] ret_from_fork+0x474/0x6b0 [ 28.141992][ C1] ret_from_fork_asm+0x11/0x20 [ 28.142128][ C1] [ 28.142202][ C1] -> (console_owner){....}-{0:0} { [ 28.142391][ C1] INITIAL USE at: [ 28.142495][ C1] } [ 28.142572][ C1] ... key at: [] console_owner_dep_map+0x0/0x60 [ 28.142827][ C1] ... acquired at: [ 28.142978][ C1] __lock_acquire+0x518/0xc20 [ 28.143118][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.143304][ C1] console_lock_spinning_enable+0x5c/0x60 [ 28.143478][ C1] console_emit_next_record+0x1d1/0x4c0 [ 28.143676][ C1] console_flush_one_record+0x46f/0x710 [ 28.143814][ C1] console_unlock+0xee/0x1f0 [ 28.143999][ C1] vprintk_emit+0x37c/0x3e0 [ 28.144137][ C1] _printk+0xc7/0x100 [ 28.144291][ C1] br_set_state+0x22f/0x430 [bridge] [ 28.144439][ C1] br_init_port+0xc4/0x200 [bridge] [ 28.144641][ C1] br_stp_enable_port+0x12/0x50 [bridge] [ 28.144816][ C1] br_port_carrier_check+0x220/0x430 [bridge] [ 28.145044][ C1] br_device_event+0x52d/0x8f0 [bridge] [ 28.145189][ C1] notifier_call_chain+0xae/0x300 [ 28.145376][ C1] netif_state_change+0x139/0x340 [ 28.145518][ C1] __linkwatch_run_queue+0x34c/0x750 [ 28.145720][ C1] linkwatch_event+0x7f/0xb0 [ 28.145859][ C1] process_one_work+0xe3e/0x1560 [ 28.146044][ C1] worker_thread+0x4f1/0xd60 [ 28.146181][ C1] kthread+0x367/0x460 [ 28.146334][ C1] ret_from_fork+0x474/0x6b0 [ 28.146473][ C1] ret_from_fork_asm+0x11/0x20 [ 28.146666][ C1] [ 28.146735][ C1] -> (&br->lock){+.-.}-{3:3} { [ 28.146880][ C1] HARDIRQ-ON-W at: [ 28.147076][ C1] __lock_acquire+0x388/0xc20 [ 28.147269][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.147491][ C1] _raw_spin_lock_bh+0x38/0x50 [ 28.147677][ C1] recalculate_group_addr+0x51/0x120 [bridge] [ 28.147893][ C1] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 28.148153][ C1] br_changelink+0x575/0x16e0 [bridge] [ 28.148390][ C1] br_dev_newlink+0xeb/0x160 [bridge] [ 28.148576][ C1] rtnl_newlink_create+0x2d0/0x750 [ 28.148804][ C1] __rtnl_newlink+0x22b/0xa50 [ 28.149035][ C1] rtnl_newlink+0x9f1/0x11c0 [ 28.149209][ C1] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.149432][ C1] netlink_rcv_skb+0x14e/0x3a0 [ 28.149670][ C1] netlink_unicast+0x486/0x750 [ 28.149844][ C1] netlink_sendmsg+0x735/0xc60 [ 28.150067][ C1] ____sys_sendmsg+0x415/0x880 [ 28.150244][ C1] ___sys_sendmsg+0x14e/0x1d0 [ 28.150465][ C1] __sys_sendmsg+0x12c/0x1d0 [ 28.150693][ C1] do_syscall_64+0xff/0x530 [ 28.150878][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.151139][ C1] IN-SOFTIRQ-W at: [ 28.151247][ C1] __lock_acquire+0x388/0xc20 [ 28.151471][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.151703][ C1] _raw_spin_lock+0x33/0x40 [ 28.151881][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.152143][ C1] call_timer_fn+0x160/0x4d0 [ 28.152371][ C1] __run_timers+0x68f/0xaa0 [ 28.152560][ C1] run_timer_softirq+0xf0/0x160 [ 28.152785][ C1] handle_softirqs+0x1d3/0x900 [ 28.153012][ C1] __irq_exit_rcu+0x145/0x1c0 [ 28.153189][ C1] irq_exit_rcu+0xe/0x30 [ 28.153408][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.153683][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.153902][ C1] pv_native_safe_halt+0xf/0x10 [ 28.154125][ C1] default_idle+0x9/0x10 [ 28.154353][ C1] default_idle_call+0x6e/0xb0 [ 28.154530][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.154759][ C1] do_idle+0xd8/0x190 [ 28.154907][ C1] cpu_startup_entry+0x53/0x70 [ 28.155135][ C1] start_secondary+0x204/0x2b0 [ 28.155365][ C1] common_startup_64+0x13e/0x148 [ 28.155554][ C1] INITIAL USE at: [ 28.155714][ C1] __lock_acquire+0x388/0xc20 [ 28.155890][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.156123][ C1] _raw_spin_lock_bh+0x38/0x50 [ 28.156379][ C1] recalculate_group_addr+0x51/0x120 [bridge] [ 28.156605][ C1] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 28.156823][ C1] br_changelink+0x575/0x16e0 [bridge] [ 28.157146][ C1] br_dev_newlink+0xeb/0x160 [bridge] [ 28.157334][ C1] rtnl_newlink_create+0x2d0/0x750 [ 28.157568][ C1] __rtnl_newlink+0x22b/0xa50 [ 28.157803][ C1] rtnl_newlink+0x9f1/0x11c0 [ 28.157976][ C1] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.158195][ C1] netlink_rcv_skb+0x14e/0x3a0 [ 28.158368][ C1] netlink_unicast+0x486/0x750 [ 28.158595][ C1] netlink_sendmsg+0x735/0xc60 [ 28.158821][ C1] ____sys_sendmsg+0x415/0x880 [ 28.158999][ C1] ___sys_sendmsg+0x14e/0x1d0 [ 28.159222][ C1] __sys_sendmsg+0x12c/0x1d0 [ 28.159444][ C1] do_syscall_64+0xff/0x530 [ 28.159624][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.159879][ C1] } [ 28.159956][ C1] ... key at: [] __key.7+0x0/0x40 [bridge] [ 28.160210][ C1] ... acquired at: [ 28.160313][ C1] mark_lock+0x1d7/0xa00 [ 28.160500][ C1] mark_usage+0x42/0x170 [ 28.160651][ C1] __lock_acquire+0x388/0xc20 [ 28.160838][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.160975][ C1] _raw_spin_lock+0x33/0x40 [ 28.161169][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.161351][ C1] call_timer_fn+0x160/0x4d0 [ 28.161568][ C1] __run_timers+0x68f/0xaa0 [ 28.161712][ C1] run_timer_softirq+0xf0/0x160 [ 28.161902][ C1] handle_softirqs+0x1d3/0x900 [ 28.162050][ C1] __irq_exit_rcu+0x145/0x1c0 [ 28.162310][ C1] irq_exit_rcu+0xe/0x30 [ 28.162456][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.162688][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.162909][ C1] pv_native_safe_halt+0xf/0x10 [ 28.163047][ C1] default_idle+0x9/0x10 [ 28.163235][ C1] default_idle_call+0x6e/0xb0 [ 28.163372][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.163614][ C1] do_idle+0xd8/0x190 [ 28.163727][ C1] cpu_startup_entry+0x53/0x70 [ 28.163917][ C1] start_secondary+0x204/0x2b0 [ 28.164056][ C1] common_startup_64+0x13e/0x148 [ 28.164241][ C1] [ 28.164310][ C1] [ 28.164310][ C1] stack backtrace: [ 28.164536][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.164540][ C1] Tainted: [W]=WARN [ 28.164541][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.164548][ C1] Call Trace: [ 28.164550][ C1] [ 28.164552][ C1] dump_stack_lvl+0x6f/0xa0 [ 28.164557][ C1] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 28.164560][ C1] mark_lock_irq+0x989/0x9c0 [ 28.164563][ C1] mark_lock+0x1d7/0xa00 [ 28.164565][ C1] mark_usage+0x42/0x170 [ 28.164567][ C1] __lock_acquire+0x388/0xc20 [ 28.164569][ C1] lock_acquire.part.0+0xd4/0x280 [ 28.164571][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.164579][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.164582][ C1] ? lock_acquire+0x13c/0x160 [ 28.164584][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.164591][ C1] _raw_spin_lock+0x33/0x40 [ 28.164593][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.164600][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.164606][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.164613][ C1] call_timer_fn+0x160/0x4d0 [ 28.164616][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 28.164618][ C1] ? debug_object_active_state+0x430/0x430 [ 28.164622][ C1] ? find_held_lock+0x2b/0x80 [ 28.164624][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 28.164626][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.164628][ C1] __run_timers+0x68f/0xaa0 [ 28.164630][ C1] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.164637][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 28.164639][ C1] ? __lock_acquire+0x518/0xc20 [ 28.164641][ C1] ? __rwlock_init+0x150/0x150 [ 28.164644][ C1] run_timer_softirq+0xf0/0x160 [ 28.164646][ C1] ? __run_timers+0xaa0/0xaa0 [ 28.164648][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.164650][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.164652][ C1] handle_softirqs+0x1d3/0x900 [ 28.164654][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 28.164655][ C1] ? _local_bh_enable+0xc0/0xc0 [ 28.164657][ C1] __irq_exit_rcu+0x145/0x1c0 [ 28.164659][ C1] irq_exit_rcu+0xe/0x30 [ 28.164660][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.164662][ C1] [ 28.164663][ C1] [ 28.164663][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 28.164665][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.164667][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 28.164669][ 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 [ 28.164672][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000282 [ 28.164675][ C1] RAX: 0000000000092eab RBX: ff11000001bea380 RCX: ffffffffaa4f0307 [ 28.164676][ C1] RDX: ff11000001bea380 RSI: ffffffffad238b01 RDI: ffffffffacc8d8e0 [ 28.164677][ C1] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 28.164678][ C1] R10: 0000000000000001 R11: 0000000000000001 R12: 1ff4000000028fc3 [ 28.164679][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 28.164680][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.164682][ C1] default_idle+0x9/0x10 [ 28.164684][ C1] default_idle_call+0x6e/0xb0 [ 28.164686][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.164688][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 28.164689][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 28.164691][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.164692][ C1] ? rcu_is_watching+0x16/0xd0 [ 28.164694][ C1] do_idle+0xd8/0x190 [ 28.164696][ C1] cpu_startup_entry+0x53/0x70 [ 28.164697][ C1] start_secondary+0x204/0x2b0 [ 28.164699][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 28.164701][ C1] common_startup_64+0x13e/0x148 [ 28.164704][ C1] [ 59.966450][ T533] Mirror/redirect action on [ 59.966464][ T533] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 59.966467][ T533] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 533, name: modprobe [ 59.966468][ T533] preempt_count: 1, expected: 0 [ 59.966469][ T533] RCU nest depth: 0, expected: 0 [ 59.966470][ T533] INFO: lockdep is turned off. [ 59.966471][ T533] irq event stamp: 0 [ 59.966472][ T533] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 59.966476][ T533] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 59.966482][ T533] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 59.966483][ T533] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 59.966485][ T533] Preemption disabled at: [ 59.966486][ T533] [] vprintk_emit+0x31b/0x3e0 [ 59.966493][ T533] CPU: 2 UID: 0 PID: 533 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 59.966496][ T533] Tainted: [W]=WARN [ 59.966498][ T533] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 59.966499][ T533] Call Trace: [ 59.966501][ T533] [ 59.966502][ T533] dump_stack_lvl+0x6f/0xa0 [ 59.966507][ T533] ? vprintk_emit+0x31b/0x3e0 [ 59.966509][ T533] __might_resched.cold+0x1fe/0x2c1 [ 59.966513][ T533] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 59.966514][ T533] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 59.966517][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966521][ T533] __kmalloc_noprof+0x443/0x760 [ 59.966524][ T533] ? __rwlock_init+0x150/0x150 [ 59.966527][ T533] ? alloc_buf.isra.0+0x4b/0x260 [ 59.966531][ T533] ? do_raw_spin_unlock+0x59/0x250 [ 59.966532][ T533] alloc_buf.isra.0+0x4b/0x260 [ 59.966534][ T533] put_chars+0x1e1/0x2f0 [ 59.966536][ T533] ? __send_to_port+0x420/0x420 [ 59.966538][ T533] ? console_prepend_replay+0x20/0x20 [ 59.966540][ T533] hvc_console_print+0x292/0x780 [ 59.966544][ T533] ? hvc_write+0x3a0/0x3a0 [ 59.966545][ T533] ? vsnprintf+0x668/0x1700 [ 59.966548][ T533] ? __rwlock_init+0x150/0x150 [ 59.966549][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966551][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966553][ T533] console_emit_next_record+0x22f/0x4c0 [ 59.966556][ T533] ? devkmsg_read+0x4b0/0x4b0 [ 59.966558][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966560][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966561][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966563][ T533] ? lock_acquire+0x13c/0x160 [ 59.966565][ T533] ? console_flush_one_record+0x111/0x710 [ 59.966566][ T533] console_flush_one_record+0x46f/0x710 [ 59.966569][ T533] ? console_emit_next_record+0x4c0/0x4c0 [ 59.966571][ T533] console_unlock+0xee/0x1f0 [ 59.966573][ T533] ? lock_acquire+0x13c/0x160 [ 59.966575][ T533] ? console_flush_one_record+0x710/0x710 [ 59.966576][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966578][ T533] ? lock_release+0x184/0x1f0 [ 59.966579][ T533] ? lock_acquire+0x60/0x160 [ 59.966581][ T533] ? __rcu_read_lock+0x30/0x70 [ 59.966583][ T533] ? __down_trylock_console_sem+0x5e/0xa0 [ 59.966585][ T533] ? vprintk_emit+0x320/0x3e0 [ 59.966587][ T533] vprintk_emit+0x37c/0x3e0 [ 59.966589][ T533] ? wake_up_klogd_work_func+0x90/0x90 [ 59.966591][ T533] ? netdev_run_todo+0x2a8/0xfd0 [ 59.966595][ T533] ? mirred_device_event+0x3d/0x1d0 [act_mirred] [ 59.966597][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966599][ T533] ? llc_exit+0x3e0/0x3e0 [llc] [ 59.966602][ T533] _printk+0xc7/0x100 [ 59.966605][ T533] ? snapshot_read.cold+0x21/0x21 [ 59.966607][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966609][ T533] ? llc_exit+0x3e0/0x3e0 [llc] [ 59.966610][ T533] ? __up_write+0x23d/0x5f0 [ 59.966611][ T533] ? __up_write+0x2e5/0x5f0 [ 59.966612][ T533] ? lock_release+0x184/0x1f0 [ 59.966614][ T533] ? __up_write+0x2c7/0x5f0 [ 59.966616][ T533] mirred_init_module+0x28/0x1000 [act_mirred] [ 59.966618][ T533] do_one_initcall+0x124/0x4f0 [ 59.966620][ T533] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 59.966622][ T533] ? do_init_module+0xae/0x8a0 [ 59.966625][ T533] ? kasan_unpoison+0x44/0x70 [ 59.966628][ T533] do_init_module+0x2ad/0x8a0 [ 59.966632][ T533] ? free_module+0x470/0x470 [ 59.966634][ T533] ? kfree+0x123/0x590 [ 59.966637][ T533] load_module+0x1424/0x1bb0 [ 59.966640][ T533] ? layout_and_allocate.isra.0+0x680/0x680 [ 59.966642][ T533] ? __ia32_sys_fsconfig+0x180/0x180 [ 59.966645][ T533] ? ovl_real_file+0x172/0x1e0 [ 59.966648][ T533] ? backing_file_read_iter+0x541/0x790 [ 59.966651][ T533] init_module_from_file+0x11f/0x1a0 [ 59.966654][ T533] ? __do_sys_init_module+0x220/0x220 [ 59.966656][ T533] ? rcu_read_unlock+0x20/0x70 [ 59.966659][ T533] ? ovl_mmap+0x270/0x270 [ 59.966662][ T533] ? __rwlock_init+0x150/0x150 [ 59.966664][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966666][ T533] ? lock_release+0x184/0x1f0 [ 59.966668][ T533] ? do_raw_spin_unlock+0x59/0x250 [ 59.966669][ T533] idempotent_init_module+0x37b/0x7c0 [ 59.966672][ T533] ? init_module_from_file+0x1a0/0x1a0 [ 59.966674][ T533] ? rcu_is_watching+0x16/0xd0 [ 59.966676][ T533] ? cap_capable+0x1d7/0x3d0 [ 59.966680][ T533] __x64_sys_finit_module+0xdf/0x190 [ 59.966682][ T533] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 59.966685][ T533] do_syscall_64+0xff/0x530 [ 59.966687][ T533] ? exc_page_fault+0xee/0x100 [ 59.966689][ T533] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 59.966691][ T533] RIP: 0033:0x7f1ce9edc37d [ 59.966693][ T533] 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 [ 59.966695][ T533] RSP: 002b:00007ffe75d72748 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 59.966698][ T533] RAX: ffffffffffffffda RBX: 000055fb75d4b810 RCX: 00007f1ce9edc37d [ 59.966700][ T533] RDX: 0000000000000000 RSI: 000055fb67dc8ffd RDI: 0000000000000000 [ 59.966701][ T533] RBP: 00007ffe75d727e0 R08: 0000000000000000 R09: 0000000000000000 [ 59.966702][ T533] R10: 0000000000000000 R11: 0000000000000246 R12: 000055fb67dc8ffd [ 59.966702][ T533] R13: 000055fb75d4b960 R14: 0000000000040000 R15: 0000000000000000 [ 59.966705][ T533] [ 66.394621][ T574] br0: port 2(veth2) entered disabled state [ 66.394651][ T574] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 66.394654][ T574] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 574, name: ip [ 66.394656][ T574] preempt_count: 202, expected: 0 [ 66.394658][ T574] RCU nest depth: 0, expected: 0 [ 66.394659][ T574] INFO: lockdep is turned off. [ 66.394661][ T574] irq event stamp: 0 [ 66.394662][ T574] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 66.394668][ T574] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 66.394675][ T574] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 66.394677][ T574] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 66.394680][ T574] Preemption disabled at: [ 66.394681][ T574] [<0000000000000000>] 0x0 [ 66.394687][ T574] CPU: 3 UID: 0 PID: 574 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 66.394692][ T574] Tainted: [W]=WARN [ 66.394693][ T574] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 66.394696][ T574] Call Trace: [ 66.394698][ T574] [ 66.394699][ T574] dump_stack_lvl+0x6f/0xa0 [ 66.394706][ T574] __might_resched.cold+0x1fe/0x2c1 [ 66.394711][ T574] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 66.394715][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.394720][ T574] __kmalloc_noprof+0x443/0x760 [ 66.394725][ T574] ? __rwlock_init+0x150/0x150 [ 66.394728][ T574] ? alloc_buf.isra.0+0x4b/0x260 [ 66.394733][ T574] ? do_raw_spin_unlock+0x59/0x250 [ 66.394736][ T574] alloc_buf.isra.0+0x4b/0x260 [ 66.394739][ T574] put_chars+0x1e1/0x2f0 [ 66.394741][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.394744][ T574] ? __send_to_port+0x420/0x420 [ 66.394747][ T574] ? printk_get_next_message+0x240/0x650 [ 66.394750][ T574] ? kvm_clock_get_cycles+0x19/0x30 [ 66.394759][ T574] ? ktime_get+0x1dd/0x2d0 [ 66.394763][ T574] hvc_console_print+0x292/0x780 [ 66.394768][ T574] ? hvc_write+0x3a0/0x3a0 [ 66.394770][ T574] ? __rwlock_init+0x150/0x150 [ 66.394773][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.394775][ T574] ? lock_acquire+0x13c/0x160 [ 66.394779][ T574] console_emit_next_record+0x22f/0x4c0 [ 66.394783][ T574] ? devkmsg_read+0x4b0/0x4b0 [ 66.394786][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.394788][ T574] ? lock_acquire+0x13c/0x160 [ 66.394791][ T574] ? console_flush_one_record+0x111/0x710 [ 66.394794][ T574] console_flush_one_record+0x46f/0x710 [ 66.394797][ T574] ? console_emit_next_record+0x4c0/0x4c0 [ 66.394801][ T574] console_unlock+0xee/0x1f0 [ 66.394804][ T574] ? lock_acquire+0x13c/0x160 [ 66.394806][ T574] ? console_flush_one_record+0x710/0x710 [ 66.394809][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.394811][ T574] ? lock_release+0x184/0x1f0 [ 66.394813][ T574] ? lock_acquire+0x60/0x160 [ 66.394816][ T574] ? __rcu_read_lock+0x30/0x70 [ 66.394819][ T574] ? __down_trylock_console_sem+0x5e/0xa0 [ 66.394821][ T574] ? vprintk_emit+0x320/0x3e0 [ 66.394825][ T574] vprintk_emit+0x37c/0x3e0 [ 66.394829][ T574] ? wake_up_klogd_work_func+0x90/0x90 [ 66.394831][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.394834][ T574] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 66.394837][ T574] ? trace_hardirqs_on+0x36/0x40 [ 66.394840][ T574] _printk+0xc7/0x100 [ 66.394844][ T574] ? snapshot_read.cold+0x21/0x21 [ 66.394847][ T574] ? ipv6_mc_down+0x17e/0x2d0 [ 66.394854][ T574] br_set_state+0x22f/0x430 [bridge] [ 66.394872][ T574] br_stp_disable_port+0x11f/0x390 [bridge] [ 66.394886][ T574] br_device_event+0x7b5/0x8f0 [bridge] [ 66.394900][ T574] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 66.394904][ T574] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 66.394908][ T574] ? addrconf_notify+0x2c8/0xf30 [ 66.394913][ T574] notifier_call_chain+0xae/0x300 [ 66.394917][ T574] __dev_notify_flags+0x23c/0x3c0 [ 66.394922][ T574] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 66.394925][ T574] ? netif_change_name+0x8c0/0x8c0 [ 66.394928][ T574] ? netif_set_allmulti+0x250/0x250 [ 66.394931][ T574] ? __kernel_text_address+0x12/0x30 [ 66.394934][ T574] ? write_profile+0xf0/0xf0 [ 66.394937][ T574] ? unwind_get_return_address+0x67/0xd0 [ 66.394940][ T574] netif_change_flags+0xfe/0x190 [ 66.394943][ T574] do_setlink.isra.0+0x1cc3/0x2750 [ 66.394947][ T574] ? stack_trace_save+0x93/0xc0 [ 66.394950][ T574] ? rtnl_link_get_size+0x350/0x350 [ 66.394952][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.394954][ T574] ? stack_depot_save_flags+0x1bc/0x790 [ 66.394959][ T574] ? kasan_save_stack+0x3d/0x50 [ 66.394963][ T574] ? kasan_save_stack+0x2f/0x50 [ 66.394965][ T574] ? kasan_save_track+0x14/0x30 [ 66.394968][ T574] ? __kasan_kmalloc+0x7b/0x90 [ 66.394970][ T574] ? __kmalloc_cache_noprof+0x29a/0x620 [ 66.394972][ T574] ? rtnl_newlink+0x124/0x11c0 [ 66.394974][ T574] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 66.394976][ T574] ? netlink_rcv_skb+0x14e/0x3a0 [ 66.394979][ T574] ? netlink_unicast+0x486/0x750 [ 66.394981][ T574] ? netlink_sendmsg+0x735/0xc60 [ 66.394983][ T574] ? ____sys_sendmsg+0x415/0x880 [ 66.394986][ T574] ? ___sys_sendmsg+0x14e/0x1d0 [ 66.394988][ T574] ? __sys_sendmsg+0x12c/0x1d0 [ 66.394991][ T574] ? do_syscall_64+0xff/0x530 [ 66.394994][ T574] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 66.394998][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395000][ T574] ? lock_acquire+0x13c/0x160 [ 66.395002][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395005][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395008][ T574] ? trace_contention_end+0xb3/0x180 [ 66.395012][ T574] ? __mutex_lock+0x1db/0x1ea0 [ 66.395014][ T574] ? __mutex_lock+0x9a3/0x1ea0 [ 66.395016][ T574] ? rtnl_newlink+0x9a8/0x11c0 [ 66.395019][ T574] ? ww_mutex_lock+0x160/0x160 [ 66.395022][ T574] ? __rtnl_newlink+0x3fa/0xa50 [ 66.395026][ T574] rtnl_newlink+0x9f1/0x11c0 [ 66.395029][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395032][ T574] ? rtnl_bridge_getlink+0x850/0x850 [ 66.395034][ T574] ? lock_acquire+0x13c/0x160 [ 66.395036][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395039][ T574] ? is_bpf_text_address+0x3e/0x110 [ 66.395043][ T574] ? is_bpf_text_address+0x72/0x110 [ 66.395046][ T574] ? kernel_text_address+0x149/0x170 [ 66.395048][ T574] ? __kernel_text_address+0x12/0x30 [ 66.395050][ T574] ? write_profile+0xf0/0xf0 [ 66.395052][ T574] ? unwind_get_return_address+0x67/0xd0 [ 66.395054][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395057][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395059][ T574] ? cap_capable+0x1d7/0x3d0 [ 66.395064][ T574] ? lock_acquire+0x13c/0x160 [ 66.395066][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395069][ T574] ? rtnl_bridge_getlink+0x850/0x850 [ 66.395071][ T574] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 66.395074][ T574] ? rtnl_link_fill+0x920/0x920 [ 66.395076][ T574] ? filter_irq_stacks+0xd0/0xd0 [ 66.395078][ T574] ? stack_depot_save_flags+0x1bc/0x790 [ 66.395082][ T574] netlink_rcv_skb+0x14e/0x3a0 [ 66.395085][ T574] ? rtnl_link_fill+0x920/0x920 [ 66.395087][ T574] ? lock_release+0x184/0x1f0 [ 66.395090][ T574] ? netlink_ack+0xcf0/0xcf0 [ 66.395092][ T574] ? __netlink_lookup+0xb9/0x6b0 [ 66.395095][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395098][ T574] ? lock_release+0x184/0x1f0 [ 66.395101][ T574] netlink_unicast+0x486/0x750 [ 66.395104][ T574] ? netlink_attachskb+0x810/0x810 [ 66.395107][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395109][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395112][ T574] ? lock_acquire+0x13c/0x160 [ 66.395115][ T574] netlink_sendmsg+0x735/0xc60 [ 66.395118][ T574] ? netlink_unicast+0x750/0x750 [ 66.395121][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395124][ T574] ? lock_release+0x184/0x1f0 [ 66.395127][ T574] ____sys_sendmsg+0x415/0x880 [ 66.395129][ T574] ? copy_msghdr_from_user+0x279/0x420 [ 66.395132][ T574] ? get_timestamp.constprop.0+0x390/0x390 [ 66.395134][ T574] ? move_addr_to_kernel+0x40/0x40 [ 66.395140][ T574] ? filter_irq_stacks+0xd0/0xd0 [ 66.395144][ T574] ___sys_sendmsg+0x14e/0x1d0 [ 66.395147][ T574] ? copy_msghdr_from_user+0x420/0x420 [ 66.395155][ T574] __sys_sendmsg+0x12c/0x1d0 [ 66.395158][ T574] ? __sys_sendmsg_sock+0x20/0x20 [ 66.395162][ T574] ? rcu_is_watching+0x16/0xd0 [ 66.395166][ T574] do_syscall_64+0xff/0x530 [ 66.395168][ T574] ? exc_page_fault+0xee/0x100 [ 66.395171][ T574] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 66.395174][ T574] RIP: 0033:0x7f3008d4954e [ 66.395176][ T574] 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 [ 66.395179][ T574] RSP: 002b:00007ffe9caa1980 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 66.395184][ T574] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f3008d4954e [ 66.395186][ T574] RDX: 0000000000000000 RSI: 00007ffe9caa1a30 RDI: 0000000000000005 [ 66.395188][ T574] RBP: 00007ffe9caa1990 R08: 0000000000000000 R09: 0000000000000000 [ 66.395189][ T574] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9155a0 [ 66.395191][ T574] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe9caa20f0 [ 66.395195][ T574] [ 66.451354][ T574] ip (574) used greatest stack depth: 23016 bytes left [ 66.471620][ T575] br0: port 1(veth1) entered disabled state [ 66.496973][ T576] veth2: left allmulticast mode [ 66.497152][ T576] veth2: left promiscuous mode [ 66.497363][ T576] br0: port 2(veth2) entered disabled state [ 66.497955][ T576] veth1: left allmulticast mode [ 66.498094][ T576] veth1: left promiscuous mode [ 66.498285][ T576] br0: port 1(veth1) entered disabled state