[ 11.446095][ T226] 8021q: 802.1Q VLAN Support v1.8 [ 11.446112][ T226] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.446114][ T226] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 226, name: modprobe [ 11.446115][ T226] preempt_count: 1, expected: 0 [ 11.446116][ T226] RCU nest depth: 0, expected: 0 [ 11.446117][ T226] locks held by modprobe/226: 4, last CPU#3: [ 11.446120][ T226] #0: ffffffffb0d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.446133][ T226] #1: ffffffffb0d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.446137][ T226] #2: ffffffffb0c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.446141][ T226] #3: ffffffffb0c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.446145][ T226] irq event stamp: 23542 [ 11.446146][ T226] hardirqs last enabled at (23541): [] __down_trylock_console_sem+0x86/0xa0 [ 11.446148][ T226] hardirqs last disabled at (23542): [] console_emit_next_record+0x3d4/0x4c0 [ 11.446150][ T226] softirqs last enabled at (21236): [] handle_softirqs+0x67c/0x900 [ 11.446153][ T226] softirqs last disabled at (21229): [] __irq_exit_rcu+0x145/0x1c0 [ 11.446156][ T226] Preemption disabled at: [ 11.446156][ T226] [] vprintk_emit+0x31b/0x3e0 [ 11.446162][ T226] CPU: 3 UID: 0 PID: 226 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.446165][ T226] Tainted: [W]=WARN [ 11.446166][ T226] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.446168][ T226] Call Trace: [ 11.446170][ T226] [ 11.446171][ T226] dump_stack_lvl+0x6f/0xa0 [ 11.446177][ T226] ? vprintk_emit+0x31b/0x3e0 [ 11.446179][ T226] __might_resched.cold+0x1fe/0x2c1 [ 11.446184][ T226] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.446188][ T226] ? __kmalloc_noprof+0xdb/0x760 [ 11.446193][ T226] __kmalloc_noprof+0x443/0x760 [ 11.446195][ T226] ? alloc_buf.isra.0+0x4b/0x260 [ 11.446201][ T226] ? do_raw_spin_unlock+0x59/0x250 [ 11.446204][ T226] alloc_buf.isra.0+0x4b/0x260 [ 11.446207][ T226] put_chars+0x1e1/0x2f0 [ 11.446210][ T226] ? __send_to_port+0x420/0x420 [ 11.446211][ T226] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.446216][ T226] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.446219][ T226] ? validate_chain+0x38b/0xc20 [ 11.446223][ T226] hvc_console_print+0x292/0x780 [ 11.446225][ T226] ? __lock_acquire+0x518/0xc20 [ 11.446230][ T226] ? hvc_write+0x3a0/0x3a0 [ 11.446233][ T226] ? rcu_is_watching+0x16/0xd0 [ 11.446237][ T226] console_emit_next_record+0x22f/0x4c0 [ 11.446241][ T226] ? devkmsg_read+0x4b0/0x4b0 [ 11.446243][ T226] ? console_flush_one_record+0x106/0x710 [ 11.446246][ T226] ? rcu_is_watching+0x16/0xd0 [ 11.446248][ T226] ? lock_acquire+0x13c/0x160 [ 11.446252][ T226] console_flush_one_record+0x46f/0x710 [ 11.446256][ T226] ? console_emit_next_record+0x4c0/0x4c0 [ 11.446258][ T226] ? __lock_acquire+0x518/0xc20 [ 11.446262][ T226] console_unlock+0xee/0x1f0 [ 11.446265][ T226] ? console_flush_one_record+0x710/0x710 [ 11.446267][ T226] ? rcu_is_watching+0x16/0xd0 [ 11.446269][ T226] ? lock_acquire+0x60/0x160 [ 11.446273][ T226] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.446274][ T226] ? vprintk_emit+0x320/0x3e0 [ 11.446278][ T226] vprintk_emit+0x37c/0x3e0 [ 11.446282][ T226] ? wake_up_klogd_work_func+0x90/0x90 [ 11.446287][ T226] ? vlan_netlink_fini+0x960/0x960 [8021q] [ 11.446291][ T226] _printk+0xc7/0x100 [ 11.446295][ T226] ? snapshot_read.cold+0x21/0x21 [ 11.446301][ T226] ? do_one_initcall+0x113/0x4f0 [ 11.446304][ T226] vlan_proto_init+0x24/0x90 [8021q] [ 11.446307][ T226] do_one_initcall+0x124/0x4f0 [ 11.446309][ T226] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 11.446314][ T226] ? do_init_module+0xae/0x8a0 [ 11.446317][ T226] ? kasan_unpoison+0x44/0x70 [ 11.446322][ T226] do_init_module+0x2ad/0x8a0 [ 11.446325][ T226] ? lockdep_hardirqs_on+0x91/0x130 [ 11.446329][ T226] ? free_module+0x470/0x470 [ 11.446332][ T226] ? kfree+0x123/0x590 [ 11.446336][ T226] load_module+0x1424/0x1bb0 [ 11.446343][ T226] ? layout_and_allocate.isra.0+0x680/0x680 [ 11.446347][ T226] ? __ia32_sys_fsconfig+0x180/0x180 [ 11.446350][ T226] ? ovl_real_file+0x172/0x1e0 [ 11.446354][ T226] ? backing_file_read_iter+0x541/0x790 [ 11.446357][ T226] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.446361][ T226] init_module_from_file+0x11f/0x1a0 [ 11.446364][ T226] ? __do_sys_init_module+0x220/0x220 [ 11.446372][ T226] ? __rwlock_init+0x150/0x150 [ 11.446375][ T226] ? do_raw_spin_unlock+0x59/0x250 [ 11.446378][ T226] idempotent_init_module+0x37b/0x7c0 [ 11.446382][ T226] ? init_module_from_file+0x1a0/0x1a0 [ 11.446388][ T226] ? rcu_is_watching+0x16/0xd0 [ 11.446390][ T226] ? cap_capable+0x1d7/0x3d0 [ 11.446395][ T226] __x64_sys_finit_module+0xdf/0x190 [ 11.446398][ T226] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.446401][ T226] do_syscall_64+0xff/0x530 [ 11.446403][ T226] ? exc_page_fault+0xee/0x100 [ 11.446406][ T226] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.446408][ T226] RIP: 0033:0x7f7b51b9237d [ 11.446411][ T226] 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.446412][ T226] RSP: 002b:00007ffc4b97e3c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 11.446415][ T226] RAX: ffffffffffffffda RBX: 000055f98d3de810 RCX: 00007f7b51b9237d [ 11.446416][ T226] RDX: 0000000000000000 RSI: 000055f963b5effd RDI: 0000000000000000 [ 11.446417][ T226] RBP: 00007ffc4b97e460 R08: 0000000000000000 R09: 0000000000000000 [ 11.446418][ T226] R10: 0000000000000000 R11: 0000000000000246 R12: 000055f963b5effd [ 11.446419][ T226] R13: 000055f98d3de960 R14: 0000000000040000 R15: 0000000000000000 [ 11.446425][ T226] [ 12.093380][ T247] br0: port 1(veth1) entered blocking state [ 12.093859][ T247] br0: port 1(veth1) entered disabled state [ 12.094161][ T247] veth1: entered allmulticast mode [ 12.102253][ T247] veth1: entered promiscuous mode [ 12.134508][ T249] br0: port 2(veth2) entered blocking state [ 12.134857][ T249] br0: port 2(veth2) entered disabled state [ 12.135147][ T249] veth2: entered allmulticast mode [ 12.139281][ T249] veth2: entered promiscuous mode [ 12.226533][ T46] br0: port 1(veth1) entered blocking state [ 12.226956][ T46] br0: port 1(veth1) entered forwarding state [ 12.258013][ T92] br0: port 2(veth2) entered blocking state [ 12.258312][ T92] br0: port 2(veth2) entered forwarding state [ 27.512730][ C0] [ 27.512744][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.512746][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 27.512748][ C0] preempt_count: 104, expected: 0 [ 27.512749][ C0] RCU nest depth: 0, expected: 0 [ 27.512750][ C0] INFO: lockdep is turned off. [ 27.512750][ C0] irq event stamp: 617570 [ 27.512751][ C0] hardirqs last enabled at (617570): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.512759][ C0] hardirqs last disabled at (617569): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.512761][ C0] softirqs last enabled at (617542): [] handle_softirqs+0x67c/0x900 [ 27.512765][ C0] softirqs last disabled at (617567): [] __irq_exit_rcu+0x145/0x1c0 [ 27.512767][ C0] Preemption disabled at: [ 27.512767][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 27.512772][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.512776][ C0] Tainted: [W]=WARN [ 27.512777][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.512779][ C0] Call Trace: [ 27.512780][ C0] [ 27.512782][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.512788][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 27.512790][ C0] __might_resched.cold+0x1fe/0x2c1 [ 27.512793][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.512797][ C0] ? __asan_memcpy+0x3c/0x60 [ 27.512800][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.512803][ C0] __kmalloc_noprof+0x443/0x760 [ 27.512806][ C0] ? __rwlock_init+0x150/0x150 [ 27.512809][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 27.512812][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.512814][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.512816][ C0] put_chars+0x1e1/0x2f0 [ 27.512818][ C0] ? __send_to_port+0x420/0x420 [ 27.512820][ C0] ? console_prepend_replay+0x20/0x20 [ 27.512823][ C0] hvc_console_print+0x292/0x780 [ 27.512826][ C0] ? hvc_write+0x3a0/0x3a0 [ 27.512828][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.512829][ C0] ? lock_acquire+0x13c/0x160 [ 27.512831][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.512834][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 27.512836][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.512838][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.512839][ C0] ? lock_acquire+0x13c/0x160 [ 27.512841][ C0] ? console_flush_one_record+0x111/0x710 [ 27.512843][ C0] console_flush_one_record+0x46f/0x710 [ 27.512845][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 27.512848][ C0] console_unlock+0xee/0x1f0 [ 27.512850][ C0] ? lock_acquire+0x13c/0x160 [ 27.512851][ C0] ? console_flush_one_record+0x710/0x710 [ 27.512853][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.512854][ C0] ? lock_release+0x184/0x1f0 [ 27.512856][ C0] ? lock_acquire+0x60/0x160 [ 27.512858][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.512860][ C0] ? vprintk_emit+0x320/0x3e0 [ 27.512862][ C0] vprintk_emit+0x37c/0x3e0 [ 27.512864][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 27.512866][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.512868][ C0] ? lock_release+0x184/0x1f0 [ 27.512869][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 27.512874][ C0] _printk+0xc7/0x100 [ 27.512877][ C0] ? snapshot_read.cold+0x21/0x21 [ 27.512879][ C0] ? arch_stack_walk+0xd7/0x130 [ 27.512883][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.512886][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.512887][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.512889][ C0] mark_lock_irq+0x989/0x9c0 [ 27.512892][ C0] mark_lock+0x1d7/0xa00 [ 27.512894][ C0] mark_usage+0x42/0x170 [ 27.512896][ C0] __lock_acquire+0x388/0xc20 [ 27.512898][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.512900][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.512912][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.512914][ C0] ? lock_acquire+0x13c/0x160 [ 27.512916][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.512922][ C0] _raw_spin_lock+0x33/0x40 [ 27.512924][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.512930][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.512936][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.512943][ C0] call_timer_fn+0x160/0x4d0 [ 27.512946][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.512948][ C0] ? debug_object_active_state+0x430/0x430 [ 27.512952][ C0] ? find_held_lock+0x2b/0x80 [ 27.512953][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.512955][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.512957][ C0] __run_timers+0x68f/0xaa0 [ 27.512959][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.512966][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.512968][ C0] ? __lock_acquire+0x518/0xc20 [ 27.512970][ C0] ? __rwlock_init+0x150/0x150 [ 27.512973][ C0] run_timer_softirq+0xf0/0x160 [ 27.512975][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.512976][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.512979][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.512980][ C0] handle_softirqs+0x1d3/0x900 [ 27.512982][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.512984][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.512986][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.512987][ C0] irq_exit_rcu+0xe/0x30 [ 27.512989][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.512992][ C0] [ 27.512992][ C0] [ 27.512993][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.512994][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.512997][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.512999][ C0] Code: 48 8b 3d 94 f2 f5 01 e8 1f 00 00 00 48 2b 05 58 b3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.513001][ C0] RSP: 0018:ffffffffb0a07cf8 EFLAGS: 00000296 [ 27.513004][ C0] RAX: 0000000000096c5d RBX: ffffffffb0a1c600 RCX: ffffffffadaf0307 [ 27.513005][ C0] RDX: ffffffffb0a1c600 RSI: ffffffffb0838b1c RDI: ffffffffb028d8e0 [ 27.513006][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.513007][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6140fa2 [ 27.513008][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.513010][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.513013][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.513014][ C0] default_idle+0x9/0x10 [ 27.513016][ C0] default_idle_call+0x6e/0xb0 [ 27.513018][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.513019][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.513021][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.513023][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.513025][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.513027][ C0] do_idle+0xd8/0x190 [ 27.513028][ C0] cpu_startup_entry+0x53/0x70 [ 27.513030][ C0] rest_init+0x279/0x280 [ 27.513031][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.513034][ C0] ? rest_init+0x280/0x280 [ 27.513036][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.513040][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.513042][ C0] ? acpi_enable+0x1e4/0x330 [ 27.513045][ C0] start_kernel+0x3af/0x3b0 [ 27.513047][ C0] x86_64_start_reservations+0x24/0x30 [ 27.513049][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.513051][ C0] common_startup_64+0x13e/0x148 [ 27.513055][ C0] [ 27.534141][ C0] ======================================================== [ 27.534416][ C0] WARNING: possible irq lock inversion dependency detected [ 27.534648][ C0] 7.2.0-virtme #1 Tainted: G W [ 27.534835][ C0] -------------------------------------------------------- [ 27.535106][ C0] swapper/0/0 just changed the state of lock: [ 27.535290][ C0] ff1100000ccd2e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.535659][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.535932][ C0] (fs_reclaim){+.+.}-{0:0} [ 27.535934][ C0] [ 27.535934][ C0] [ 27.535934][ C0] and interrupts could create inverse lock ordering between them. [ 27.535934][ C0] [ 27.536585][ C0] [ 27.536585][ C0] other info that might help us debug this: [ 27.536865][ C0] Chain exists of: [ 27.536865][ C0] &br->lock --> console_owner --> fs_reclaim [ 27.536865][ C0] [ 27.537258][ C0] Possible interrupt unsafe locking scenario: [ 27.537258][ C0] [ 27.537534][ C0] CPU0 CPU1 [ 27.537691][ C0] ---- ---- [ 27.537896][ C0] lock(fs_reclaim); [ 27.538014][ C0] local_irq_disable(); [ 27.538253][ C0] lock(&br->lock); [ 27.538442][ C0] lock(console_owner); [ 27.538693][ C0] [ 27.538807][ C0] lock(&br->lock); [ 27.538976][ C0] [ 27.538976][ C0] *** DEADLOCK *** [ 27.538976][ C0] [ 27.539195][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 27.539432][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.539803][ C0] [ 27.539803][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 27.540116][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 27.540270][ C0] HARDIRQ-ON-W at: [ 27.540438][ C0] __lock_acquire+0x388/0xc20 [ 27.540645][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.540833][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.541022][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.541249][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.541530][ C0] workqueue_init+0x2a/0x680 [ 27.545462][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.545706][ C0] kernel_init+0x21/0x150 [ 27.545895][ C0] ret_from_fork+0x474/0x6b0 [ 27.546138][ C0] ret_from_fork_asm+0x11/0x20 [ 27.546326][ C0] SOFTIRQ-ON-W at: [ 27.546498][ C0] __lock_acquire+0x388/0xc20 [ 27.546705][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.546947][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.547194][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.547425][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.547718][ C0] workqueue_init+0x2a/0x680 [ 27.547962][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.548243][ C0] kernel_init+0x21/0x150 [ 27.548432][ C0] ret_from_fork+0x474/0x6b0 [ 27.548684][ C0] ret_from_fork_asm+0x11/0x20 [ 27.548873][ C0] INITIAL USE at: [ 27.549038][ C0] __lock_acquire+0x388/0xc20 [ 27.549226][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.549467][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.549724][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.549950][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.550231][ C0] workqueue_init+0x2a/0x680 [ 27.550476][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.550675][ C0] kernel_init+0x21/0x150 [ 27.550863][ C0] ret_from_fork+0x474/0x6b0 [ 27.551107][ C0] ret_from_fork_asm+0x11/0x20 [ 27.551297][ C0] } [ 27.551429][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.551668][ C0] ... acquired at: [ 27.551834][ C0] __lock_acquire+0x518/0xc20 [ 27.551987][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.552189][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.552337][ C0] __kmalloc_noprof+0xd3/0x760 [ 27.552540][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.552705][ C0] put_chars+0x1e1/0x2f0 [ 27.552907][ C0] hvc_console_print+0x292/0x780 [ 27.553058][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.553265][ C0] console_flush_one_record+0x46f/0x710 [ 27.553414][ C0] console_unlock+0xee/0x1f0 [ 27.553628][ C0] vprintk_emit+0x37c/0x3e0 [ 27.553778][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 27.553986][ C0] dev_printk_emit+0xb9/0xee [ 27.554136][ C0] _dev_info+0xe2/0x116 [ 27.554303][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 27.554491][ C0] cmos_do_probe+0x73b/0x98a [ 27.554705][ C0] platform_probe+0xfe/0x1f0 [ 27.554857][ C0] call_driver_probe+0x61/0x1c0 [ 27.555061][ C0] really_probe+0x199/0x760 [ 27.555213][ C0] __driver_probe_device+0x24f/0x440 [ 27.555420][ C0] driver_probe_device+0x4a/0xf0 [ 27.555571][ C0] __driver_attach+0x1b8/0x540 [ 27.555784][ C0] bus_for_each_dev+0x130/0x1e0 [ 27.555935][ C0] bus_add_driver+0x2c8/0x530 [ 27.556139][ C0] driver_register+0x1a3/0x390 [ 27.556291][ C0] __platform_driver_probe+0x13f/0x270 [ 27.556495][ C0] cmos_init+0x31/0x40 [ 27.556620][ C0] do_one_initcall+0x124/0x4f0 [ 27.556827][ C0] kernel_init_freeable+0x596/0x630 [ 27.556976][ C0] kernel_init+0x21/0x150 [ 27.557180][ C0] ret_from_fork+0x474/0x6b0 [ 27.557331][ C0] ret_from_fork_asm+0x11/0x20 [ 27.557536][ C0] [ 27.557627][ C0] -> (console_owner){....}-{0:0} { [ 27.557780][ C0] INITIAL USE at: [ 27.557949][ C0] } [ 27.558024][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.558303][ C0] ... acquired at: [ 27.558415][ C0] __lock_acquire+0x518/0xc20 [ 27.558631][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.558780][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.559025][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.559179][ C0] console_flush_one_record+0x46f/0x710 [ 27.559383][ C0] console_unlock+0xee/0x1f0 [ 27.559533][ C0] vprintk_emit+0x37c/0x3e0 [ 27.559748][ C0] _printk+0xc7/0x100 [ 27.559862][ C0] br_set_state+0x22f/0x430 [bridge] [ 27.560074][ C0] br_init_port+0xc4/0x200 [bridge] [ 27.560229][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 27.560477][ C0] br_port_carrier_check+0x220/0x430 [bridge] [ 27.560681][ C0] br_device_event+0x52d/0x8f0 [bridge] [ 27.560888][ C0] notifier_call_chain+0xae/0x300 [ 27.561041][ C0] netif_state_change+0x139/0x340 [ 27.561246][ C0] __linkwatch_run_queue+0x34c/0x750 [ 27.561397][ C0] linkwatch_event+0x7f/0xb0 [ 27.561611][ C0] process_one_work+0xe3e/0x1560 [ 27.561764][ C0] worker_thread+0x4f1/0xd60 [ 27.561967][ C0] kthread+0x367/0x460 [ 27.562080][ C0] ret_from_fork+0x474/0x6b0 [ 27.562284][ C0] ret_from_fork_asm+0x11/0x20 [ 27.562433][ C0] [ 27.562509][ C0] -> (&br->lock){+.-.}-{3:3} { [ 27.562725][ C0] HARDIRQ-ON-W at: [ 27.562838][ C0] __lock_acquire+0x388/0xc20 [ 27.563081][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.563327][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.563517][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.563814][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.564096][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.564292][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.564536][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.564789][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.564976][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.565221][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.565411][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.565667][ C0] netlink_unicast+0x486/0x750 [ 27.565909][ C0] netlink_sendmsg+0x735/0xc60 [ 27.566102][ C0] ____sys_sendmsg+0x415/0x880 [ 27.566348][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.566594][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.566789][ C0] do_syscall_64+0xff/0x530 [ 27.567035][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.567317][ C0] IN-SOFTIRQ-W at: [ 27.567430][ C0] __lock_acquire+0x388/0xc20 [ 27.567686][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.567873][ C0] _raw_spin_lock+0x33/0x40 [ 27.568116][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.568406][ C0] call_timer_fn+0x160/0x4d0 [ 27.568608][ C0] __run_timers+0x68f/0xaa0 [ 27.568798][ C0] run_timer_softirq+0xf0/0x160 [ 27.568989][ C0] handle_softirqs+0x1d3/0x900 [ 27.569180][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.569422][ C0] irq_exit_rcu+0xe/0x30 [ 27.569619][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.569846][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.570072][ C0] pv_native_safe_halt+0xf/0x10 [ 27.570261][ C0] default_idle+0x9/0x10 [ 27.570504][ C0] default_idle_call+0x6e/0xb0 [ 27.570759][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.570991][ C0] do_idle+0xd8/0x190 [ 27.571199][ C0] cpu_startup_entry+0x53/0x70 [ 27.571440][ C0] rest_init+0x279/0x280 [ 27.571641][ C0] start_kernel+0x3af/0x3b0 [ 27.571887][ C0] x86_64_start_reservations+0x24/0x30 [ 27.572078][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.572322][ C0] common_startup_64+0x13e/0x148 [ 27.572570][ C0] INITIAL USE at: [ 27.572694][ C0] __lock_acquire+0x388/0xc20 [ 27.572939][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.573132][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.573376][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.573675][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.573911][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.574162][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.574410][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.574613][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.574859][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.575103][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.575294][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.575540][ C0] netlink_unicast+0x486/0x750 [ 27.575744][ C0] netlink_sendmsg+0x735/0xc60 [ 27.575988][ C0] ____sys_sendmsg+0x415/0x880 [ 27.576233][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.576422][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.576680][ C0] do_syscall_64+0xff/0x530 [ 27.576925][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.577156][ C0] } [ 27.577288][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.577519][ C0] ... acquired at: [ 27.577697][ C0] mark_lock+0x1d7/0xa00 [ 27.577851][ C0] mark_usage+0x42/0x170 [ 27.578057][ C0] __lock_acquire+0x388/0xc20 [ 27.578209][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.578417][ C0] _raw_spin_lock+0x33/0x40 [ 27.578571][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.578779][ C0] call_timer_fn+0x160/0x4d0 [ 27.578935][ C0] __run_timers+0x68f/0xaa0 [ 27.579087][ C0] run_timer_softirq+0xf0/0x160 [ 27.579238][ C0] handle_softirqs+0x1d3/0x900 [ 27.579445][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.579609][ C0] irq_exit_rcu+0xe/0x30 [ 27.579817][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.580009][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.580255][ C0] pv_native_safe_halt+0xf/0x10 [ 27.580463][ C0] default_idle+0x9/0x10 [ 27.580628][ C0] default_idle_call+0x6e/0xb0 [ 27.580834][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.581024][ C0] do_idle+0xd8/0x190 [ 27.581196][ C0] cpu_startup_entry+0x53/0x70 [ 27.581350][ C0] rest_init+0x279/0x280 [ 27.581555][ C0] start_kernel+0x3af/0x3b0 [ 27.581718][ C0] x86_64_start_reservations+0x24/0x30 [ 27.581925][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.582077][ C0] common_startup_64+0x13e/0x148 [ 27.582281][ C0] [ 27.582357][ C0] [ 27.582357][ C0] stack backtrace: [ 27.582547][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.582551][ C0] Tainted: [W]=WARN [ 27.582552][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.582553][ C0] Call Trace: [ 27.582554][ C0] [ 27.582554][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.582558][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.582561][ C0] mark_lock_irq+0x989/0x9c0 [ 27.582564][ C0] mark_lock+0x1d7/0xa00 [ 27.582566][ C0] mark_usage+0x42/0x170 [ 27.582567][ C0] __lock_acquire+0x388/0xc20 [ 27.582570][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.582577][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.582584][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.582587][ C0] ? lock_acquire+0x13c/0x160 [ 27.582589][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.582596][ C0] _raw_spin_lock+0x33/0x40 [ 27.582599][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.582609][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.582615][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.582622][ C0] call_timer_fn+0x160/0x4d0 [ 27.582624][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.582626][ C0] ? debug_object_active_state+0x430/0x430 [ 27.582629][ C0] ? find_held_lock+0x2b/0x80 [ 27.582631][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.582633][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.582635][ C0] __run_timers+0x68f/0xaa0 [ 27.582637][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.582644][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.582646][ C0] ? __lock_acquire+0x518/0xc20 [ 27.582649][ C0] ? __rwlock_init+0x150/0x150 [ 27.582651][ C0] run_timer_softirq+0xf0/0x160 [ 27.582653][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.582655][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.582657][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.582659][ C0] handle_softirqs+0x1d3/0x900 [ 27.582661][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.582662][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.582664][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.582666][ C0] irq_exit_rcu+0xe/0x30 [ 27.582667][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.582669][ C0] [ 27.582670][ C0] [ 27.582670][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.582672][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.582674][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.582676][ C0] Code: 48 8b 3d 94 f2 f5 01 e8 1f 00 00 00 48 2b 05 58 b3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.582677][ C0] RSP: 0018:ffffffffb0a07cf8 EFLAGS: 00000296 [ 27.582678][ C0] RAX: 0000000000096c5d RBX: ffffffffb0a1c600 RCX: ffffffffadaf0307 [ 27.582680][ C0] RDX: ffffffffb0a1c600 RSI: ffffffffb0838b1c RDI: ffffffffb028d8e0 [ 27.582680][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.582681][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6140fa2 [ 27.582682][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.582683][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.582686][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.582687][ C0] default_idle+0x9/0x10 [ 27.582689][ C0] default_idle_call+0x6e/0xb0 [ 27.582691][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.582692][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.582694][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.582696][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.582697][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.582699][ C0] do_idle+0xd8/0x190 [ 27.582701][ C0] cpu_startup_entry+0x53/0x70 [ 27.582702][ C0] rest_init+0x279/0x280 [ 27.582704][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.582707][ C0] ? rest_init+0x280/0x280 [ 27.582709][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.582712][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.582715][ C0] ? acpi_enable+0x1e4/0x330 [ 27.582717][ C0] start_kernel+0x3af/0x3b0 [ 27.582719][ C0] x86_64_start_reservations+0x24/0x30 [ 27.582720][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.582722][ C0] common_startup_64+0x13e/0x148 [ 27.582725][ C0] [ 58.206252][ T533] Mirror/redirect action on [ 58.206266][ T533] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 58.206268][ T533] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 533, name: modprobe [ 58.206270][ T533] preempt_count: 1, expected: 0 [ 58.206271][ T533] RCU nest depth: 0, expected: 0 [ 58.206272][ T533] INFO: lockdep is turned off. [ 58.206273][ T533] irq event stamp: 0 [ 58.206274][ T533] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 58.206278][ T533] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 58.206284][ T533] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 58.206286][ T533] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 58.206288][ T533] Preemption disabled at: [ 58.206288][ T533] [] vprintk_emit+0x31b/0x3e0 [ 58.206295][ T533] CPU: 0 UID: 0 PID: 533 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 58.206299][ T533] Tainted: [W]=WARN [ 58.206300][ T533] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 58.206302][ T533] Call Trace: [ 58.206304][ T533] [ 58.206305][ T533] dump_stack_lvl+0x6f/0xa0 [ 58.206310][ T533] ? vprintk_emit+0x31b/0x3e0 [ 58.206312][ T533] __might_resched.cold+0x1fe/0x2c1 [ 58.206316][ T533] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 58.206318][ T533] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 58.206321][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206324][ T533] __kmalloc_noprof+0x443/0x760 [ 58.206328][ T533] ? __rwlock_init+0x150/0x150 [ 58.206330][ T533] ? alloc_buf.isra.0+0x4b/0x260 [ 58.206334][ T533] ? do_raw_spin_unlock+0x59/0x250 [ 58.206336][ T533] alloc_buf.isra.0+0x4b/0x260 [ 58.206338][ T533] put_chars+0x1e1/0x2f0 [ 58.206340][ T533] ? __send_to_port+0x420/0x420 [ 58.206342][ T533] ? console_prepend_replay+0x20/0x20 [ 58.206344][ T533] hvc_console_print+0x292/0x780 [ 58.206348][ T533] ? hvc_write+0x3a0/0x3a0 [ 58.206349][ T533] ? vsnprintf+0x668/0x1700 [ 58.206352][ T533] ? __rwlock_init+0x150/0x150 [ 58.206354][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206355][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206358][ T533] console_emit_next_record+0x22f/0x4c0 [ 58.206360][ T533] ? devkmsg_read+0x4b0/0x4b0 [ 58.206362][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206364][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206365][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206367][ T533] ? lock_acquire+0x13c/0x160 [ 58.206369][ T533] ? console_flush_one_record+0x111/0x710 [ 58.206371][ T533] console_flush_one_record+0x46f/0x710 [ 58.206373][ T533] ? console_emit_next_record+0x4c0/0x4c0 [ 58.206376][ T533] console_unlock+0xee/0x1f0 [ 58.206378][ T533] ? lock_acquire+0x13c/0x160 [ 58.206379][ T533] ? console_flush_one_record+0x710/0x710 [ 58.206381][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206382][ T533] ? lock_release+0x184/0x1f0 [ 58.206384][ T533] ? lock_acquire+0x60/0x160 [ 58.206385][ T533] ? __rcu_read_lock+0x30/0x70 [ 58.206387][ T533] ? __down_trylock_console_sem+0x5e/0xa0 [ 58.206389][ T533] ? vprintk_emit+0x320/0x3e0 [ 58.206391][ T533] vprintk_emit+0x37c/0x3e0 [ 58.206394][ T533] ? wake_up_klogd_work_func+0x90/0x90 [ 58.206396][ T533] ? netdev_run_todo+0x2a8/0xfd0 [ 58.206400][ T533] ? mirred_device_event+0x3d/0x1d0 [act_mirred] [ 58.206402][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206405][ T533] ? llc_exit+0x3e0/0x3e0 [llc] [ 58.206407][ T533] _printk+0xc7/0x100 [ 58.206410][ T533] ? snapshot_read.cold+0x21/0x21 [ 58.206412][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206414][ T533] ? llc_exit+0x3e0/0x3e0 [llc] [ 58.206415][ T533] ? __up_write+0x23d/0x5f0 [ 58.206417][ T533] ? __up_write+0x2e5/0x5f0 [ 58.206418][ T533] ? lock_release+0x184/0x1f0 [ 58.206420][ T533] ? __up_write+0x2c7/0x5f0 [ 58.206421][ T533] mirred_init_module+0x28/0x1000 [act_mirred] [ 58.206424][ T533] do_one_initcall+0x124/0x4f0 [ 58.206426][ T533] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 58.206428][ T533] ? do_init_module+0xae/0x8a0 [ 58.206432][ T533] ? kasan_unpoison+0x44/0x70 [ 58.206434][ T533] do_init_module+0x2ad/0x8a0 [ 58.206437][ T533] ? free_module+0x470/0x470 [ 58.206439][ T533] ? kfree+0x123/0x590 [ 58.206441][ T533] load_module+0x1424/0x1bb0 [ 58.206444][ T533] ? layout_and_allocate.isra.0+0x680/0x680 [ 58.206447][ T533] ? __ia32_sys_fsconfig+0x180/0x180 [ 58.206449][ T533] ? ovl_real_file+0x172/0x1e0 [ 58.206453][ T533] ? backing_file_read_iter+0x541/0x790 [ 58.206456][ T533] init_module_from_file+0x11f/0x1a0 [ 58.206458][ T533] ? __do_sys_init_module+0x220/0x220 [ 58.206460][ T533] ? ovl_mmap+0x270/0x270 [ 58.206463][ T533] ? __rwlock_init+0x150/0x150 [ 58.206464][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206466][ T533] ? lock_release+0x184/0x1f0 [ 58.206468][ T533] ? do_raw_spin_unlock+0x59/0x250 [ 58.206469][ T533] idempotent_init_module+0x37b/0x7c0 [ 58.206472][ T533] ? init_module_from_file+0x1a0/0x1a0 [ 58.206474][ T533] ? rcu_is_watching+0x16/0xd0 [ 58.206476][ T533] ? cap_capable+0x1d7/0x3d0 [ 58.206480][ T533] __x64_sys_finit_module+0xdf/0x190 [ 58.206482][ T533] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 58.206485][ T533] do_syscall_64+0xff/0x530 [ 58.206487][ T533] ? exc_page_fault+0xee/0x100 [ 58.206489][ T533] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 58.206491][ T533] RIP: 0033:0x7f4be513637d [ 58.206493][ 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 [ 58.206495][ T533] RSP: 002b:00007ffd4ec64018 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 58.206498][ T533] RAX: ffffffffffffffda RBX: 000055fa0aff8810 RCX: 00007f4be513637d [ 58.206502][ T533] RDX: 0000000000000000 RSI: 000055f9dc70dffd RDI: 0000000000000000 [ 58.206503][ T533] RBP: 00007ffd4ec640b0 R08: 0000000000000000 R09: 0000000000000000 [ 58.206504][ T533] R10: 0000000000000000 R11: 0000000000000246 R12: 000055f9dc70dffd [ 58.206504][ T533] R13: 000055fa0aff8960 R14: 0000000000040000 R15: 0000000000000000 [ 58.206507][ T533] [ 64.580177][ T574] br0: port 2(veth2) entered disabled state [ 64.580196][ T574] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 64.580199][ T574] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 574, name: ip [ 64.580202][ T574] preempt_count: 202, expected: 0 [ 64.580203][ T574] RCU nest depth: 0, expected: 0 [ 64.580205][ T574] INFO: lockdep is turned off. [ 64.580206][ T574] irq event stamp: 0 [ 64.580208][ T574] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 64.580213][ T574] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 64.580221][ T574] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 64.580223][ T574] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 64.580226][ T574] Preemption disabled at: [ 64.580227][ T574] [<0000000000000000>] 0x0 [ 64.580234][ T574] CPU: 3 UID: 0 PID: 574 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 64.580238][ T574] Tainted: [W]=WARN [ 64.580240][ T574] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 64.580242][ T574] Call Trace: [ 64.580244][ T574] [ 64.580246][ T574] dump_stack_lvl+0x6f/0xa0 [ 64.580253][ T574] __might_resched.cold+0x1fe/0x2c1 [ 64.580258][ T574] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 64.580262][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580267][ T574] __kmalloc_noprof+0x443/0x760 [ 64.580272][ T574] ? __rwlock_init+0x150/0x150 [ 64.580275][ T574] ? alloc_buf.isra.0+0x4b/0x260 [ 64.580280][ T574] ? do_raw_spin_unlock+0x59/0x250 [ 64.580282][ T574] alloc_buf.isra.0+0x4b/0x260 [ 64.580285][ T574] put_chars+0x1e1/0x2f0 [ 64.580288][ T574] ? __send_to_port+0x420/0x420 [ 64.580291][ T574] ? printk_get_next_message+0x240/0x650 [ 64.580296][ T574] hvc_console_print+0x292/0x780 [ 64.580301][ T574] ? hvc_write+0x3a0/0x3a0 [ 64.580304][ T574] ? __rwlock_init+0x150/0x150 [ 64.580306][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580309][ T574] ? lock_acquire+0x13c/0x160 [ 64.580313][ T574] console_emit_next_record+0x22f/0x4c0 [ 64.580316][ T574] ? devkmsg_read+0x4b0/0x4b0 [ 64.580319][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580321][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580324][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580326][ T574] ? lock_acquire+0x13c/0x160 [ 64.580329][ T574] ? console_flush_one_record+0x111/0x710 [ 64.580332][ T574] console_flush_one_record+0x46f/0x710 [ 64.580335][ T574] ? console_emit_next_record+0x4c0/0x4c0 [ 64.580339][ T574] console_unlock+0xee/0x1f0 [ 64.580341][ T574] ? lock_acquire+0x13c/0x160 [ 64.580343][ T574] ? console_flush_one_record+0x710/0x710 [ 64.580346][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580348][ T574] ? lock_release+0x184/0x1f0 [ 64.580350][ T574] ? lock_acquire+0x60/0x160 [ 64.580352][ T574] ? __rcu_read_lock+0x30/0x70 [ 64.580355][ T574] ? __down_trylock_console_sem+0x5e/0xa0 [ 64.580358][ T574] ? vprintk_emit+0x320/0x3e0 [ 64.580361][ T574] vprintk_emit+0x37c/0x3e0 [ 64.580365][ T574] ? wake_up_klogd_work_func+0x90/0x90 [ 64.580367][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580370][ T574] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 64.580373][ T574] ? trace_hardirqs_on+0x36/0x40 [ 64.580376][ T574] _printk+0xc7/0x100 [ 64.580380][ T574] ? snapshot_read.cold+0x21/0x21 [ 64.580383][ T574] ? ipv6_mc_down+0x17e/0x2d0 [ 64.580390][ T574] br_set_state+0x22f/0x430 [bridge] [ 64.580407][ T574] br_stp_disable_port+0x11f/0x390 [bridge] [ 64.580423][ T574] br_device_event+0x7b5/0x8f0 [bridge] [ 64.580437][ T574] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 64.580441][ T574] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 64.580445][ T574] ? addrconf_notify+0x2c8/0xf30 [ 64.580450][ T574] notifier_call_chain+0xae/0x300 [ 64.580455][ T574] __dev_notify_flags+0x23c/0x3c0 [ 64.580459][ T574] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 64.580462][ T574] ? netif_change_name+0x8c0/0x8c0 [ 64.580465][ T574] ? netif_set_allmulti+0x250/0x250 [ 64.580468][ T574] ? __kernel_text_address+0x12/0x30 [ 64.580471][ T574] ? write_profile+0xf0/0xf0 [ 64.580474][ T574] ? unwind_get_return_address+0x67/0xd0 [ 64.580478][ T574] netif_change_flags+0xfe/0x190 [ 64.580482][ T574] do_setlink.isra.0+0x1cc3/0x2750 [ 64.580486][ T574] ? stack_trace_save+0x93/0xc0 [ 64.580488][ T574] ? rtnl_link_get_size+0x350/0x350 [ 64.580490][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580492][ T574] ? stack_depot_save_flags+0x1bc/0x790 [ 64.580498][ T574] ? kasan_save_stack+0x3d/0x50 [ 64.580501][ T574] ? kasan_save_stack+0x2f/0x50 [ 64.580503][ T574] ? kasan_save_track+0x14/0x30 [ 64.580506][ T574] ? __kasan_kmalloc+0x7b/0x90 [ 64.580508][ T574] ? __kmalloc_cache_noprof+0x29a/0x620 [ 64.580510][ T574] ? rtnl_newlink+0x124/0x11c0 [ 64.580512][ T574] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 64.580515][ T574] ? netlink_rcv_skb+0x14e/0x3a0 [ 64.580518][ T574] ? netlink_unicast+0x486/0x750 [ 64.580520][ T574] ? netlink_sendmsg+0x735/0xc60 [ 64.580522][ T574] ? ____sys_sendmsg+0x415/0x880 [ 64.580526][ T574] ? ___sys_sendmsg+0x14e/0x1d0 [ 64.580528][ T574] ? __sys_sendmsg+0x12c/0x1d0 [ 64.580530][ T574] ? do_syscall_64+0xff/0x530 [ 64.580533][ T574] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 64.580537][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580539][ T574] ? lock_acquire+0x13c/0x160 [ 64.580542][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580544][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580547][ T574] ? trace_contention_end+0xb3/0x180 [ 64.580551][ T574] ? __mutex_lock+0x1db/0x1ea0 [ 64.580553][ T574] ? __mutex_lock+0x9a3/0x1ea0 [ 64.580555][ T574] ? rtnl_newlink+0x9a8/0x11c0 [ 64.580558][ T574] ? ww_mutex_lock+0x160/0x160 [ 64.580561][ T574] ? __rtnl_newlink+0x3fa/0xa50 [ 64.580565][ T574] rtnl_newlink+0x9f1/0x11c0 [ 64.580568][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580570][ T574] ? rtnl_bridge_getlink+0x850/0x850 [ 64.580572][ T574] ? lock_acquire+0x13c/0x160 [ 64.580574][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580577][ T574] ? is_bpf_text_address+0x3e/0x110 [ 64.580580][ T574] ? is_bpf_text_address+0x72/0x110 [ 64.580583][ T574] ? kernel_text_address+0x149/0x170 [ 64.580585][ T574] ? __kernel_text_address+0x12/0x30 [ 64.580586][ T574] ? write_profile+0xf0/0xf0 [ 64.580588][ T574] ? unwind_get_return_address+0x67/0xd0 [ 64.580590][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580592][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580594][ T574] ? cap_capable+0x1d7/0x3d0 [ 64.580598][ T574] ? lock_acquire+0x13c/0x160 [ 64.580600][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580603][ T574] ? rtnl_bridge_getlink+0x850/0x850 [ 64.580605][ T574] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 64.580607][ T574] ? rtnl_link_fill+0x920/0x920 [ 64.580609][ T574] ? filter_irq_stacks+0xd0/0xd0 [ 64.580611][ T574] ? stack_depot_save_flags+0x1bc/0x790 [ 64.580615][ T574] netlink_rcv_skb+0x14e/0x3a0 [ 64.580617][ T574] ? rtnl_link_fill+0x920/0x920 [ 64.580619][ T574] ? lock_release+0x184/0x1f0 [ 64.580621][ T574] ? netlink_ack+0xcf0/0xcf0 [ 64.580623][ T574] ? __netlink_lookup+0xb9/0x6b0 [ 64.580627][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580629][ T574] ? lock_release+0x184/0x1f0 [ 64.580632][ T574] netlink_unicast+0x486/0x750 [ 64.580635][ T574] ? netlink_attachskb+0x810/0x810 [ 64.580637][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580639][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580642][ T574] ? lock_acquire+0x13c/0x160 [ 64.580644][ T574] netlink_sendmsg+0x735/0xc60 [ 64.580647][ T574] ? netlink_unicast+0x750/0x750 [ 64.580650][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580652][ T574] ? lock_release+0x184/0x1f0 [ 64.580655][ T574] ____sys_sendmsg+0x415/0x880 [ 64.580657][ T574] ? copy_msghdr_from_user+0x279/0x420 [ 64.580659][ T574] ? get_timestamp.constprop.0+0x390/0x390 [ 64.580661][ T574] ? move_addr_to_kernel+0x40/0x40 [ 64.580664][ T574] ? filter_irq_stacks+0xd0/0xd0 [ 64.580668][ T574] ___sys_sendmsg+0x14e/0x1d0 [ 64.580670][ T574] ? copy_msghdr_from_user+0x420/0x420 [ 64.580678][ T574] __sys_sendmsg+0x12c/0x1d0 [ 64.580680][ T574] ? __sys_sendmsg_sock+0x20/0x20 [ 64.580688][ T574] ? rcu_is_watching+0x16/0xd0 [ 64.580691][ T574] do_syscall_64+0xff/0x530 [ 64.580693][ T574] ? exc_page_fault+0xee/0x100 [ 64.580696][ T574] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 64.580698][ T574] RIP: 0033:0x7f9e121a754e [ 64.580701][ 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 [ 64.580703][ T574] RSP: 002b:00007ffe5312be20 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 64.580708][ T574] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f9e121a754e [ 64.580710][ T574] RDX: 0000000000000000 RSI: 00007ffe5312bed0 RDI: 0000000000000005 [ 64.580711][ T574] RBP: 00007ffe5312be30 R08: 0000000000000000 R09: 0000000000000000 [ 64.580713][ T574] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a90e35d [ 64.580714][ T574] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe5312c590 [ 64.580718][ T574] [ 64.625478][ T574] ip (574) used greatest stack depth: 23016 bytes left [ 64.641502][ T575] br0: port 1(veth1) entered disabled state [ 64.656061][ T576] veth2: left allmulticast mode [ 64.656246][ T576] veth2: left promiscuous mode [ 64.656491][ T576] br0: port 2(veth2) entered disabled state [ 64.661132][ T576] veth1: left allmulticast mode [ 64.661308][ T576] veth1: left promiscuous mode [ 64.661547][ T576] br0: port 1(veth1) entered disabled state