FAULT_INJECTION: forcing a failure. name fail_usercopy, interval 1, probability 0, space 0, times 0 ====================================================== WARNING: possible circular locking dependency detected syzkaller #0 Tainted: G L ------------------------------------------------------ syz.8.7523/5030 is trying to acquire lock: ffffffff8e2d6ac0 (console_owner){-.-.}-{0:0}, at: console_lock_spinning_enable+0x61/0x80 kernel/printk/printk.c:1900 but task is already holding lock: ffff88802b23add8 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested kernel/sched/core.c:647 [inline] ffff88802b23add8 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x7e/0x130 kernel/sched/core.c:632 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #4 (&rq->__lock){-.-.}-{2:2}: _raw_spin_lock_nested+0x31/0x40 kernel/locking/spinlock.c:378 raw_spin_rq_lock_nested+0x29/0x130 kernel/sched/core.c:639 raw_spin_rq_lock kernel/sched/sched.h:1558 [inline] task_rq_lock+0xcf/0x490 kernel/sched/core.c:739 cgroup_move_task+0x81/0x2a0 kernel/sched/psi.c:1170 css_set_move_task+0x288/0x5f0 kernel/cgroup/cgroup.c:951 cgroup_post_fork+0x201/0x9e0 kernel/cgroup/cgroup.c:6913 copy_process+0x56b6/0x7430 kernel/fork.c:2465 kernel_clone+0xfc/0x910 kernel/fork.c:2651 user_mode_thread+0xc8/0x110 kernel/fork.c:2727 rest_init+0x23/0x2b0 init/main.c:722 start_kernel+0x3ef/0x4d0 init/main.c:1206 x86_64_start_reservations+0x18/0x30 arch/x86/kernel/head64.c:310 x86_64_start_kernel+0x130/0x190 arch/x86/kernel/head64.c:291 common_startup_64+0x13e/0x148 -> #3 (&p->pi_lock){-.-.}-{2:2}: __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline] _raw_spin_lock_irqsave+0x3a/0x60 kernel/locking/spinlock.c:162 class_raw_spinlock_irqsave_constructor include/linux/spinlock.h:557 [inline] try_to_wake_up+0xb7/0x1860 kernel/sched/core.c:4106 __wake_up_common+0x135/0x1f0 kernel/sched/wait.c:108 __wake_up_common_lock kernel/sched/wait.c:125 [inline] __wake_up+0x31/0x60 kernel/sched/wait.c:146 tty_port_default_wakeup+0x4d/0x60 drivers/tty/tty_port.c:67 serial8250_tx_chars+0x68e/0x860 drivers/tty/serial/8250/8250_port.c:1719 serial8250_handle_irq+0x761/0xcb0 drivers/tty/serial/8250/8250_port.c:1827 serial8250_default_handle_irq+0x9e/0x270 drivers/tty/serial/8250/8250_port.c:1846 serial8250_interrupt+0xf8/0x1d0 drivers/tty/serial/8250/8250_core.c:86 __handle_irq_event_percpu+0x236/0x890 kernel/irq/handle.c:211 handle_irq_event_percpu kernel/irq/handle.c:248 [inline] handle_irq_event+0xab/0x1e0 kernel/irq/handle.c:265 handle_edge_irq+0x3ca/0x9e0 kernel/irq/chip.c:855 generic_handle_irq_desc include/linux/irqdesc.h:172 [inline] handle_irq arch/x86/kernel/irq.c:255 [inline] call_irq_handler arch/x86/kernel/irq.c:311 [inline] __common_interrupt+0xd0/0x2f0 arch/x86/kernel/irq.c:326 common_interrupt+0xba/0xe0 arch/x86/kernel/irq.c:319 asm_common_interrupt+0x26/0x40 arch/x86/include/asm/idtentry.h:688 native_safe_halt arch/x86/include/asm/irqflags.h:48 [inline] pv_native_safe_halt+0xf/0x20 arch/x86/kernel/paravirt.c:81 arch_safe_halt arch/x86/include/asm/paravirt.h:107 [inline] default_idle+0x13/0x20 arch/x86/kernel/process.c:767 default_idle_call+0x6c/0xb0 kernel/sched/idle.c:122 cpuidle_idle_call kernel/sched/idle.c:191 [inline] do_idle+0x38d/0x510 kernel/sched/idle.c:332 cpu_startup_entry+0x4f/0x60 kernel/sched/idle.c:430 start_secondary+0x21d/0x2d0 arch/x86/kernel/smpboot.c:312 common_startup_64+0x13e/0x148 -> #2 (&tty->write_wait){-.-.}-{3:3}: __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline] _raw_spin_lock_irqsave+0x3a/0x60 kernel/locking/spinlock.c:162 __wake_up_common_lock kernel/sched/wait.c:124 [inline] __wake_up+0x1c/0x60 kernel/sched/wait.c:146 tty_port_default_wakeup+0x4d/0x60 drivers/tty/tty_port.c:67 serial8250_tx_chars+0x68e/0x860 drivers/tty/serial/8250/8250_port.c:1719 __start_tx+0x3df/0x490 drivers/tty/serial/8250/8250_port.c:1426 serial8250_start_tx+0x368/0x530 drivers/tty/serial/8250/8250_port.c:1535 __uart_start+0x295/0x500 drivers/tty/serial/serial_core.c:161 uart_write+0x218/0xb30 drivers/tty/serial/serial_core.c:633 process_output_block drivers/tty/n_tty.c:557 [inline] n_tty_write+0x434/0x1280 drivers/tty/n_tty.c:2366 iterate_tty_write drivers/tty/tty_io.c:1006 [inline] file_tty_write.constprop.0+0x503/0x9b0 drivers/tty/tty_io.c:1081 tty_write drivers/tty/tty_io.c:1102 [inline] redirected_tty_write drivers/tty/tty_io.c:1125 [inline] redirected_tty_write+0xd4/0x120 drivers/tty/tty_io.c:1105 new_sync_write fs/read_write.c:593 [inline] vfs_write+0x7d3/0x11d0 fs/read_write.c:686 ksys_write+0x12a/0x250 fs/read_write.c:738 do_syscall_x64 arch/x86/entry/syscall_64.c:63 [inline] do_syscall_64+0xcd/0xf80 arch/x86/entry/syscall_64.c:94 entry_SYSCALL_64_after_hwframe+0x77/0x7f -> #1 (&port_lock_key){-.-.}-{3:3}: __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline] _raw_spin_lock_irqsave+0x3a/0x60 kernel/locking/spinlock.c:162 uart_port_lock_irqsave include/linux/serial_core.h:717 [inline] serial8250_console_write+0x181/0x1890 drivers/tty/serial/8250/8250_port.c:3301 console_emit_next_record kernel/printk/printk.c:3129 [inline] console_flush_one_record+0x796/0xc60 kernel/printk/printk.c:3215 console_flush_all kernel/printk/printk.c:3289 [inline] __console_flush_and_unlock kernel/printk/printk.c:3319 [inline] console_unlock+0xef/0x240 kernel/printk/printk.c:3359 vprintk_emit+0x407/0x6b0 kernel/printk/printk.c:2426 _printk+0xc7/0x100 kernel/printk/printk.c:2451 register_console+0x823/0x1210 kernel/printk/printk.c:4170 univ8250_console_init+0x5f/0x90 drivers/tty/serial/8250/8250_core.c:515 console_init+0x152/0x600 kernel/printk/printk.c:4369 start_kernel+0x298/0x4d0 init/main.c:1143 x86_64_start_reservations+0x18/0x30 arch/x86/kernel/head64.c:310 x86_64_start_kernel+0x130/0x190 arch/x86/kernel/head64.c:291 common_startup_64+0x13e/0x148 -> #0 (console_owner){-.-.}-{0:0}: check_prev_add kernel/locking/lockdep.c:3165 [inline] check_prevs_add kernel/locking/lockdep.c:3284 [inline] validate_chain kernel/locking/lockdep.c:3908 [inline] __lock_acquire+0x1669/0x2890 kernel/locking/lockdep.c:5237 lock_acquire kernel/locking/lockdep.c:5868 [inline] lock_acquire+0x179/0x330 kernel/locking/lockdep.c:5825 console_lock_spinning_enable+0x72/0x80 kernel/printk/printk.c:1900 console_emit_next_record kernel/printk/printk.c:3123 [inline] console_flush_one_record+0x73f/0xc60 kernel/printk/printk.c:3215 console_flush_all kernel/printk/printk.c:3289 [inline] __console_flush_and_unlock kernel/printk/printk.c:3319 [inline] console_unlock+0xef/0x240 kernel/printk/printk.c:3359 vprintk_emit+0x407/0x6b0 kernel/printk/printk.c:2426 _printk+0xc7/0x100 kernel/printk/printk.c:2451 fail_dump lib/fault-inject.c:66 [inline] should_fail_ex+0x4e7/0x640 lib/fault-inject.c:174 strncpy_from_user+0x3b/0x2e0 lib/strncpy_from_user.c:118 strncpy_from_user_nofault+0x7f/0x180 mm/maccess.c:192 bpf_trace_copy_string kernel/bpf/helpers.c:761 [inline] bpf_bprintf_prepare+0xe90/0x13f0 kernel/bpf/helpers.c:976 ____bpf_trace_printk kernel/trace/bpf_trace.c:372 [inline] bpf_trace_printk+0xda/0x190 kernel/trace/bpf_trace.c:362 bpf_prog_930ede9872f2967c+0x3e/0x44 bpf_dispatcher_nop_func include/linux/bpf.h:1378 [inline] __bpf_prog_run include/linux/filter.h:723 [inline] bpf_prog_run include/linux/filter.h:730 [inline] __bpf_trace_run kernel/trace/bpf_trace.c:2075 [inline] bpf_trace_run2+0x239/0x5c0 kernel/trace/bpf_trace.c:2116 __bpf_trace_contention_begin+0xc9/0x110 include/trace/events/lock.h:95 __do_trace_contention_begin include/trace/events/lock.h:95 [inline] trace_contention_begin.constprop.0+0xd6/0x140 include/trace/events/lock.h:95 __pv_queued_spin_lock_slowpath+0x109/0xcf0 kernel/locking/qspinlock.c:219 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:557 [inline] queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:51 [inline] queued_spin_lock include/asm-generic/qspinlock.h:114 [inline] do_raw_spin_lock+0x20e/0x2b0 kernel/locking/spinlock_debug.c:116 raw_spin_rq_lock_nested kernel/sched/core.c:647 [inline] raw_spin_rq_lock_nested+0x7e/0x130 kernel/sched/core.c:632 raw_spin_rq_lock kernel/sched/sched.h:1558 [inline] rq_lock kernel/sched/sched.h:1885 [inline] __schedule+0x331/0x6150 kernel/sched/core.c:6768 preempt_schedule_notrace+0x62/0xe0 kernel/sched/core.c:7140 preempt_schedule_notrace_thunk+0x16/0x30 arch/x86/entry/thunk.S:13 rcu_is_watching+0x8e/0xc0 kernel/rcu/tree.c:752 trace_lock_acquire include/trace/events/lock.h:24 [inline] lock_acquire+0x2cd/0x330 kernel/locking/lockdep.c:5831 rcu_lock_acquire include/linux/rcupdate.h:331 [inline] rcu_read_lock include/linux/rcupdate.h:867 [inline] class_rcu_constructor include/linux/rcupdate.h:1195 [inline] unwind_next_frame+0xd1/0x20b0 arch/x86/kernel/unwind_orc.c:495 arch_stack_walk+0x94/0x100 arch/x86/kernel/stacktrace.c:25 stack_trace_save+0x8e/0xc0 kernel/stacktrace.c:122 save_stack+0x160/0x1f0 mm/page_owner.c:165 __reset_page_owner+0x84/0x1a0 mm/page_owner.c:320 reset_page_owner include/linux/page_owner.h:25 [inline] free_pages_prepare mm/page_alloc.c:1433 [inline] __free_frozen_pages+0x7df/0x1170 mm/page_alloc.c:2973 __kasan_populate_vmalloc_do mm/kasan/shadow.c:393 [inline] __kasan_populate_vmalloc+0x149/0x220 mm/kasan/shadow.c:424 kasan_populate_vmalloc include/linux/kasan.h:580 [inline] alloc_vmap_area+0x98d/0x2a50 mm/vmalloc.c:2124 __get_vm_area_node+0x1ca/0x330 mm/vmalloc.c:3219 __vmalloc_node_range_noprof+0x247/0x16b0 mm/vmalloc.c:4011 __vmalloc_node_noprof+0xad/0xf0 mm/vmalloc.c:4111 __vmalloc_noprof+0xa3/0x120 mm/vmalloc.c:4127 bpf_prog_alloc_no_stats+0x58/0x600 kernel/bpf/core.c:106 bpf_prog_alloc+0x3b/0x230 kernel/bpf/core.c:153 bpf_prog_load+0x19bc/0x2cc0 kernel/bpf/syscall.c:2992 __sys_bpf+0x3e72/0x4980 kernel/bpf/syscall.c:6164 __do_sys_bpf kernel/bpf/syscall.c:6274 [inline] __se_sys_bpf kernel/bpf/syscall.c:6272 [inline] __ia32_sys_bpf+0x76/0xe0 kernel/bpf/syscall.c:6272 do_syscall_32_irqs_on arch/x86/entry/syscall_32.c:83 [inline] __do_fast_syscall_32+0xe8/0x680 arch/x86/entry/syscall_32.c:307 do_fast_syscall_32+0x32/0x80 arch/x86/entry/syscall_32.c:332 entry_SYSENTER_compat_after_hwframe+0x84/0x8e other info that might help us debug this: Chain exists of: console_owner --> &p->pi_lock --> &rq->__lock Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(&rq->__lock); lock(&p->pi_lock); lock(&rq->__lock); lock(console_owner); *** DEADLOCK *** 4 locks held by syz.8.7523/5030: #0: ffff88802b23add8 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested kernel/sched/core.c:647 [inline] #0: ffff88802b23add8 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x7e/0x130 kernel/sched/core.c:632 #1: ffffffff8e3c9520 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire include/linux/rcupdate.h:331 [inline] #1: ffffffff8e3c9520 (rcu_read_lock){....}-{1:3}, at: rcu_read_lock include/linux/rcupdate.h:867 [inline] #1: ffffffff8e3c9520 (rcu_read_lock){....}-{1:3}, at: __bpf_trace_run kernel/trace/bpf_trace.c:2074 [inline] #1: ffffffff8e3c9520 (rcu_read_lock){....}-{1:3}, at: bpf_trace_run2+0x1bc/0x5c0 kernel/trace/bpf_trace.c:2116 #2: ffffffff8e3b6f00 (console_lock){+.+.}-{0:0}, at: _printk+0xc7/0x100 kernel/printk/printk.c:2451 #3: ffffffff8e3b6f78 (console_srcu){....}-{0:0}, at: rcu_try_lock_acquire include/linux/rcupdate.h:336 [inline] #3: ffffffff8e3b6f78 (console_srcu){....}-{0:0}, at: srcu_read_lock_nmisafe include/linux/srcu.h:409 [inline] #3: ffffffff8e3b6f78 (console_srcu){....}-{0:0}, at: console_srcu_read_lock kernel/printk/printk.c:289 [inline] #3: ffffffff8e3b6f78 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x103/0xc60 kernel/printk/printk.c:3192 stack backtrace: CPU: 1 UID: 0 PID: 5030 Comm: syz.8.7523 Tainted: G L syzkaller #0 PREEMPT(full) Tainted: [L]=SOFTLOCKUP Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.3-debian-1.16.3-2~bpo12+1 04/01/2014 Call Trace: __dump_stack lib/dump_stack.c:94 [inline] dump_stack_lvl+0x116/0x1f0 lib/dump_stack.c:120 print_circular_bug+0x275/0x340 kernel/locking/lockdep.c:2043 check_noncircular+0x146/0x160 kernel/locking/lockdep.c:2175 check_prev_add kernel/locking/lockdep.c:3165 [inline] check_prevs_add kernel/locking/lockdep.c:3284 [inline] validate_chain kernel/locking/lockdep.c:3908 [inline] __lock_acquire+0x1669/0x2890 kernel/locking/lockdep.c:5237 lock_acquire kernel/locking/lockdep.c:5868 [inline] lock_acquire+0x179/0x330 kernel/locking/lockdep.c:5825 console_lock_spinning_enable+0x72/0x80 kernel/printk/printk.c:1900 console_emit_next_record kernel/printk/printk.c:3123 [inline] console_flush_one_record+0x73f/0xc60 kernel/printk/printk.c:3215 console_flush_all kernel/printk/printk.c:3289 [inline] __console_flush_and_unlock kernel/printk/printk.c:3319 [inline] console_unlock+0xef/0x240 kernel/printk/printk.c:3359 vprintk_emit+0x407/0x6b0 kernel/printk/printk.c:2426 _printk+0xc7/0x100 kernel/printk/printk.c:2451 fail_dump lib/fault-inject.c:66 [inline] should_fail_ex+0x4e7/0x640 lib/fault-inject.c:174 strncpy_from_user+0x3b/0x2e0 lib/strncpy_from_user.c:118 strncpy_from_user_nofault+0x7f/0x180 mm/maccess.c:192 bpf_trace_copy_string kernel/bpf/helpers.c:761 [inline] bpf_bprintf_prepare+0xe90/0x13f0 kernel/bpf/helpers.c:976 ____bpf_trace_printk kernel/trace/bpf_trace.c:372 [inline] bpf_trace_printk+0xda/0x190 kernel/trace/bpf_trace.c:362 bpf_prog_930ede9872f2967c+0x3e/0x44 bpf_dispatcher_nop_func include/linux/bpf.h:1378 [inline] __bpf_prog_run include/linux/filter.h:723 [inline] bpf_prog_run include/linux/filter.h:730 [inline] __bpf_trace_run kernel/trace/bpf_trace.c:2075 [inline] bpf_trace_run2+0x239/0x5c0 kernel/trace/bpf_trace.c:2116 __bpf_trace_contention_begin+0xc9/0x110 include/trace/events/lock.h:95 __do_trace_contention_begin include/trace/events/lock.h:95 [inline] trace_contention_begin.constprop.0+0xd6/0x140 include/trace/events/lock.h:95 __pv_queued_spin_lock_slowpath+0x109/0xcf0 kernel/locking/qspinlock.c:219 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:557 [inline] queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:51 [inline] queued_spin_lock include/asm-generic/qspinlock.h:114 [inline] do_raw_spin_lock+0x20e/0x2b0 kernel/locking/spinlock_debug.c:116 raw_spin_rq_lock_nested kernel/sched/core.c:647 [inline] raw_spin_rq_lock_nested+0x7e/0x130 kernel/sched/core.c:632 raw_spin_rq_lock kernel/sched/sched.h:1558 [inline] rq_lock kernel/sched/sched.h:1885 [inline] __schedule+0x331/0x6150 kernel/sched/core.c:6768 preempt_schedule_notrace+0x62/0xe0 kernel/sched/core.c:7140 preempt_schedule_notrace_thunk+0x16/0x30 arch/x86/entry/thunk.S:13 rcu_is_watching+0x8e/0xc0 kernel/rcu/tree.c:752 trace_lock_acquire include/trace/events/lock.h:24 [inline] lock_acquire+0x2cd/0x330 kernel/locking/lockdep.c:5831 rcu_lock_acquire include/linux/rcupdate.h:331 [inline] rcu_read_lock include/linux/rcupdate.h:867 [inline] class_rcu_constructor include/linux/rcupdate.h:1195 [inline] unwind_next_frame+0xd1/0x20b0 arch/x86/kernel/unwind_orc.c:495 arch_stack_walk+0x94/0x100 arch/x86/kernel/stacktrace.c:25 stack_trace_save+0x8e/0xc0 kernel/stacktrace.c:122 save_stack+0x160/0x1f0 mm/page_owner.c:165 __reset_page_owner+0x84/0x1a0 mm/page_owner.c:320 reset_page_owner include/linux/page_owner.h:25 [inline] free_pages_prepare mm/page_alloc.c:1433 [inline] __free_frozen_pages+0x7df/0x1170 mm/page_alloc.c:2973 __kasan_populate_vmalloc_do mm/kasan/shadow.c:393 [inline] __kasan_populate_vmalloc+0x149/0x220 mm/kasan/shadow.c:424 kasan_populate_vmalloc include/linux/kasan.h:580 [inline] alloc_vmap_area+0x98d/0x2a50 mm/vmalloc.c:2124 __get_vm_area_node+0x1ca/0x330 mm/vmalloc.c:3219 __vmalloc_node_range_noprof+0x247/0x16b0 mm/vmalloc.c:4011 __vmalloc_node_noprof+0xad/0xf0 mm/vmalloc.c:4111 __vmalloc_noprof+0xa3/0x120 mm/vmalloc.c:4127 bpf_prog_alloc_no_stats+0x58/0x600 kernel/bpf/core.c:106 bpf_prog_alloc+0x3b/0x230 kernel/bpf/core.c:153 bpf_prog_load+0x19bc/0x2cc0 kernel/bpf/syscall.c:2992 __sys_bpf+0x3e72/0x4980 kernel/bpf/syscall.c:6164 __do_sys_bpf kernel/bpf/syscall.c:6274 [inline] __se_sys_bpf kernel/bpf/syscall.c:6272 [inline] __ia32_sys_bpf+0x76/0xe0 kernel/bpf/syscall.c:6272 do_syscall_32_irqs_on arch/x86/entry/syscall_32.c:83 [inline] __do_fast_syscall_32+0xe8/0x680 arch/x86/entry/syscall_32.c:307 do_fast_syscall_32+0x32/0x80 arch/x86/entry/syscall_32.c:332 entry_SYSENTER_compat_after_hwframe+0x84/0x8e RIP: 0023:0xf704d579 Code: b8 01 10 06 03 74 b4 01 10 07 03 74 b0 01 10 08 03 74 d8 01 00 00 00 00 00 00 00 00 00 00 00 00 00 51 52 55 89 e5 0f 34 cd 80 <5d> 5a 59 c3 90 90 90 90 8d b4 26 00 00 00 00 8d b4 26 00 00 00 00 RSP: 002b:00000000f543d55c EFLAGS: 00000296 ORIG_RAX: 0000000000000165 RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 0000000080000100 RDX: 0000000000000094 RSI: 0000000000000000 RDI: 0000000000000000 RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000 R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 CPU: 1 UID: 0 PID: 5030 Comm: syz.8.7523 Tainted: G L syzkaller #0 PREEMPT(full) Tainted: [L]=SOFTLOCKUP Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.3-debian-1.16.3-2~bpo12+1 04/01/2014 Call Trace: __dump_stack lib/dump_stack.c:94 [inline] dump_stack_lvl+0x116/0x1f0 lib/dump_stack.c:120 fail_dump lib/fault-inject.c:73 [inline] should_fail_ex+0x512/0x640 lib/fault-inject.c:174 strncpy_from_user+0x3b/0x2e0 lib/strncpy_from_user.c:118 strncpy_from_user_nofault+0x7f/0x180 mm/maccess.c:192 bpf_trace_copy_string kernel/bpf/helpers.c:761 [inline] bpf_bprintf_prepare+0xe90/0x13f0 kernel/bpf/helpers.c:976 ____bpf_trace_printk kernel/trace/bpf_trace.c:372 [inline] bpf_trace_printk+0xda/0x190 kernel/trace/bpf_trace.c:362 bpf_prog_930ede9872f2967c+0x3e/0x44 bpf_dispatcher_nop_func include/linux/bpf.h:1378 [inline] __bpf_prog_run include/linux/filter.h:723 [inline] bpf_prog_run include/linux/filter.h:730 [inline] __bpf_trace_run kernel/trace/bpf_trace.c:2075 [inline] bpf_trace_run2+0x239/0x5c0 kernel/trace/bpf_trace.c:2116 __bpf_trace_contention_begin+0xc9/0x110 include/trace/events/lock.h:95 __do_trace_contention_begin include/trace/events/lock.h:95 [inline] trace_contention_begin.constprop.0+0xd6/0x140 include/trace/events/lock.h:95 __pv_queued_spin_lock_slowpath+0x109/0xcf0 kernel/locking/qspinlock.c:219 pv_queued_spin_lock_slowpath arch/x86/include/asm/paravirt.h:557 [inline] queued_spin_lock_slowpath arch/x86/include/asm/qspinlock.h:51 [inline] queued_spin_lock include/asm-generic/qspinlock.h:114 [inline] do_raw_spin_lock+0x20e/0x2b0 kernel/locking/spinlock_debug.c:116 raw_spin_rq_lock_nested kernel/sched/core.c:647 [inline] raw_spin_rq_lock_nested+0x7e/0x130 kernel/sched/core.c:632 raw_spin_rq_lock kernel/sched/sched.h:1558 [inline] rq_lock kernel/sched/sched.h:1885 [inline] __schedule+0x331/0x6150 kernel/sched/core.c:6768 preempt_schedule_notrace+0x62/0xe0 kernel/sched/core.c:7140 preempt_schedule_notrace_thunk+0x16/0x30 arch/x86/entry/thunk.S:13 rcu_is_watching+0x8e/0xc0 kernel/rcu/tree.c:752 trace_lock_acquire include/trace/events/lock.h:24 [inline] lock_acquire+0x2cd/0x330 kernel/locking/lockdep.c:5831 rcu_lock_acquire include/linux/rcupdate.h:331 [inline] rcu_read_lock include/linux/rcupdate.h:867 [inline] class_rcu_constructor include/linux/rcupdate.h:1195 [inline] unwind_next_frame+0xd1/0x20b0 arch/x86/kernel/unwind_orc.c:495 arch_stack_walk+0x94/0x100 arch/x86/kernel/stacktrace.c:25 stack_trace_save+0x8e/0xc0 kernel/stacktrace.c:122 save_stack+0x160/0x1f0 mm/page_owner.c:165 __reset_page_owner+0x84/0x1a0 mm/page_owner.c:320 reset_page_owner include/linux/page_owner.h:25 [inline] free_pages_prepare mm/page_alloc.c:1433 [inline] __free_frozen_pages+0x7df/0x1170 mm/page_alloc.c:2973 __kasan_populate_vmalloc_do mm/kasan/shadow.c:393 [inline] __kasan_populate_vmalloc+0x149/0x220 mm/kasan/shadow.c:424 kasan_populate_vmalloc include/linux/kasan.h:580 [inline] alloc_vmap_area+0x98d/0x2a50 mm/vmalloc.c:2124 __get_vm_area_node+0x1ca/0x330 mm/vmalloc.c:3219 __vmalloc_node_range_noprof+0x247/0x16b0 mm/vmalloc.c:4011 __vmalloc_node_noprof+0xad/0xf0 mm/vmalloc.c:4111 __vmalloc_noprof+0xa3/0x120 mm/vmalloc.c:4127 bpf_prog_alloc_no_stats+0x58/0x600 kernel/bpf/core.c:106 bpf_prog_alloc+0x3b/0x230 kernel/bpf/core.c:153 bpf_prog_load+0x19bc/0x2cc0 kernel/bpf/syscall.c:2992 __sys_bpf+0x3e72/0x4980 kernel/bpf/syscall.c:6164 __do_sys_bpf kernel/bpf/syscall.c:6274 [inline] __se_sys_bpf kernel/bpf/syscall.c:6272 [inline] __ia32_sys_bpf+0x76/0xe0 kernel/bpf/syscall.c:6272 do_syscall_32_irqs_on arch/x86/entry/syscall_32.c:83 [inline] __do_fast_syscall_32+0xe8/0x680 arch/x86/entry/syscall_32.c:307 do_fast_syscall_32+0x32/0x80 arch/x86/entry/syscall_32.c:332 entry_SYSENTER_compat_after_hwframe+0x84/0x8e RIP: 0023:0xf704d579 Code: b8 01 10 06 03 74 b4 01 10 07 03 74 b0 01 10 08 03 74 d8 01 00 00 00 00 00 00 00 00 00 00 00 00 00 51 52 55 89 e5 0f 34 cd 80 <5d> 5a 59 c3 90 90 90 90 8d b4 26 00 00 00 00 8d b4 26 00 00 00 00 RSP: 002b:00000000f543d55c EFLAGS: 00000296 ORIG_RAX: 0000000000000165 RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 0000000080000100 RDX: 0000000000000094 RSI: 0000000000000000 RDI: 0000000000000000 RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000 R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 ---------------- Code disassembly (best guess), 2 bytes skipped: 0: 10 06 adc %al,(%rsi) 2: 03 74 b4 01 add 0x1(%rsp,%rsi,4),%esi 6: 10 07 adc %al,(%rdi) 8: 03 74 b0 01 add 0x1(%rax,%rsi,4),%esi c: 10 08 adc %cl,(%rax) e: 03 74 d8 01 add 0x1(%rax,%rbx,8),%esi 1e: 00 51 52 add %dl,0x52(%rcx) 21: 55 push %rbp 22: 89 e5 mov %esp,%ebp 24: 0f 34 sysenter 26: cd 80 int $0x80 * 28: 5d pop %rbp <-- trapping instruction 29: 5a pop %rdx 2a: 59 pop %rcx 2b: c3 ret 2c: 90 nop 2d: 90 nop 2e: 90 nop 2f: 90 nop 30: 8d b4 26 00 00 00 00 lea 0x0(%rsi,%riz,1),%esi 37: 8d b4 26 00 00 00 00 lea 0x0(%rsi,%riz,1),%esi