syzbot


INFO: task hung in ni_readpage_cmpr

Status: upstream: reported C repro on 2023/07/21 00:28
Subsystems: ntfs3
[Documentation on labels]
Reported-by: syzbot+9877b6999a2554291c7d@syzkaller.appspotmail.com
First crash: 285d, last: 57d
Cause bisection: failed (error log, bisect log)
  
Discussions (1)
Title Replies (including bot) Last reply
[syzbot] [ntfs3?] INFO: task hung in ni_readpage_cmpr 1 (3) 2024/01/14 13:43
Similar bugs (1)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-5.15 INFO: task hung in ni_readpage_cmpr origin:lts-only C done 2 34d 73d 0/3 upstream: reported C repro on 2024/02/13 07:32
Last patch testing requests (6)
Created Duration User Patch Repo Result
2024/02/02 08:20 23m retest repro linux-next OK log
2024/01/14 12:48 22m hdanton@sina.com https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master OK log
2023/11/10 14:38 0m retest repro linux-next error OK
2023/10/26 02:16 26m retest repro git://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci OK log
2023/09/28 14:05 22m almaz.alexandrovich@paragon-software.com https://github.com/Paragon-Software-Group/linux-ntfs3.git master OK log
2023/09/01 00:40 14m retest repro linux-next report log

Sample crash report:
INFO: task syz-executor122:5344 blocked for more than 143 seconds.
      Not tainted 6.5.0-rc1-next-20230714-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor122 state:D stack:26032 pid:5344  ppid:5028   flags:0x00004006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5380 [inline]
 __schedule+0xee1/0x59f0 kernel/sched/core.c:6709
 schedule+0xe7/0x1b0 kernel/sched/core.c:6785
 io_schedule+0xbe/0x130 kernel/sched/core.c:9027
 folio_wait_bit_common+0x3d0/0x9a0 mm/filemap.c:1304
 __folio_lock mm/filemap.c:1632 [inline]
 folio_lock include/linux/pagemap.h:974 [inline]
 folio_lock include/linux/pagemap.h:970 [inline]
 __filemap_get_folio+0x788/0x990 mm/filemap.c:1900
 pagecache_get_page+0x2c/0x270 mm/folio-compat.c:99
 find_or_create_page include/linux/pagemap.h:655 [inline]
 ni_readpage_cmpr+0x24e/0xc80 fs/ntfs3/frecord.c:2135
 ntfs_read_folio+0x107/0x1e0 fs/ntfs3/inode.c:718
 filemap_read_folio+0xe5/0x2b0 mm/filemap.c:2390
 filemap_create_folio mm/filemap.c:2518 [inline]
 filemap_get_pages+0xdd7/0x1820 mm/filemap.c:2571
 filemap_splice_read+0x3d0/0x9f0 mm/filemap.c:2927
 ntfs_file_splice_read+0x10d/0x190 fs/ntfs3/file.c:773
 vfs_splice_read fs/splice.c:993 [inline]
 vfs_splice_read+0x2c8/0x3b0 fs/splice.c:962
 splice_direct_to_actor+0x2a5/0xa30 fs/splice.c:1069
 do_splice_direct+0x1af/0x280 fs/splice.c:1194
 do_sendfile+0xb88/0x1390 fs/read_write.c:1254
 __do_sys_sendfile64 fs/read_write.c:1322 [inline]
 __se_sys_sendfile64 fs/read_write.c:1308 [inline]
 __x64_sys_sendfile64+0x1d6/0x220 fs/read_write.c:1308
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x38/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7fd9aca10e09
RSP: 002b:00007fd9ac9c5168 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 00007fd9acab1708 RCX: 00007fd9aca10e09
RDX: 0000000000000000 RSI: 0000000000000005 RDI: 0000000000000004
RBP: 00007fd9acab1700 R08: 00007fd9ac9c56c0 R09: 0000000000000000
R10: 0001000000201005 R11: 0000000000000246 R12: 00007fd9acab170c
R13: 0000000000000006 R14: 00007ffdd97961a0 R15: 00007ffdd9796288
 </TASK>
