[syzbot] [io-uring?] KCSAN: data-race in io_req_defer_failed / io_wq_free_work (3)

[syzbot] [io-uring?] KCSAN: data-race in io_req_defer_failed / io_wq_free_work (3)

[syzbot]

Hello,

syzbot found the following issue on:

HEAD commit:    c964ced77262 Merge tag 'for-linus' of git://git.kernel.org..
git tree:       upstream
console output: https://syzkaller.appspot.com/x/log.txt?x=14d3cf27980000
kernel config:  https://syzkaller.appspot.com/x/.config?x=fd83253b74c9c570
dashboard link: https://syzkaller.appspot.com/bug?extid=2b8e48083b04a2e58fab
compiler:       Debian clang version 15.0.6, GNU ld (GNU Binutils for Debian) 2.40

Unfortunately, I don't have any reproducer for this issue yet.

Downloadable assets:
disk image: https://storage.googleapis.com/syzbot-assets/dc3d0edf69f7/disk-c964ced7.raw.xz
vmlinux: https://storage.googleapis.com/syzbot-assets/bb0052a85cf6/vmlinux-c964ced7.xz
kernel image: https://storage.googleapis.com/syzbot-assets/ec8def944d77/bzImage-c964ced7.xz

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+2b8e48083b04a2e58fab@syzkaller.appspotmail.com

==================================================================
BUG: KCSAN: data-race in io_req_defer_failed / io_wq_free_work

write to 0xffff888117079648 of 8 bytes by task 3752 on cpu 1:
 io_req_defer_failed+0x73/0x440 io_uring/io_uring.c:935
 io_req_task_cancel+0x21/0x30 io_uring/io_uring.c:1361
 io_handle_tw_list+0x1b9/0x200 io_uring/io_uring.c:1063
 tctx_task_work_run+0x6c/0x1b0 io_uring/io_uring.c:1135
 tctx_task_work+0x40/0x80 io_uring/io_uring.c:1153
 task_work_run+0x13a/0x1a0 kernel/task_work.c:228
 get_signal+0xee9/0x1070 kernel/signal.c:2690
 arch_do_signal_or_restart+0x95/0x4b0 arch/x86/kernel/signal.c:337
 exit_to_user_mode_loop kernel/entry/common.c:111 [inline]
 exit_to_user_mode_prepare include/linux/entry-common.h:328 [inline]
 __syscall_exit_to_user_mode_work kernel/entry/common.c:207 [inline]
 syscall_exit_to_user_mode+0x59/0x130 kernel/entry/common.c:218
 do_syscall_64+0xd6/0x1c0 arch/x86/entry/common.c:89
 entry_SYSCALL_64_after_hwframe+0x77/0x7f

read to 0xffff888117079648 of 8 bytes by task 3753 on cpu 0:
 req_ref_put_and_test io_uring/refs.h:22 [inline]
 io_wq_free_work+0x21/0x160 io_uring/io_uring.c:1779
 io_worker_handle_work+0x4cb/0x9d0 io_uring/io-wq.c:604
 io_wq_worker+0x286/0x820 io_uring/io-wq.c:655
 ret_from_fork+0x4b/0x60 arch/x86/kernel/process.c:147
 ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244

value changed: 0x00000000802c2058 -> 0x00000000806c2118

Reported by Kernel Concurrency Sanitizer on:
CPU: 0 UID: 0 PID: 3753 Comm: iou-wrk-3752 Not tainted 6.12.0-rc3-syzkaller-00087-gc964ced77262 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
==================================================================


---
This report is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzkaller@googlegroups.com.

syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

If the report is already addressed, let syzbot know by replying with:
#syz fix: exact-commit-title

If you want to overwrite report's subsystems, reply with:
#syz set subsystems: new-subsystem
(See the list of subsystem names on the web dashboard)

If the report is a duplicate of another one, reply with:
#syz dup: exact-subject-of-another-report

