syzbot


INFO: task hung in do_sys_ftruncate

Status: auto-closed as invalid on 2020/02/19 13:16
Subsystems: exfat
[Documentation on labels]
First crash: 1610d, last: 1610d

Sample crash report:
INFO: task syz-executor.4:9155 blocked for more than 143 seconds.
      Not tainted 5.4.0-rc8-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D27984  9155   8010 0x00004004
Call Trace:
 context_switch kernel/sched/core.c:3384 [inline]
 __schedule+0x7b7/0xc30 kernel/sched/core.c:4078
 schedule+0x131/0x1e0 kernel/sched/core.c:4145
 rwsem_down_write_slowpath+0x823/0xde0 kernel/locking/rwsem.c:1238
 __down_write kernel/locking/rwsem.c:1392 [inline]
 down_write+0x12b/0x140 kernel/locking/rwsem.c:1535
 inode_lock include/linux/fs.h:791 [inline]
 do_truncate fs/open.c:62 [inline]
 do_sys_ftruncate+0x5b8/0x710 fs/open.c:195
 __do_sys_ftruncate fs/open.c:205 [inline]
 __se_sys_ftruncate fs/open.c:203 [inline]
 __x64_sys_ftruncate+0x60/0x70 fs/open.c:203
 do_syscall_64+0xf7/0x1c0 arch/x86/entry/common.c:290
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45a639
Code: Bad RIP value.
RSP: 002b:00007fc2d6131c78 EFLAGS: 00000246 ORIG_RAX: 000000000000004d
RAX: ffffffffffffffda RBX: 0000000000000002 RCX: 000000000045a639
RDX: 0000000000000000 RSI: 0000000000208200 RDI: 0000000000000007
RBP: 000000000075bfc8 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007fc2d61326d4
R13: 00000000004c12c6 R14: 00000000004d40c8 R15: 00000000ffffffff
INFO: task syz-executor.4:9157 blocked for more than 143 seconds.
      Not tainted 5.4.0-rc8-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D23800  9157   8010 0x00004004
Call Trace:
 context_switch kernel/sched/core.c:3384 [inline]
 __schedule+0x7b7/0xc30 kernel/sched/core.c:4078
 schedule+0x131/0x1e0 kernel/sched/core.c:4145
 io_schedule+0x8b/0x100 kernel/sched/core.c:5789
 wait_on_page_bit_common+0x918/0xc90 mm/filemap.c:1175
 wait_on_page_bit+0x46/0x50 mm/filemap.c:1224
 wait_on_page_writeback+0x13e/0x180 mm/page-writeback.c:2822
 __filemap_fdatawait_range+0x1b5/0x3b0 mm/filemap.c:526
 file_write_and_wait_range+0x141/0x1a0 mm/filemap.c:786
 __generic_file_fsync+0x76/0x1a0 fs/libfs.c:1000
 fat_file_fsync+0x7c/0x190 fs/fat/file.c:199
 vfs_fsync_range+0xfd/0x1a0 fs/sync.c:197
 generic_write_sync include/linux/fs.h:2853 [inline]
 generic_file_write_iter+0x575/0x630 mm/filemap.c:3470
 do_iter_readv_writev+0x651/0x8e0 include/linux/fs.h:1889
 do_iter_write+0x180/0x590 fs/read_write.c:970
 vfs_iter_write+0x7c/0xa0 fs/read_write.c:983
 iter_file_splice_write+0x6d8/0xe20 fs/splice.c:746
 do_splice_from fs/splice.c:848 [inline]
 direct_splice_actor+0xf7/0x130 fs/splice.c:1020
 splice_direct_to_actor+0x463/0xac0 fs/splice.c:975
 do_splice_direct+0x200/0x330 fs/splice.c:1063
 do_sendfile+0x7e4/0xfd0 fs/read_write.c:1464
 __do_sys_sendfile64 fs/read_write.c:1519 [inline]
 __se_sys_sendfile64 fs/read_write.c:1511 [inline]
 __x64_sys_sendfile64+0xfc/0x1b0 fs/read_write.c:1511
 do_syscall_64+0xf7/0x1c0 arch/x86/entry/common.c:290
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45a639
Code: Bad RIP value.
RSP: 002b:00007fc2d6110c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 000000000045a639
RDX: 0000000020000000 RSI: 0000000000000004 RDI: 0000000000000004
RBP: 000000000075c070 R08: 0000000000000000 R09: 0000000000000000
R10: 00008080fffffffe R11: 0000000000000246 R12: 00007fc2d61116d4
R13: 00000000004c83bf R14: 00000000004de7f0 R15: 00000000ffffffff
INFO: task syz-executor.4:9195 blocked for more than 143 seconds.
      Not tainted 5.4.0-rc8-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D27472  9195   8010 0x00004004
