[ 9.908731][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 9.908844][ C0] [ 9.908846][ C0] ======================================================== [ 9.908847][ C0] WARNING: possible irq lock inversion dependency detected [ 9.908849][ C0] 7.2.0-virtme #1 Tainted: G W [ 9.908851][ C0] -------------------------------------------------------- [ 9.908851][ C0] (udev-worker)/203 just changed the state of lock: [ 9.908852][ C0] ffffffff85c89660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.908866][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 9.908867][ C0] (fs_reclaim){+.+.}-{0:0} [ 9.908869][ C0] [ 9.908869][ C0] [ 9.908869][ C0] and interrupts could create inverse lock ordering between them. [ 9.908869][ C0] [ 9.908869][ C0] [ 9.908869][ C0] other info that might help us debug this: [ 9.908870][ C0] Possible interrupt unsafe locking scenario: [ 9.908870][ C0] [ 9.908871][ C0] CPU0 CPU1 [ 9.908871][ C0] ---- ---- [ 9.908872][ C0] lock(fs_reclaim); [ 9.908873][ C0] local_irq_disable(); [ 9.908874][ C0] lock(console_owner); [ 9.908875][ C0] lock(fs_reclaim); [ 9.908875][ C0] [ 9.908876][ C0] lock(console_owner); [ 9.908877][ C0] [ 9.908877][ C0] *** DEADLOCK *** [ 9.908877][ C0] [ 9.908877][ C0] locks held by (udev-worker)/203: 4, last CPU#0: [ 9.908879][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 9.908885][ C0] #1: ffffffff85dce8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 9.908889][ C0] #2: ffffffff85d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.908892][ C0] #3: ffffffff85d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.908896][ C0] [ 9.908896][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 9.908900][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 9.908902][ C0] HARDIRQ-ON-W at: [ 9.908904][ C0] __lock_acquire+0x388/0xc20 [ 9.908908][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.908910][ C0] fs_reclaim_acquire+0xd5/0x120 [ 9.908914][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 9.908916][ C0] kthread_create_worker_on_node+0xea/0x210 [ 9.908919][ C0] workqueue_init+0x2a/0x680 [ 9.908923][ C0] kernel_init_freeable+0x2fe/0x630 [ 9.908925][ C0] kernel_init+0x21/0x150 [ 9.908929][ C0] ret_from_fork+0x474/0x6b0 [ 9.908932][ C0] ret_from_fork_asm+0x11/0x20 [ 9.908935][ C0] SOFTIRQ-ON-W at: [ 9.908936][ C0] __lock_acquire+0x388/0xc20 [ 9.908938][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.908939][ C0] fs_reclaim_acquire+0xd5/0x120 [ 9.908940][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 9.908942][ C0] kthread_create_worker_on_node+0xea/0x210 [ 9.908943][ C0] workqueue_init+0x2a/0x680 [ 9.908944][ C0] kernel_init_freeable+0x2fe/0x630 [ 9.908945][ C0] kernel_init+0x21/0x150 [ 9.908947][ C0] ret_from_fork+0x474/0x6b0 [ 9.908948][ C0] ret_from_fork_asm+0x11/0x20 [ 9.908950][ C0] INITIAL USE at: [ 9.908950][ C0] __lock_acquire+0x388/0xc20 [ 9.908952][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.908953][ C0] fs_reclaim_acquire+0xd5/0x120 [ 9.908954][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 9.908956][ C0] kthread_create_worker_on_node+0xea/0x210 [ 9.908957][ C0] workqueue_init+0x2a/0x680 [ 9.908958][ C0] kernel_init_freeable+0x2fe/0x630 [ 9.908959][ C0] kernel_init+0x21/0x150 [ 9.908961][ C0] ret_from_fork+0x474/0x6b0 [ 9.908962][ C0] ret_from_fork_asm+0x11/0x20 [ 9.908964][ C0] } [ 9.908964][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 9.908968][ C0] ... acquired at: [ 9.908969][ C0] __lock_acquire+0x518/0xc20 [ 9.908971][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.908972][ C0] fs_reclaim_acquire+0xd5/0x120 [ 9.908973][ C0] __kmalloc_noprof+0xd3/0x760 [ 9.908974][ C0] alloc_buf.isra.0+0x4b/0x260 [ 9.908977][ C0] put_chars+0x1e1/0x2f0 [ 9.908979][ C0] hvc_console_print+0x292/0x780 [ 9.908981][ C0] console_emit_next_record+0x22f/0x4c0 [ 9.908982][ C0] console_flush_one_record+0x46f/0x710 [ 9.908984][ C0] console_unlock+0xee/0x1f0 [ 9.908986][ C0] vprintk_emit+0x37c/0x3e0 [ 9.908987][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 9.908991][ C0] dev_printk_emit+0xb9/0xee [ 9.908992][ C0] _dev_info+0xe2/0x116 [ 9.908994][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 9.908997][ C0] cmos_do_probe+0x73b/0x98a [ 9.908998][ C0] platform_probe+0xfe/0x1f0 [ 9.909002][ C0] call_driver_probe+0x61/0x1c0 [ 9.909003][ C0] really_probe+0x199/0x760 [ 9.909005][ C0] __driver_probe_device+0x24f/0x440 [ 9.909007][ C0] driver_probe_device+0x4a/0xf0 [ 9.909008][ C0] __driver_attach+0x1b8/0x540 [ 9.909010][ C0] bus_for_each_dev+0x130/0x1e0 [ 9.909011][ C0] bus_add_driver+0x2c8/0x530 [ 9.909012][ C0] driver_register+0x1a3/0x390 [ 9.909014][ C0] __platform_driver_probe+0x13f/0x270 [ 9.909016][ C0] cmos_init+0x31/0x40 [ 9.909019][ C0] do_one_initcall+0x124/0x4f0 [ 9.909020][ C0] kernel_init_freeable+0x596/0x630 [ 9.909021][ C0] kernel_init+0x21/0x150 [ 9.909023][ C0] ret_from_fork+0x474/0x6b0 [ 9.909024][ C0] ret_from_fork_asm+0x11/0x20 [ 9.909025][ C0] [ 9.909026][ C0] -> (console_owner){..-.}-{0:0} { [ 9.909027][ C0] IN-SOFTIRQ-W at: [ 9.909028][ C0] __lock_acquire+0x388/0xc20 [ 9.909030][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.909031][ C0] console_lock_spinning_enable+0x5c/0x60 [ 9.909033][ C0] console_emit_next_record+0x1d1/0x4c0 [ 9.909035][ C0] console_flush_one_record+0x46f/0x710 [ 9.909036][ C0] console_unlock+0xee/0x1f0 [ 9.909038][ C0] vprintk_emit+0x37c/0x3e0 [ 9.909040][ C0] _printk+0xc7/0x100 [ 9.909043][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 9.909045][ C0] call_timer_fn+0x160/0x4d0 [ 9.909047][ C0] __run_timers+0x68f/0xaa0 [ 9.909048][ C0] run_timer_softirq+0xf0/0x160 [ 9.909050][ C0] handle_softirqs+0x1d3/0x900 [ 9.909053][ C0] __irq_exit_rcu+0x145/0x1c0 [ 9.909054][ C0] irq_exit_rcu+0xe/0x30 [ 9.909055][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 9.909057][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 9.909059][ C0] _raw_spin_unlock_irqrestore+0x36/0x80 [ 9.909062][ C0] get_from_partial_node+0x21d/0x500 [ 9.909063][ C0] ___slab_alloc+0x71/0x2f0 [ 9.909065][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 9.909066][ C0] __alloc_object+0x30/0x260 [ 9.909070][ C0] __create_object+0x30/0x110 [ 9.909072][ C0] __kmalloc_cache_noprof+0x4a9/0x620 [ 9.909075][ C0] kmem_cache_free+0x365/0x550 [ 9.909077][ C0] fput_close_sync+0xde/0x1b0 [ 9.909079][ C0] __x64_sys_close+0x8b/0xf0 [ 9.909081][ C0] do_syscall_64+0xff/0x530 [ 9.909082][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.909084][ C0] INITIAL USE at: [ 9.909085][ C0] } [ 9.909085][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 9.909089][ C0] ... acquired at: [ 9.909089][ C0] mark_lock+0x1d7/0xa00 [ 9.909091][ C0] mark_usage+0x42/0x170 [ 9.909092][ C0] __lock_acquire+0x388/0xc20 [ 9.909093][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.909095][ C0] console_lock_spinning_enable+0x5c/0x60 [ 9.909096][ C0] console_emit_next_record+0x1d1/0x4c0 [ 9.909098][ C0] console_flush_one_record+0x46f/0x710 [ 9.909099][ C0] console_unlock+0xee/0x1f0 [ 9.909101][ C0] vprintk_emit+0x37c/0x3e0 [ 9.909103][ C0] _printk+0xc7/0x100 [ 9.909104][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 9.909105][ C0] call_timer_fn+0x160/0x4d0 [ 9.909115][ C0] __run_timers+0x68f/0xaa0 [ 9.909116][ C0] run_timer_softirq+0xf0/0x160 [ 9.909118][ C0] handle_softirqs+0x1d3/0x900 [ 9.909119][ C0] __irq_exit_rcu+0x145/0x1c0 [ 9.909120][ C0] irq_exit_rcu+0xe/0x30 [ 9.909122][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 9.909123][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 9.909124][ C0] _raw_spin_unlock_irqrestore+0x36/0x80 [ 9.909126][ C0] get_from_partial_node+0x21d/0x500 [ 9.909127][ C0] ___slab_alloc+0x71/0x2f0 [ 9.909128][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 9.909130][ C0] __alloc_object+0x30/0x260 [ 9.909131][ C0] __create_object+0x30/0x110 [ 9.909133][ C0] __kmalloc_cache_noprof+0x4a9/0x620 [ 9.909134][ C0] kmem_cache_free+0x365/0x550 [ 9.909135][ C0] fput_close_sync+0xde/0x1b0 [ 9.909136][ C0] __x64_sys_close+0x8b/0xf0 [ 9.909138][ C0] do_syscall_64+0xff/0x530 [ 9.909139][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.909140][ C0] [ 9.909140][ C0] [ 9.909140][ C0] stack backtrace: [ 9.909143][ C0] CPU: 0 UID: 0 PID: 203 Comm: (udev-worker) Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.909147][ C0] Tainted: [W]=WARN [ 9.909147][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.909149][ C0] Call Trace: [ 9.909150][ C0] [ 9.909152][ C0] dump_stack_lvl+0x6f/0xa0 [ 9.909156][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 9.909158][ C0] mark_lock_irq+0x989/0x9c0 [ 9.909160][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 9.909163][ C0] mark_lock+0x1d7/0xa00 [ 9.909165][ C0] mark_usage+0x42/0x170 [ 9.909167][ C0] __lock_acquire+0x388/0xc20 [ 9.909169][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.909171][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 9.909173][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909176][ C0] ? lock_acquire+0x13c/0x160 [ 9.909178][ C0] console_lock_spinning_enable+0x5c/0x60 [ 9.909180][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 9.909182][ C0] console_emit_next_record+0x1d1/0x4c0 [ 9.909184][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 9.909186][ C0] ? console_flush_one_record+0x106/0x710 [ 9.909188][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909190][ C0] ? lock_acquire+0x13c/0x160 [ 9.909192][ C0] console_flush_one_record+0x46f/0x710 [ 9.909194][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 9.909196][ C0] ? __lock_acquire+0x518/0xc20 [ 9.909198][ C0] console_unlock+0xee/0x1f0 [ 9.909200][ C0] ? console_flush_one_record+0x710/0x710 [ 9.909202][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909204][ C0] ? lock_acquire+0x60/0x160 [ 9.909206][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.909208][ C0] ? vprintk_emit+0x320/0x3e0 [ 9.909210][ C0] vprintk_emit+0x37c/0x3e0 [ 9.909212][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 9.909215][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 9.909217][ C0] _printk+0xc7/0x100 [ 9.909219][ C0] ? snapshot_read.cold+0x21/0x21 [ 9.909221][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 9.909223][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.909225][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 9.909227][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 9.909228][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 9.909230][ C0] call_timer_fn+0x160/0x4d0 [ 9.909232][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 9.909234][ C0] ? debug_object_active_state+0x430/0x430 [ 9.909238][ C0] ? find_held_lock+0x2b/0x80 [ 9.909239][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.909241][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909244][ C0] __run_timers+0x68f/0xaa0 [ 9.909245][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 9.909248][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 9.909249][ C0] ? __lock_acquire+0x518/0xc20 [ 9.909252][ C0] ? __rwlock_init+0x150/0x150 [ 9.909255][ C0] run_timer_softirq+0xf0/0x160 [ 9.909257][ C0] ? __run_timers+0xaa0/0xaa0 [ 9.909259][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.909261][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909263][ C0] handle_softirqs+0x1d3/0x900 [ 9.909265][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.909267][ C0] ? _local_bh_enable+0xc0/0xc0 [ 9.909269][ C0] __irq_exit_rcu+0x145/0x1c0 [ 9.909270][ C0] irq_exit_rcu+0xe/0x30 [ 9.909272][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 9.909274][ C0] [ 9.909274][ C0] [ 9.909275][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 9.909277][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 9.909280][ C0] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 c1 bf ba fd 48 89 df e8 c9 15 bb fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 70 df ac fd 65 48 83 3d af 1b f3 01 00 74 12 5b [ 9.909281][ C0] RSP: 0018:ffa00000006d7bf8 EFLAGS: 00000246 [ 9.909284][ C0] RAX: 0000000000000096 RBX: ff11000001041440 RCX: ffffffff84fa7483 [ 9.909285][ C0] RDX: ff11000013a9a380 RSI: ffffffff85838b1c RDI: ffffffff8528d8e0 [ 9.909286][ C0] RBP: 0000000000000246 R08: 0000000000000000 R09: 0000000000000000 [ 9.909287][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff11000001041440 [ 9.909288][ C0] R13: ffd40000004e7590 R14: ffd40000004e7580 R15: ff110000139d6f08 [ 9.909289][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 9.909292][ C0] get_from_partial_node+0x21d/0x500 [ 9.909295][ C0] ___slab_alloc+0x71/0x2f0 [ 9.909297][ C0] ? __pcs_replace_empty_main+0x42f/0x6a0 [ 9.909299][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 9.909300][ C0] ? __alloc_object+0x30/0x260 [ 9.909303][ C0] __alloc_object+0x30/0x260 [ 9.909305][ C0] __create_object+0x30/0x110 [ 9.909307][ C0] __kmalloc_cache_noprof+0x4a9/0x620 [ 9.909309][ C0] ? kmem_cache_free+0x365/0x550 [ 9.909311][ C0] ? fput_close_sync+0xde/0x1b0 [ 9.909312][ C0] kmem_cache_free+0x365/0x550 [ 9.909314][ C0] ? find_held_lock+0x2b/0x80 [ 9.909315][ C0] fput_close_sync+0xde/0x1b0 [ 9.909317][ C0] ? alloc_file_clone+0xe0/0xe0 [ 9.909318][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 9.909321][ C0] __x64_sys_close+0x8b/0xf0 [ 9.909322][ C0] do_syscall_64+0xff/0x530 [ 9.909324][ C0] ? exc_page_fault+0xee/0x100 [ 9.909326][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.909327][ C0] RIP: 0033:0x7fa495f7a54e [ 9.909330][ C0] 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 [ 9.909331][ C0] RSP: 002b:00007fff66764600 EFLAGS: 00000202 ORIG_RAX: 0000000000000003 [ 9.909333][ C0] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa495f7a54e [ 9.909335][ C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000025 [ 9.909336][ C0] RBP: 00007fff66764610 R08: 0000000000000000 R09: 0000000000000000 [ 9.909337][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 000055d4ebebdb31 [ 9.909339][ C0] R13: 0000000000000025 R14: 000055d4ec013d20 R15: 0000000000000024 [ 9.909343][ C0] [ 9.909347][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.909349][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 203, name: (udev-worker) [ 9.909350][ C0] preempt_count: 103, expected: 0 [ 9.909351][ C0] RCU nest depth: 0, expected: 0 [ 9.909352][ C0] INFO: lockdep is turned off. [ 9.909353][ C0] irq event stamp: 15747 [ 9.909353][ C0] hardirqs last enabled at (15746): [] __down_trylock_console_sem+0x86/0xa0 [ 9.909356][ C0] hardirqs last disabled at (15747): [] console_emit_next_record+0x3d4/0x4c0 [ 9.909357][ C0] softirqs last enabled at (12920): [] handle_softirqs+0x67c/0x900 [ 9.909359][ C0] softirqs last disabled at (15733): [] __irq_exit_rcu+0x145/0x1c0 [ 9.909361][ C0] Preemption disabled at: [ 9.909361][ C0] [<0000000000000000>] 0x0 [ 9.909364][ C0] CPU: 0 UID: 0 PID: 203 Comm: (udev-worker) Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.909366][ C0] Tainted: [W]=WARN [ 9.909366][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.909367][ C0] Call Trace: [ 9.909367][ C0] [ 9.909368][ C0] dump_stack_lvl+0x6f/0xa0 [ 9.909370][ C0] __might_resched.cold+0x1fe/0x2c1 [ 9.909373][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.909377][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909379][ C0] __kmalloc_noprof+0x443/0x760 [ 9.909381][ C0] ? __rwlock_init+0x150/0x150 [ 9.909382][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 9.909384][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 9.909386][ C0] alloc_buf.isra.0+0x4b/0x260 [ 9.909388][ C0] put_chars+0x1e1/0x2f0 [ 9.909390][ C0] ? __send_to_port+0x420/0x420 [ 9.909392][ C0] ? mark_lock+0x1d7/0xa00 [ 9.909394][ C0] hvc_console_print+0x292/0x780 [ 9.909396][ C0] ? hvc_write+0x3a0/0x3a0 [ 9.909398][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909400][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909402][ C0] console_emit_next_record+0x22f/0x4c0 [ 9.909404][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 9.909406][ C0] ? console_flush_one_record+0x106/0x710 [ 9.909408][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909410][ C0] ? lock_acquire+0x13c/0x160 [ 9.909412][ C0] console_flush_one_record+0x46f/0x710 [ 9.909414][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 9.909416][ C0] ? __lock_acquire+0x518/0xc20 [ 9.909419][ C0] console_unlock+0xee/0x1f0 [ 9.909421][ C0] ? console_flush_one_record+0x710/0x710 [ 9.909422][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909424][ C0] ? lock_acquire+0x60/0x160 [ 9.909426][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.909428][ C0] ? vprintk_emit+0x320/0x3e0 [ 9.909430][ C0] vprintk_emit+0x37c/0x3e0 [ 9.909432][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 9.909435][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 9.909436][ C0] _printk+0xc7/0x100 [ 9.909438][ C0] ? snapshot_read.cold+0x21/0x21 [ 9.909440][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 9.909442][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.909444][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 9.909446][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 9.909447][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 9.909449][ C0] call_timer_fn+0x160/0x4d0 [ 9.909451][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 9.909453][ C0] ? debug_object_active_state+0x430/0x430 [ 9.909455][ C0] ? find_held_lock+0x2b/0x80 [ 9.909456][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.909458][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909460][ C0] __run_timers+0x68f/0xaa0 [ 9.909462][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 9.909465][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 9.909466][ C0] ? __lock_acquire+0x518/0xc20 [ 9.909469][ C0] ? __rwlock_init+0x150/0x150 [ 9.909471][ C0] run_timer_softirq+0xf0/0x160 [ 9.909473][ C0] ? __run_timers+0xaa0/0xaa0 [ 9.909475][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.909477][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.909478][ C0] handle_softirqs+0x1d3/0x900 [ 9.909480][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.909482][ C0] ? _local_bh_enable+0xc0/0xc0 [ 9.909484][ C0] __irq_exit_rcu+0x145/0x1c0 [ 9.909486][ C0] irq_exit_rcu+0xe/0x30 [ 9.909487][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 9.909489][ C0] [ 9.909489][ C0] [ 9.909490][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 9.909492][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 9.909494][ C0] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 c1 bf ba fd 48 89 df e8 c9 15 bb fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 70 df ac fd 65 48 83 3d af 1b f3 01 00 74 12 5b [ 9.909495][ C0] RSP: 0018:ffa00000006d7bf8 EFLAGS: 00000246 [ 9.909496][ C0] RAX: 0000000000000096 RBX: ff11000001041440 RCX: ffffffff84fa7483 [ 9.909497][ C0] RDX: ff11000013a9a380 RSI: ffffffff85838b1c RDI: ffffffff8528d8e0 [ 9.909499][ C0] RBP: 0000000000000246 R08: 0000000000000000 R09: 0000000000000000 [ 9.909500][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff11000001041440 [ 9.909501][ C0] R13: ffd40000004e7590 R14: ffd40000004e7580 R15: ff110000139d6f08 [ 9.909504][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 9.909507][ C0] get_from_partial_node+0x21d/0x500 [ 9.909510][ C0] ___slab_alloc+0x71/0x2f0 [ 9.909512][ C0] ? __pcs_replace_empty_main+0x42f/0x6a0 [ 9.909514][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 9.909515][ C0] ? __alloc_object+0x30/0x260 [ 9.909518][ C0] __alloc_object+0x30/0x260 [ 9.909520][ C0] __create_object+0x30/0x110 [ 9.909522][ C0] __kmalloc_cache_noprof+0x4a9/0x620 [ 9.909524][ C0] ? kmem_cache_free+0x365/0x550 [ 9.909526][ C0] ? fput_close_sync+0xde/0x1b0 [ 9.909527][ C0] kmem_cache_free+0x365/0x550 [ 9.909529][ C0] ? find_held_lock+0x2b/0x80 [ 9.909530][ C0] fput_close_sync+0xde/0x1b0 [ 9.909532][ C0] ? alloc_file_clone+0xe0/0xe0 [ 9.909533][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 9.909536][ C0] __x64_sys_close+0x8b/0xf0 [ 9.909537][ C0] do_syscall_64+0xff/0x530 [ 9.909539][ C0] ? exc_page_fault+0xee/0x100 [ 9.909541][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.909542][ C0] RIP: 0033:0x7fa495f7a54e [ 9.909543][ C0] 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 [ 9.909544][ C0] RSP: 002b:00007fff66764600 EFLAGS: 00000202 ORIG_RAX: 0000000000000003 [ 9.909545][ C0] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa495f7a54e [ 9.909546][ C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000025 [ 9.909547][ C0] RBP: 00007fff66764610 R08: 0000000000000000 R09: 0000000000000000 [ 9.909547][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 000055d4ebebdb31 [ 9.909548][ C0] R13: 0000000000000025 R14: 000055d4ec013d20 R15: 0000000000000024 [ 9.909551][ C0] [ 10.919443][ T256] br1: port 1(veth1) entered blocking state [ 10.919461][ T256] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.919465][ T256] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 256, name: ip [ 10.919467][ T256] preempt_count: 1, expected: 0 [ 10.919468][ T256] RCU nest depth: 0, expected: 0 [ 10.919470][ T256] INFO: lockdep is turned off. [ 10.919471][ T256] irq event stamp: 0 [ 10.919472][ T256] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 10.919477][ T256] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 10.919483][ T256] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 10.919485][ T256] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 10.919486][ T256] Preemption disabled at: [ 10.919487][ T256] [] vprintk_emit+0x31b/0x3e0 [ 10.919494][ T256] CPU: 2 UID: 0 PID: 256 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.919498][ T256] Tainted: [W]=WARN [ 10.919498][ T256] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.919500][ T256] Call Trace: [ 10.919501][ T256] [ 10.919503][ T256] dump_stack_lvl+0x6f/0xa0 [ 10.919508][ T256] ? vprintk_emit+0x31b/0x3e0 [ 10.919510][ T256] __might_resched.cold+0x1fe/0x2c1 [ 10.919514][ T256] ? __module_address+0x36/0x330 [ 10.919517][ T256] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.919521][ T256] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 10.919522][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919525][ T256] __kmalloc_noprof+0x443/0x760 [ 10.919529][ T256] ? __rwlock_init+0x150/0x150 [ 10.919531][ T256] ? alloc_buf.isra.0+0x4b/0x260 [ 10.919535][ T256] ? do_raw_spin_unlock+0x59/0x250 [ 10.919537][ T256] alloc_buf.isra.0+0x4b/0x260 [ 10.919539][ T256] put_chars+0x1e1/0x2f0 [ 10.919540][ T256] ? prb_final_commit+0x50/0x50 [ 10.919542][ T256] ? __send_to_port+0x420/0x420 [ 10.919543][ T256] ? printk_get_next_message+0x240/0x650 [ 10.919546][ T256] hvc_console_print+0x292/0x780 [ 10.919549][ T256] ? hvc_write+0x3a0/0x3a0 [ 10.919551][ T256] ? do_raw_spin_lock+0x131/0x280 [ 10.919552][ T256] ? vsnprintf+0x668/0x1700 [ 10.919556][ T256] ? __rwlock_init+0x150/0x150 [ 10.919557][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919559][ T256] ? lock_acquire+0x13c/0x160 [ 10.919561][ T256] console_emit_next_record+0x22f/0x4c0 [ 10.919564][ T256] ? devkmsg_read+0x4b0/0x4b0 [ 10.919566][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919568][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919570][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919571][ T256] ? lock_acquire+0x13c/0x160 [ 10.919573][ T256] ? console_flush_one_record+0x111/0x710 [ 10.919575][ T256] console_flush_one_record+0x46f/0x710 [ 10.919577][ T256] ? console_emit_next_record+0x4c0/0x4c0 [ 10.919580][ T256] console_unlock+0xee/0x1f0 [ 10.919582][ T256] ? lock_acquire+0x13c/0x160 [ 10.919583][ T256] ? console_flush_one_record+0x710/0x710 [ 10.919587][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919589][ T256] ? lock_release+0x184/0x1f0 [ 10.919591][ T256] ? lock_acquire+0x60/0x160 [ 10.919592][ T256] ? __rcu_read_lock+0x30/0x70 [ 10.919594][ T256] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.919596][ T256] ? vprintk_emit+0x320/0x3e0 [ 10.919598][ T256] vprintk_emit+0x37c/0x3e0 [ 10.919601][ T256] ? wake_up_klogd_work_func+0x90/0x90 [ 10.919603][ T256] ? __rwlock_init+0x150/0x150 [ 10.919605][ T256] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 10.919608][ T256] ? lock_acquire+0x13c/0x160 [ 10.919609][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919611][ T256] _printk+0xc7/0x100 [ 10.919615][ T256] ? snapshot_read.cold+0x21/0x21 [ 10.919617][ T256] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 10.919630][ T256] ? do_setlink.isra.0+0xa31/0x2750 [ 10.919633][ T256] ? rtnl_newlink+0x9f1/0x11c0 [ 10.919634][ T256] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.919636][ T256] br_set_state+0x22f/0x430 [bridge] [ 10.919645][ T256] br_init_port+0xc4/0x200 [bridge] [ 10.919653][ T256] new_nbp+0x39c/0x580 [bridge] [ 10.919661][ T256] br_add_if+0x212/0x1320 [bridge] [ 10.919668][ T256] ? bpf_address_lookup+0x250/0x290 [ 10.919671][ T256] ? is_bpf_text_address+0x72/0x110 [ 10.919673][ T256] ? kernel_text_address+0x149/0x170 [ 10.919676][ T256] ? __kernel_text_address+0x12/0x30 [ 10.919677][ T256] do_set_master+0x357/0x580 [ 10.919680][ T256] do_setlink.isra.0+0xa31/0x2750 [ 10.919682][ T256] ? stack_trace_save+0x93/0xc0 [ 10.919684][ T256] ? rtnl_link_get_size+0x350/0x350 [ 10.919685][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919687][ T256] ? stack_depot_save_flags+0x1bc/0x790 [ 10.919691][ T256] ? kasan_save_stack+0x3d/0x50 [ 10.919693][ T256] ? kasan_save_stack+0x2f/0x50 [ 10.919695][ T256] ? kasan_save_track+0x14/0x30 [ 10.919696][ T256] ? __kasan_kmalloc+0x7b/0x90 [ 10.919698][ T256] ? __kmalloc_cache_noprof+0x29a/0x620 [ 10.919699][ T256] ? rtnl_newlink+0x124/0x11c0 [ 10.919700][ T256] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.919702][ T256] ? netlink_rcv_skb+0x14e/0x3a0 [ 10.919704][ T256] ? netlink_unicast+0x486/0x750 [ 10.919706][ T256] ? netlink_sendmsg+0x735/0xc60 [ 10.919707][ T256] ? ____sys_sendmsg+0x415/0x880 [ 10.919710][ T256] ? ___sys_sendmsg+0x14e/0x1d0 [ 10.919711][ T256] ? __sys_sendmsg+0x12c/0x1d0 [ 10.919713][ T256] ? do_syscall_64+0xff/0x530 [ 10.919715][ T256] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.919717][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919719][ T256] ? lock_acquire+0x13c/0x160 [ 10.919720][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919722][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919724][ T256] ? trace_contention_end+0xb3/0x180 [ 10.919727][ T256] ? __mutex_lock+0x1db/0x1ea0 [ 10.919728][ T256] ? __mutex_lock+0x9a3/0x1ea0 [ 10.919730][ T256] ? rtnl_newlink+0x9a8/0x11c0 [ 10.919731][ T256] ? ww_mutex_lock+0x160/0x160 [ 10.919733][ T256] ? nla_get_range_signed+0x3d0/0x3d0 [ 10.919735][ T256] ? __rtnl_newlink+0x3fa/0xa50 [ 10.919737][ T256] rtnl_newlink+0x9f1/0x11c0 [ 10.919739][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919741][ T256] ? rtnl_bridge_getlink+0x850/0x850 [ 10.919742][ T256] ? lock_acquire+0x13c/0x160 [ 10.919744][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919746][ T256] ? is_bpf_text_address+0x3e/0x110 [ 10.919748][ T256] ? is_bpf_text_address+0x72/0x110 [ 10.919749][ T256] ? kernel_text_address+0x149/0x170 [ 10.919751][ T256] ? __kernel_text_address+0x12/0x30 [ 10.919752][ T256] ? write_profile+0xf0/0xf0 [ 10.919753][ T256] ? unwind_get_return_address+0x67/0xd0 [ 10.919756][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919758][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919759][ T256] ? cap_capable+0x1d7/0x3d0 [ 10.919763][ T256] ? lock_acquire+0x13c/0x160 [ 10.919765][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919767][ T256] ? rtnl_bridge_getlink+0x850/0x850 [ 10.919768][ T256] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.919770][ T256] ? rtnl_link_fill+0x920/0x920 [ 10.919771][ T256] ? filter_irq_stacks+0xd0/0xd0 [ 10.919773][ T256] ? stack_depot_save_flags+0x1bc/0x790 [ 10.919775][ T256] netlink_rcv_skb+0x14e/0x3a0 [ 10.919777][ T256] ? rtnl_link_fill+0x920/0x920 [ 10.919778][ T256] ? lock_release+0x184/0x1f0 [ 10.919780][ T256] ? netlink_ack+0xcf0/0xcf0 [ 10.919781][ T256] ? __netlink_lookup+0xb9/0x6b0 [ 10.919784][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919785][ T256] ? lock_release+0x184/0x1f0 [ 10.919788][ T256] netlink_unicast+0x486/0x750 [ 10.919789][ T256] ? netlink_attachskb+0x810/0x810 [ 10.919791][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919793][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919794][ T256] ? lock_acquire+0x13c/0x160 [ 10.919796][ T256] netlink_sendmsg+0x735/0xc60 [ 10.919798][ T256] ? netlink_unicast+0x750/0x750 [ 10.919800][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919802][ T256] ? lock_release+0x184/0x1f0 [ 10.919804][ T256] ____sys_sendmsg+0x415/0x880 [ 10.919806][ T256] ? copy_msghdr_from_user+0x279/0x420 [ 10.919808][ T256] ? get_timestamp.constprop.0+0x390/0x390 [ 10.919809][ T256] ? move_addr_to_kernel+0x40/0x40 [ 10.919811][ T256] ? filter_irq_stacks+0xd0/0xd0 [ 10.919813][ T256] ___sys_sendmsg+0x14e/0x1d0 [ 10.919815][ T256] ? copy_msghdr_from_user+0x420/0x420 [ 10.919820][ T256] __sys_sendmsg+0x12c/0x1d0 [ 10.919822][ T256] ? __sys_sendmsg_sock+0x20/0x20 [ 10.919825][ T256] ? rcu_is_watching+0x16/0xd0 [ 10.919827][ T256] do_syscall_64+0xff/0x530 [ 10.919828][ T256] ? exc_page_fault+0xee/0x100 [ 10.919830][ T256] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.919832][ T256] RIP: 0033:0x7fd12a8c454e [ 10.919834][ T256] 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 [ 10.919836][ T256] RSP: 002b:00007fff25818f60 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 10.919840][ T256] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fd12a8c454e [ 10.919841][ T256] RDX: 0000000000000000 RSI: 00007fff25819010 RDI: 0000000000000005 [ 10.919842][ T256] RBP: 00007fff25818f70 R08: 0000000000000000 R09: 0000000000000000 [ 10.919843][ T256] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a90da4a [ 10.919844][ T256] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff258196d0 [ 10.919846][ T256] [ 10.955377][ T256] br1: port 1(veth1) entered disabled state [ 10.955636][ T256] veth1: entered allmulticast mode [ 10.956634][ T256] veth1: entered promiscuous mode [ 10.960772][ T256] ip (256) used greatest stack depth: 23336 bytes left [ 10.979279][ T36] br1: port 1(veth1) entered blocking state [ 10.979479][ T36] br1: port 1(veth1) entered forwarding state [ 11.000030][ T258] br1: port 2(veth2) entered blocking state [ 11.000291][ T258] br1: port 2(veth2) entered disabled state [ 11.000550][ T258] veth2: entered allmulticast mode [ 11.001557][ T258] veth2: entered promiscuous mode [ 11.019870][ T105] br1: port 2(veth2) entered blocking state [ 11.020076][ T105] br1: port 2(veth2) entered forwarding state [ 11.081061][ T262] 8021q: 802.1Q VLAN Support v1.8 [ 11.270030][ T272] GACT probability NOT on [ 16.848678][ T316] Mirror/redirect action on [ 16.848694][ T316] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.848697][ T316] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 316, name: modprobe [ 16.848698][ T316] preempt_count: 1, expected: 0 [ 16.848699][ T316] RCU nest depth: 0, expected: 0 [ 16.848700][ T316] INFO: lockdep is turned off. [ 16.848702][ T316] irq event stamp: 0 [ 16.848702][ T316] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 16.848706][ T316] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 16.848712][ T316] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 16.848714][ T316] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 16.848715][ T316] Preemption disabled at: [ 16.848716][ T316] [] vprintk_emit+0x31b/0x3e0 [ 16.848723][ T316] CPU: 2 UID: 0 PID: 316 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.848726][ T316] Tainted: [W]=WARN [ 16.848727][ T316] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.848729][ T316] Call Trace: [ 16.848731][ T316] [ 16.848732][ T316] dump_stack_lvl+0x6f/0xa0 [ 16.848737][ T316] ? vprintk_emit+0x31b/0x3e0 [ 16.848739][ T316] __might_resched.cold+0x1fe/0x2c1 [ 16.848743][ T316] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 16.848745][ T316] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.848748][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848752][ T316] __kmalloc_noprof+0x443/0x760 [ 16.848755][ T316] ? __rwlock_init+0x150/0x150 [ 16.848758][ T316] ? alloc_buf.isra.0+0x4b/0x260 [ 16.848761][ T316] ? do_raw_spin_unlock+0x59/0x250 [ 16.848763][ T316] alloc_buf.isra.0+0x4b/0x260 [ 16.848765][ T316] put_chars+0x1e1/0x2f0 [ 16.848767][ T316] ? __send_to_port+0x420/0x420 [ 16.848769][ T316] ? console_prepend_replay+0x20/0x20 [ 16.848771][ T316] hvc_console_print+0x292/0x780 [ 16.848774][ T316] ? hvc_write+0x3a0/0x3a0 [ 16.848775][ T316] ? vsnprintf+0x668/0x1700 [ 16.848778][ T316] ? __rwlock_init+0x150/0x150 [ 16.848780][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848781][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848784][ T316] console_emit_next_record+0x22f/0x4c0 [ 16.848786][ T316] ? devkmsg_read+0x4b0/0x4b0 [ 16.848788][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848790][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848791][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848793][ T316] ? lock_acquire+0x13c/0x160 [ 16.848795][ T316] ? console_flush_one_record+0x111/0x710 [ 16.848797][ T316] console_flush_one_record+0x46f/0x710 [ 16.848799][ T316] ? console_emit_next_record+0x4c0/0x4c0 [ 16.848802][ T316] console_unlock+0xee/0x1f0 [ 16.848804][ T316] ? lock_acquire+0x13c/0x160 [ 16.848805][ T316] ? console_flush_one_record+0x710/0x710 [ 16.848807][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848809][ T316] ? lock_release+0x184/0x1f0 [ 16.848810][ T316] ? lock_acquire+0x60/0x160 [ 16.848811][ T316] ? __rcu_read_lock+0x30/0x70 [ 16.848813][ T316] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.848816][ T316] ? vprintk_emit+0x320/0x3e0 [ 16.848818][ T316] vprintk_emit+0x37c/0x3e0 [ 16.848821][ T316] ? wake_up_klogd_work_func+0x90/0x90 [ 16.848823][ T316] ? netdev_run_todo+0x2a8/0xfd0 [ 16.848827][ T316] ? mirred_device_event+0x3d/0x1d0 [act_mirred] [ 16.848830][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848832][ T316] ? llc_exit+0x3e0/0x3e0 [llc] [ 16.848834][ T316] _printk+0xc7/0x100 [ 16.848837][ T316] ? snapshot_read.cold+0x21/0x21 [ 16.848839][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848841][ T316] ? llc_exit+0x3e0/0x3e0 [llc] [ 16.848842][ T316] ? __up_write+0x23d/0x5f0 [ 16.848844][ T316] ? __up_write+0x2e5/0x5f0 [ 16.848845][ T316] ? lock_release+0x184/0x1f0 [ 16.848847][ T316] ? __up_write+0x2c7/0x5f0 [ 16.848848][ T316] mirred_init_module+0x28/0x1000 [act_mirred] [ 16.848850][ T316] do_one_initcall+0x124/0x4f0 [ 16.848853][ T316] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 16.848855][ T316] ? do_init_module+0xae/0x8a0 [ 16.848858][ T316] ? kasan_unpoison+0x44/0x70 [ 16.848860][ T316] do_init_module+0x2ad/0x8a0 [ 16.848863][ T316] ? free_module+0x470/0x470 [ 16.848865][ T316] ? kfree+0x123/0x590 [ 16.848867][ T316] load_module+0x1424/0x1bb0 [ 16.848870][ T316] ? layout_and_allocate.isra.0+0x680/0x680 [ 16.848872][ T316] ? lock_release+0x184/0x1f0 [ 16.848874][ T316] ? __ia32_sys_fsconfig+0x180/0x180 [ 16.848876][ T316] ? lock_acquire+0x13c/0x160 [ 16.848878][ T316] init_module_from_file+0x11f/0x1a0 [ 16.848881][ T316] ? __do_sys_init_module+0x220/0x220 [ 16.848882][ T316] ? rcu_read_unlock+0x20/0x70 [ 16.848885][ T316] ? handle_pte_fault+0x426/0x710 [ 16.848888][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848889][ T316] ? ovl_mmap+0x270/0x270 [ 16.848893][ T316] ? __rwlock_init+0x150/0x150 [ 16.848895][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848897][ T316] ? lock_release+0x184/0x1f0 [ 16.848898][ T316] ? do_raw_spin_unlock+0x59/0x250 [ 16.848900][ T316] idempotent_init_module+0x37b/0x7c0 [ 16.848902][ T316] ? init_module_from_file+0x1a0/0x1a0 [ 16.848905][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848907][ T316] ? rcu_is_watching+0x16/0xd0 [ 16.848908][ T316] ? cap_capable+0x1d7/0x3d0 [ 16.848912][ T316] __x64_sys_finit_module+0xdf/0x190 [ 16.848914][ T316] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.848916][ T316] do_syscall_64+0xff/0x530 [ 16.848918][ T316] ? exc_page_fault+0xee/0x100 [ 16.848920][ T316] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 16.848923][ T316] RIP: 0033:0x7f9766e5a37d [ 16.848925][ T316] 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 [ 16.848926][ T316] RSP: 002b:00007ffc95d7c228 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 16.848930][ T316] RAX: ffffffffffffffda RBX: 0000557319845810 RCX: 00007f9766e5a37d [ 16.848931][ T316] RDX: 0000000000000000 RSI: 00005572ffaa5ffd RDI: 0000000000000000 [ 16.848932][ T316] RBP: 00007ffc95d7c2c0 R08: 0000000000000000 R09: 0000000000000000 [ 16.848933][ T316] R10: 0000000000000000 R11: 0000000000000246 R12: 00005572ffaa5ffd [ 16.848934][ T316] R13: 0000557319845960 R14: 0000000000040000 R15: 0000000000000000 [ 16.848936][ T316] [ 43.436401][ T504] br1: port 1(veth1) entered disabled state [ 43.436415][ T504] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 43.436417][ T504] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 504, name: ip [ 43.436419][ T504] preempt_count: 202, expected: 0 [ 43.436420][ T504] RCU nest depth: 0, expected: 0 [ 43.436420][ T504] INFO: lockdep is turned off. [ 43.436422][ T504] irq event stamp: 0 [ 43.436423][ T504] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 43.436428][ T504] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 43.436433][ T504] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 43.436435][ T504] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 43.436436][ T504] Preemption disabled at: [ 43.436437][ T504] [<0000000000000000>] 0x0 [ 43.436441][ T504] CPU: 3 UID: 0 PID: 504 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 43.436444][ T504] Tainted: [W]=WARN [ 43.436445][ T504] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 43.436447][ T504] Call Trace: [ 43.436449][ T504] [ 43.436450][ T504] dump_stack_lvl+0x6f/0xa0 [ 43.436455][ T504] __might_resched.cold+0x1fe/0x2c1 [ 43.436459][ T504] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 43.436463][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436467][ T504] __kmalloc_noprof+0x443/0x760 [ 43.436470][ T504] ? __rwlock_init+0x150/0x150 [ 43.436473][ T504] ? alloc_buf.isra.0+0x4b/0x260 [ 43.436476][ T504] ? do_raw_spin_unlock+0x59/0x250 [ 43.436478][ T504] alloc_buf.isra.0+0x4b/0x260 [ 43.436480][ T504] put_chars+0x1e1/0x2f0 [ 43.436482][ T504] ? __send_to_port+0x420/0x420 [ 43.436483][ T504] ? printk_get_next_message+0x240/0x650 [ 43.436486][ T504] hvc_console_print+0x292/0x780 [ 43.436489][ T504] ? hvc_write+0x3a0/0x3a0 [ 43.436492][ T504] ? __rwlock_init+0x150/0x150 [ 43.436493][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436495][ T504] ? lock_acquire+0x13c/0x160 [ 43.436497][ T504] console_emit_next_record+0x22f/0x4c0 [ 43.436499][ T504] ? devkmsg_read+0x4b0/0x4b0 [ 43.436501][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436503][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436504][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436506][ T504] ? lock_acquire+0x13c/0x160 [ 43.436508][ T504] ? console_flush_one_record+0x111/0x710 [ 43.436509][ T504] console_flush_one_record+0x46f/0x710 [ 43.436512][ T504] ? console_emit_next_record+0x4c0/0x4c0 [ 43.436514][ T504] console_unlock+0xee/0x1f0 [ 43.436516][ T504] ? lock_acquire+0x13c/0x160 [ 43.436517][ T504] ? console_flush_one_record+0x710/0x710 [ 43.436519][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436520][ T504] ? lock_release+0x184/0x1f0 [ 43.436522][ T504] ? lock_acquire+0x60/0x160 [ 43.436523][ T504] ? __rcu_read_lock+0x30/0x70 [ 43.436525][ T504] ? __down_trylock_console_sem+0x5e/0xa0 [ 43.436527][ T504] ? vprintk_emit+0x320/0x3e0 [ 43.436529][ T504] vprintk_emit+0x37c/0x3e0 [ 43.436532][ T504] ? wake_up_klogd_work_func+0x90/0x90 [ 43.436533][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436535][ T504] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 43.436537][ T504] ? trace_hardirqs_on+0x36/0x40 [ 43.436539][ T504] _printk+0xc7/0x100 [ 43.436542][ T504] ? snapshot_read.cold+0x21/0x21 [ 43.436544][ T504] ? ipv6_mc_down+0x17e/0x2d0 [ 43.436548][ T504] br_set_state+0x22f/0x430 [bridge] [ 43.436561][ T504] br_stp_disable_port+0x11f/0x390 [bridge] [ 43.436569][ T504] br_device_event+0x7b5/0x8f0 [bridge] [ 43.436578][ T504] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 43.436581][ T504] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 43.436583][ T504] ? addrconf_notify+0x2c8/0xf30 [ 43.436589][ T504] notifier_call_chain+0xae/0x300 [ 43.436592][ T504] __dev_notify_flags+0x23c/0x3c0 [ 43.436596][ T504] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 43.436597][ T504] ? netif_change_name+0x8c0/0x8c0 [ 43.436599][ T504] ? netif_set_allmulti+0x250/0x250 [ 43.436601][ T504] ? __kernel_text_address+0x12/0x30 [ 43.436603][ T504] ? write_profile+0xf0/0xf0 [ 43.436605][ T504] ? unwind_get_return_address+0x67/0xd0 [ 43.436608][ T504] netif_change_flags+0xfe/0x190 [ 43.436610][ T504] do_setlink.isra.0+0x1cc3/0x2750 [ 43.436613][ T504] ? stack_trace_save+0x93/0xc0 [ 43.436614][ T504] ? rtnl_link_get_size+0x350/0x350 [ 43.436616][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436617][ T504] ? stack_depot_save_flags+0x1bc/0x790 [ 43.436621][ T504] ? kasan_save_stack+0x3d/0x50 [ 43.436624][ T504] ? kasan_save_stack+0x2f/0x50 [ 43.436625][ T504] ? kasan_save_track+0x14/0x30 [ 43.436626][ T504] ? __kasan_kmalloc+0x7b/0x90 [ 43.436628][ T504] ? __kmalloc_cache_noprof+0x29a/0x620 [ 43.436629][ T504] ? rtnl_newlink+0x124/0x11c0 [ 43.436630][ T504] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 43.436631][ T504] ? netlink_rcv_skb+0x14e/0x3a0 [ 43.436634][ T504] ? netlink_unicast+0x486/0x750 [ 43.436635][ T504] ? netlink_sendmsg+0x735/0xc60 [ 43.436636][ T504] ? ____sys_sendmsg+0x415/0x880 [ 43.436639][ T504] ? ___sys_sendmsg+0x14e/0x1d0 [ 43.436640][ T504] ? __sys_sendmsg+0x12c/0x1d0 [ 43.436642][ T504] ? do_syscall_64+0xff/0x530 [ 43.436644][ T504] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 43.436646][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436648][ T504] ? lock_acquire+0x13c/0x160 [ 43.436649][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436651][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436652][ T504] ? trace_contention_end+0xb3/0x180 [ 43.436655][ T504] ? __mutex_lock+0x1db/0x1ea0 [ 43.436657][ T504] ? __mutex_lock+0x9a3/0x1ea0 [ 43.436658][ T504] ? rtnl_newlink+0x9a8/0x11c0 [ 43.436659][ T504] ? ww_mutex_lock+0x160/0x160 [ 43.436661][ T504] ? __rtnl_newlink+0x3fa/0xa50 [ 43.436663][ T504] rtnl_newlink+0x9f1/0x11c0 [ 43.436665][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436667][ T504] ? rtnl_bridge_getlink+0x850/0x850 [ 43.436668][ T504] ? lock_acquire+0x13c/0x160 [ 43.436669][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436671][ T504] ? is_bpf_text_address+0x3e/0x110 [ 43.436674][ T504] ? is_bpf_text_address+0x72/0x110 [ 43.436676][ T504] ? kernel_text_address+0x149/0x170 [ 43.436677][ T504] ? __kernel_text_address+0x12/0x30 [ 43.436678][ T504] ? write_profile+0xf0/0xf0 [ 43.436679][ T504] ? unwind_get_return_address+0x67/0xd0 [ 43.436681][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436682][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436684][ T504] ? cap_capable+0x1d7/0x3d0 [ 43.436687][ T504] ? lock_acquire+0x13c/0x160 [ 43.436689][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436690][ T504] ? rtnl_bridge_getlink+0x850/0x850 [ 43.436692][ T504] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 43.436693][ T504] ? rtnl_link_fill+0x920/0x920 [ 43.436694][ T504] ? filter_irq_stacks+0xd0/0xd0 [ 43.436696][ T504] ? stack_depot_save_flags+0x1bc/0x790 [ 43.436698][ T504] netlink_rcv_skb+0x14e/0x3a0 [ 43.436699][ T504] ? rtnl_link_fill+0x920/0x920 [ 43.436701][ T504] ? lock_release+0x184/0x1f0 [ 43.436702][ T504] ? netlink_ack+0xcf0/0xcf0 [ 43.436703][ T504] ? __netlink_lookup+0xb9/0x6b0 [ 43.436706][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436708][ T504] ? lock_release+0x184/0x1f0 [ 43.436709][ T504] netlink_unicast+0x486/0x750 [ 43.436711][ T504] ? netlink_attachskb+0x810/0x810 [ 43.436713][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436714][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436716][ T504] ? lock_acquire+0x13c/0x160 [ 43.436718][ T504] netlink_sendmsg+0x735/0xc60 [ 43.436720][ T504] ? netlink_unicast+0x750/0x750 [ 43.436721][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436723][ T504] ? lock_release+0x184/0x1f0 [ 43.436725][ T504] ____sys_sendmsg+0x415/0x880 [ 43.436726][ T504] ? copy_msghdr_from_user+0x279/0x420 [ 43.436728][ T504] ? get_timestamp.constprop.0+0x390/0x390 [ 43.436730][ T504] ? move_addr_to_kernel+0x40/0x40 [ 43.436731][ T504] ? filter_irq_stacks+0xd0/0xd0 [ 43.436733][ T504] ___sys_sendmsg+0x14e/0x1d0 [ 43.436735][ T504] ? copy_msghdr_from_user+0x420/0x420 [ 43.436740][ T504] __sys_sendmsg+0x12c/0x1d0 [ 43.436742][ T504] ? __sys_sendmsg_sock+0x20/0x20 [ 43.436745][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.436747][ T504] do_syscall_64+0xff/0x530 [ 43.436748][ T504] ? exc_page_fault+0xee/0x100 [ 43.436750][ T504] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 43.436752][ T504] RIP: 0033:0x7fcc4513454e [ 43.436753][ T504] 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 [ 43.436755][ T504] RSP: 002b:00007fffb5f319e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 43.436758][ T504] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fcc4513454e [ 43.436760][ T504] RDX: 0000000000000000 RSI: 00007fffb5f31a90 RDI: 0000000000000005 [ 43.436761][ T504] RBP: 00007fffb5f319f0 R08: 0000000000000000 R09: 0000000000000000 [ 43.436761][ T504] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a90da6a [ 43.436762][ T504] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fffb5f32150 [ 43.436765][ T504] [ 43.472918][ T504] ip (504) used greatest stack depth: 23016 bytes left [ 43.484485][ T505] br1: port 2(veth2) entered disabled state [ 43.503841][ T506] veth2: left allmulticast mode [ 43.504011][ T506] veth2: left promiscuous mode [ 43.504218][ T506] br1: port 2(veth2) entered disabled state [ 43.509439][ T506] veth1: left allmulticast mode [ 43.509585][ T506] veth1: left promiscuous mode [ 43.510135][ T506] br1: port 1(veth1) entered disabled state