If you want to undo deduplication, reply with:
#syz undup

Re: [syzbot] [io-uring?] KCSAN: data-race in io_req_defer_failed / io_wq_free_work (3)

[Pavel Begunkov]

On 10/17/24 09:20, syzbot wrote:
> Hello,
> 
> syzbot found the following issue on:
> 
> HEAD commit:    c964ced77262 Merge tag 'for-linus' of git://git.kernel.org..
> git tree:       upstream
> console output: https://syzkaller.appspot.com/x/log.txt?x=14d3cf27980000
> kernel config:  https://syzkaller.appspot.com/x/.config?x=fd83253b74c9c570
> dashboard link: https://syzkaller.appspot.com/bug?extid=2b8e48083b04a2e58fab
> compiler:       Debian clang version 15.0.6, GNU ld (GNU Binutils for Debian) 2.40
> 
> Unfortunately, I don't have any reproducer for this issue yet.
> 
> Downloadable assets:
> disk image: https://storage.googleapis.com/syzbot-assets/dc3d0edf69f7/disk-c964ced7.raw.xz
> vmlinux: https://storage.googleapis.com/syzbot-assets/bb0052a85cf6/vmlinux-c964ced7.xz
> kernel image: https://storage.googleapis.com/syzbot-assets/ec8def944d77/bzImage-c964ced7.xz
> 
> IMPORTANT: if you fix the issue, please add the following tag to the commit:
> Reported-by: syzbot+2b8e48083b04a2e58fab@syzkaller.appspotmail.com

I'd assume io_req_defer_failed sets F_FAIL flag, req_ref_put_and_test()
reads F_REFCOUNT, which never changes at this point, and there are
dozens other such modifications.

The race is mild, it shouldn't be a problem unless the compiler
misbehaves,  but may make sense to add WRITE_ONCE for every
modification.


> ==================================================================
> BUG: KCSAN: data-race in io_req_defer_failed / io_wq_free_work
> 
> write to 0xffff888117079648 of 8 bytes by task 3752 on cpu 1:
>   io_req_defer_failed+0x73/0x440 io_uring/io_uring.c:935
>   io_req_task_cancel+0x21/0x30 io_uring/io_uring.c:1361
>   io_handle_tw_list+0x1b9/0x200 io_uring/io_uring.c:1063
>   tctx_task_work_run+0x6c/0x1b0 io_uring/io_uring.c:1135
>   tctx_task_work+0x40/0x80 io_uring/io_uring.c:1153
>   task_work_run+0x13a/0x1a0 kernel/task_work.c:228
>   get_signal+0xee9/0x1070 kernel/signal.c:2690
>   arch_do_signal_or_restart+0x95/0x4b0 arch/x86/kernel/signal.c:337
>   exit_to_user_mode_loop kernel/entry/common.c:111 [inline]
>   exit_to_user_mode_prepare include/linux/entry-common.h:328 [inline]
>   __syscall_exit_to_user_mode_work kernel/entry/common.c:207 [inline]
>   syscall_exit_to_user_mode+0x59/0x130 kernel/entry/common.c:218
>   do_syscall_64+0xd6/0x1c0 arch/x86/entry/common.c:89
>   entry_SYSCALL_64_after_hwframe+0x77/0x7f
> 
> read to 0xffff888117079648 of 8 bytes by task 3753 on cpu 0:
>   req_ref_put_and_test io_uring/refs.h:22 [inline]
>   io_wq_free_work+0x21/0x160 io_uring/io_uring.c:1779
>   io_worker_handle_work+0x4cb/0x9d0 io_uring/io-wq.c:604
>   io_wq_worker+0x286/0x820 io_uring/io-wq.c:655
>   ret_from_fork+0x4b/0x60 arch/x86/kernel/process.c:147
>   ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244
> 
> value changed: 0x00000000802c2058 -> 0x00000000806c2118

-- 
Pavel Begunkov