Call Trace:
 context_switch kernel/sched/core.c:3384 [inline]
 __schedule+0x7b7/0xc30 kernel/sched/core.c:4078
 schedule+0x131/0x1e0 kernel/sched/core.c:4145
 rwsem_down_write_slowpath+0x823/0xde0 kernel/locking/rwsem.c:1238
 __down_write kernel/locking/rwsem.c:1392 [inline]
 down_write+0x12b/0x140 kernel/locking/rwsem.c:1535
 inode_lock include/linux/fs.h:791 [inline]
 process_measurement+0x2d4/0x1800 security/integrity/ima/ima_main.c:230
 ima_file_check+0x9b/0xe0 security/integrity/ima/ima_main.c:438
 do_last fs/namei.c:3412 [inline]
 path_openat+0x1760/0x4420 fs/namei.c:3525
 do_filp_open+0x192/0x3d0 fs/namei.c:3555
 do_sys_open+0x29f/0x560 fs/open.c:1097
 __do_sys_open fs/open.c:1115 [inline]
 __se_sys_open fs/open.c:1110 [inline]
 __x64_sys_open+0x87/0x90 fs/open.c:1110
 do_syscall_64+0xf7/0x1c0 arch/x86/entry/common.c:290
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45a639
Code: Bad RIP value.
RSP: 002b:00007fc2d60efc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a639
RDX: 0000000000000001 RSI: 0000000000000000 RDI: 0000000020000000
RBP: 000000000075c118 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007fc2d60f06d4
R13: 00000000004fb41f R14: 00000000004dccc0 R15: 00000000ffffffff
INFO: task syz-executor.4:9198 blocked for more than 143 seconds.
      Not tainted 5.4.0-rc8-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.4  D27528  9198   8010 0x00004004
Call Trace:
 context_switch kernel/sched/core.c:3384 [inline]
 __schedule+0x7b7/0xc30 kernel/sched/core.c:4078
 schedule+0x131/0x1e0 kernel/sched/core.c:4145
 io_schedule+0x8b/0x100 kernel/sched/core.c:5789
 wait_on_page_bit_common+0x918/0xc90 mm/filemap.c:1175
 wait_on_page_bit+0x46/0x50 mm/filemap.c:1224
 wait_on_page_writeback+0x13e/0x180 mm/page-writeback.c:2822
 truncate_inode_pages_range+0x119b/0x16e0 mm/truncate.c:448
 truncate_inode_pages mm/truncate.c:476 [inline]
 truncate_pagecache mm/truncate.c:817 [inline]
 truncate_setsize+0xd9/0x100 mm/truncate.c:842
 fat_setattr+0x8d4/0xd60 fs/fat/file.c:547
 notify_change+0xa27/0xe70 fs/attr.c:337
 do_truncate+0x194/0x230 fs/open.c:64
 handle_truncate fs/namei.c:2998 [inline]
 do_last fs/namei.c:3414 [inline]
 path_openat+0x2fe3/0x4420 fs/namei.c:3525
 do_filp_open+0x192/0x3d0 fs/namei.c:3555
 do_sys_open+0x29f/0x560 fs/open.c:1097
 ksys_open include/linux/syscalls.h:1385 [inline]
 __do_sys_creat fs/open.c:1155 [inline]
 __se_sys_creat fs/open.c:1153 [inline]
 __x64_sys_creat+0x65/0x70 fs/open.c:1153
 do_syscall_64+0xf7/0x1c0 arch/x86/entry/common.c:290
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x45a639
Code: Bad RIP value.
RSP: 002b:00007fc2d60cec78 EFLAGS: 00000246 ORIG_RAX: 0000000000000055
RAX: ffffffffffffffda RBX: 0000000000000002 RCX: 000000000045a639
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000700
RBP: 000000000075c1c0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007fc2d60cf6d4
R13: 00000000004c0ee8 R14: 00000000004d3960 R15: 00000000ffffffff

Showing all locks held in the system:
1 lock held by khungtaskd/1072:
 #0: ffffffff888d3e40 (rcu_read_lock){....}, at: rcu_lock_acquire+0x4/0x30 include/linux/rcupdate.h:207
2 locks held by rsyslogd/7871:
 #0: ffff88808811eda0 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x243/0x2e0 fs/file.c:801
 #1: ffff8880aa401818 (&(&parent->list_lock)->rlock){-.-.}, at: syslog_print kernel/printk/printk.c:1364 [inline]
 #1: ffff8880aa401818 (&(&parent->list_lock)->rlock){-.-.}, at: do_syslog+0x12b2/0x1740 kernel/printk/printk.c:1529