INFO: task syz-executor122:5345 blocked for more than 143 seconds.
      Not tainted 6.5.0-rc1-next-20230714-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor122 state:D stack:26448 pid:5345  ppid:5028   flags:0x00004006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5380 [inline]
 __schedule+0xee1/0x59f0 kernel/sched/core.c:6709
 schedule+0xe7/0x1b0 kernel/sched/core.c:6785
 io_schedule+0xbe/0x130 kernel/sched/core.c:9027
 folio_wait_bit_common+0x3d0/0x9a0 mm/filemap.c:1304
 __folio_lock mm/filemap.c:1632 [inline]
 folio_lock include/linux/pagemap.h:974 [inline]
 folio_lock include/linux/pagemap.h:970 [inline]
 __filemap_get_folio+0x788/0x990 mm/filemap.c:1900
 pagecache_get_page+0x2c/0x270 mm/folio-compat.c:99
 find_or_create_page include/linux/pagemap.h:655 [inline]
 ntfs_get_frame_pages+0x132/0x640 fs/ntfs3/file.c:793
 ntfs_compress_write fs/ntfs3/file.c:943 [inline]
 ntfs_file_write_iter+0xfdc/0x1c10 fs/ntfs3/file.c:1080
 call_write_iter include/linux/fs.h:1916 [inline]
 do_iter_readv_writev+0x21e/0x3c0 fs/read_write.c:735
 do_iter_write+0x17f/0x830 fs/read_write.c:860
 vfs_iter_write+0x7a/0xb0 fs/read_write.c:901
 iter_file_splice_write+0x698/0xbf0 fs/splice.c:738
 do_splice_from fs/splice.c:933 [inline]
 direct_splice_actor+0x118/0x180 fs/splice.c:1142
 splice_direct_to_actor+0x347/0xa30 fs/splice.c:1088
 do_splice_direct+0x1af/0x280 fs/splice.c:1194
 do_sendfile+0xb88/0x1390 fs/read_write.c:1254
 __do_sys_sendfile64 fs/read_write.c:1322 [inline]
 __se_sys_sendfile64 fs/read_write.c:1308 [inline]
 __x64_sys_sendfile64+0x1d6/0x220 fs/read_write.c:1308
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x38/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7fd9aca10e09
RSP: 002b:00007fd9a47a4168 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 00007fd9acab1718 RCX: 00007fd9aca10e09
RDX: 0000000000000000 RSI: 0000000000000007 RDI: 0000000000000006
RBP: 00007fd9acab1710 R08: 00007fd9a47a46c0 R09: 0000000000000000
R10: 0001000000201005 R11: 0000000000000246 R12: 00007fd9acab171c
R13: 000000000000006e R14: 00007ffdd97961a0 R15: 00007ffdd9796288
 </TASK>

Showing all locks held in the system:
1 lock held by rcu_tasks_kthre/13:
 #0: ffffffff8c9a5cb0 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x2c/0xef0 kernel/rcu/tasks.h:571
1 lock held by rcu_tasks_trace/14:
 #0: ffffffff8c9a5970 (rcu_tasks_trace.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x2c/0xef0 kernel/rcu/tasks.h:571
1 lock held by khungtaskd/28:
 #0: ffffffff8c9a68c0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x55/0x340 kernel/locking/lockdep.c:6615
5 locks held by kworker/u4:4/57:
2 locks held by getty/4784:
 #0: ffff88814ac0c098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x24/0x80 drivers/tty/tty_ldisc.c:243
 #1: ffffc900015b72f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0xfcb/0x1480 drivers/tty/n_tty.c:2187
3 locks held by syz-executor122/5344:
 #0: ffff88807da84410 (sb_writers#10){.+.+}-{0:0}, at: __do_sys_sendfile64 fs/read_write.c:1322 [inline]
 #0: ffff88807da84410 (sb_writers#10){.+.+}-{0:0}, at: __se_sys_sendfile64 fs/read_write.c:1308 [inline]
 #0: ffff88807da84410 (sb_writers#10){.+.+}-{0:0}, at: __x64_sys_sendfile64+0x1d6/0x220 fs/read_write.c:1308
 #1: ffff888072bebd88 (mapping.invalidate_lock#3){++++}-{3:3}, at: filemap_invalidate_lock_shared include/linux/fs.h:826 [inline]
 #1: ffff888072bebd88 (mapping.invalidate_lock#3){++++}-{3:3}, at: filemap_create_folio mm/filemap.c:2510 [inline]
 #1: ffff888072bebd88 (mapping.invalidate_lock#3){++++}-{3:3}, at: filemap_get_pages+0xd14/0x1820 mm/filemap.c:2571
 #2: ffff888072beb900 (&ni->ni_lock/4){+.+.}-{3:3}, at: ni_lock fs/ntfs3/ntfs_fs.h:1124 [inline]
 #2: ffff888072beb900 (&ni->ni_lock/4){+.+.}-{3:3}, at: ntfs_read_folio+0xfc/0x1e0 fs/ntfs3/inode.c:717
2 locks held by syz-executor122/5345:
 #0: ffff88807da84410 (sb_writers#10){.+.+}-{0:0}, at: __do_sys_sendfile64 fs/read_write.c:1322 [inline]
 #0: ffff88807da84410 (sb_writers#10){.+.+}-{0:0}, at: __se_sys_sendfile64 fs/read_write.c:1308 [inline]
 #0: ffff88807da84410 (sb_writers#10){.+.+}-{0:0}, at: __x64_sys_sendfile64+0x1d6/0x220 fs/read_write.c:1308
 #1: ffff888072bebbd0 (&sb->s_type->i_mutex_key#15){+.+.}-{3:3}, at: inode_lock include/linux/fs.h:771 [inline]
 #1: ffff888072bebbd0 (&sb->s_type->i_mutex_key#15){+.+.}-{3:3}, at: ntfs_file_write_iter+0x296/0x1c10 fs/ntfs3/file.c:1063