2 locks held by getty/7961:
 #0: ffff88809442b090 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:272
 #1: ffffc90005f252e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x221/0x1b00 drivers/tty/n_tty.c:2156
2 locks held by getty/7962:
 #0: ffff888089f40090 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:272
 #1: ffffc90005f1d2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x221/0x1b00 drivers/tty/n_tty.c:2156
2 locks held by getty/7963:
 #0: ffff88809c88a090 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:272
 #1: ffffc90005f2d2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x221/0x1b00 drivers/tty/n_tty.c:2156
2 locks held by getty/7964:
 #0: ffff88809456b090 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:272
 #1: ffffc90005f392e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x221/0x1b00 drivers/tty/n_tty.c:2156
2 locks held by getty/7965:
 #0: ffff888087185090 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:272
 #1: ffffc90005f312e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x221/0x1b00 drivers/tty/n_tty.c:2156
2 locks held by getty/7966:
 #0: ffff888096017090 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:272
 #1: ffffc90005f352e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x221/0x1b00 drivers/tty/n_tty.c:2156
2 locks held by getty/7967:
 #0: ffff88808ab25090 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:272
 #1: ffffc90005f092e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x221/0x1b00 drivers/tty/n_tty.c:2156
2 locks held by syz-executor.4/9155:
 #0: ffff8880a1fdc428 (sb_writers#17){.+.+}, at: sb_start_write include/linux/fs.h:1650 [inline]
 #0: ffff8880a1fdc428 (sb_writers#17){.+.+}, at: do_sys_ftruncate+0x2f7/0x710 fs/open.c:190
 #1: ffff8880a2aa4318 (&sb->s_type->i_mutex_key#24){+.+.}, at: inode_lock include/linux/fs.h:791 [inline]
 #1: ffff8880a2aa4318 (&sb->s_type->i_mutex_key#24){+.+.}, at: do_truncate fs/open.c:62 [inline]
 #1: ffff8880a2aa4318 (&sb->s_type->i_mutex_key#24){+.+.}, at: do_sys_ftruncate+0x5b8/0x710 fs/open.c:195
1 lock held by syz-executor.4/9157:
 #0: ffff8880a1fdc428 (sb_writers#17){.+.+}, at: file_start_write include/linux/fs.h:2882 [inline]
 #0: ffff8880a1fdc428 (sb_writers#17){.+.+}, at: do_sendfile+0x7c2/0xfd0 fs/read_write.c:1463
1 lock held by syz-executor.4/9195:
 #0: ffff8880a2aa4318 (&sb->s_type->i_mutex_key#24){+.+.}, at: inode_lock include/linux/fs.h:791 [inline]
 #0: ffff8880a2aa4318 (&sb->s_type->i_mutex_key#24){+.+.}, at: process_measurement+0x2d4/0x1800 security/integrity/ima/ima_main.c:230
3 locks held by syz-executor.4/9198:
 #0: ffff8880a1fdc428 (sb_writers#17){.+.+}, at: sb_start_write include/linux/fs.h:1650 [inline]
 #0: ffff8880a1fdc428 (sb_writers#17){.+.+}, at: mnt_want_write+0x4a/0xa0 fs/namespace.c:354
 #1: ffff8880a2aa4318 (&sb->s_type->i_mutex_key#24){+.+.}, at: inode_lock include/linux/fs.h:791 [inline]
 #1: ffff8880a2aa4318 (&sb->s_type->i_mutex_key#24){+.+.}, at: do_truncate+0x186/0x230 fs/open.c:62
 #2: ffff8880a2aa41b8 (&ei->truncate_lock){+.+.}, at: fat_setattr+0x8b9/0xd60 fs/fat/file.c:546

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

NMI backtrace for cpu 1
CPU: 1 PID: 1072 Comm: khungtaskd Not tainted 5.4.0-rc8-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x1fb/0x318 lib/dump_stack.c:118
 nmi_cpu_backtrace+0xaf/0x1a0 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x174/0x290 lib/nmi_backtrace.c:62
 arch_trigger_cpumask_backtrace+0x10/0x20 arch/x86/kernel/apic/hw_nmi.c:38
 trigger_all_cpu_backtrace+0x17/0x20 include/linux/nmi.h:146
 check_hung_uninterruptible_tasks kernel/hung_task.c:205 [inline]
 watchdog+0xbb9/0xbd0 kernel/hung_task.c:289
 kthread+0x332/0x350 kernel/kthread.c:255
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0 skipped: idling at native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:60

Crashes (2):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2019/11/21 13:09 upstream c74386d50fba 8098ea0f .config console log report ci-upstream-kasan-gce-smack-root
2019/11/21 12:12 upstream c74386d50fba 8098ea0f .config console log report ci-upstream-kasan-gce-smack-root
* Struck through repros no longer work on HEAD.