=============================================

NMI backtrace for cpu 1
CPU: 1 PID: 28 Comm: khungtaskd Not tainted 6.5.0-rc1-next-20230714-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/03/2023
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0xd9/0x1b0 lib/dump_stack.c:106
 nmi_cpu_backtrace+0x277/0x380 lib/nmi_backtrace.c:113
 nmi_trigger_cpumask_backtrace+0x2ac/0x310 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:160 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:222 [inline]
 watchdog+0xf29/0x11b0 kernel/hung_task.c:379
 kthread+0x33a/0x430 kernel/kthread.c:389
 ret_from_fork+0x2c/0x70 arch/x86/kernel/process.c:145
 ret_from_fork_asm+0x11/0x20 arch/x86/entry/entry_64.S:296
RIP: 0000:0x0
Code: Unable to access opcode bytes at 0xffffffffffffffd6.
RSP: 0000:0000000000000000 EFLAGS: 00000000 ORIG_RAX: 0000000000000000
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
 </TASK>
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 57 Comm: kworker/u4:4 Not tainted 6.5.0-rc1-next-20230714-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/03/2023
Workqueue: events_unbound toggle_allocation_gate
RIP: 0010:__mutex_lock_common kernel/locking/mutex.c:602 [inline]
RIP: 0010:__mutex_lock+0x14f/0x1340 kernel/locking/mutex.c:747
Code: 29 49 8d 7e 60 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 0f 85 00 0d 00 00 4d 3b 76 60 0f 85 67 07 00 00 <bf> 01 00 00 00 e8 27 ae 23 f7 49 8d 46 68 31 d2 4d 89 e1 48 89 85
RSP: 0018:ffffc90001587aa0 EFLAGS: 00000246
RAX: dffffc0000000000 RBX: 0000000000000000 RCX: 0000000000000001
RDX: 1ffffffff1954708 RSI: ffffffff8a6c7cc0 RDI: ffffffff8caa3840
RBP: ffffc90001587bf0 R08: ffffffff81b25fbb R09: 0000000000000001
R10: ffffc90001587c08 R11: 0000000000000000 R12: 0000000000000000
R13: dffffc0000000000 R14: ffffffff8caa37e0 R15: ffffffff9216b4c0
FS:  0000000000000000(0000) GS:ffff8880b9800000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000056410262d680 CR3: 000000000c776000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>
 <TASK>
 jump_label_lock kernel/jump_label.c:27 [inline]
 static_key_disable_cpuslocked+0x10b/0x1b0 kernel/jump_label.c:233
 static_key_disable+0x1a/0x20 kernel/jump_label.c:243
 toggle_allocation_gate mm/kfence/core.c:836 [inline]
 toggle_allocation_gate+0x13f/0x250 mm/kfence/core.c:823
 process_one_work+0xaa2/0x16f0 kernel/workqueue.c:2600
 worker_thread+0x687/0x1110 kernel/workqueue.c:2751
 kthread+0x33a/0x430 kernel/kthread.c:389
 ret_from_fork+0x2c/0x70 arch/x86/kernel/process.c:145
 ret_from_fork_asm+0x11/0x20 arch/x86/entry/entry_64.S:296
RIP: 0000:0x0
Code: Unable to access opcode bytes at 0xffffffffffffffd6.
RSP: 0000:0000000000000000 EFLAGS: 00000000 ORIG_RAX: 0000000000000000
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
 </TASK>

Crashes (5):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2023/07/17 00:17 linux-next 7c2878be5732 35d9ecc5 .config strace log report syz C [disk image] [vmlinux] [kernel image] [mounted in repro] ci-upstream-linux-next-kasan-gce-root INFO: task hung in ni_readpage_cmpr
2023/10/12 01:33 git://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci 19af4a4ed414 83165b57 .config console log report syz C [disk image] [vmlinux] [kernel image] [mounted in repro] ci-upstream-gce-arm64 INFO: task hung in ni_readpage_cmpr
2024/03/01 00:31 upstream 805d849d7c3c 352ab904 .config console log report info [disk image] [vmlinux] [kernel image] ci2-upstream-fs INFO: task hung in ni_readpage_cmpr
2024/01/19 00:52 git://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci 0802e17d9aca 239abf84 .config console log report info [disk image] [vmlinux] [kernel image] ci-upstream-gce-arm64 INFO: task hung in ni_readpage_cmpr
2023/07/16 19:40 linux-next 7c2878be5732 35d9ecc5 .config console log report info [disk image] [vmlinux] [kernel image] ci-upstream-linux-next-kasan-gce-root INFO: task hung in ni_readpage_cmpr
* Struck through repros no longer work on HEAD.