syzbot


INFO: task hung in ext4_orphan_del

Status: auto-closed as invalid on 2019/03/09 03:11
First crash: 2052d, last: 2052d

Sample crash report:
Free swap  = 0kB
Total swap = 0kB
1965979 pages RAM
0 pages HighMem/MovableOnly
313152 pages reserved
INFO: task syz-executor0:6670 blocked for more than 140 seconds.
      Not tainted 4.9.125+ #37
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor0   D28104  6670   2106 0x00000000
 ffff88019cf15f00 ffff88019c57e300 ffff88019d1e8580 ffff8801cacd8000
 ffff8801db621018 ffff8801d02775d8 ffffffff8277d092 0000000000000002
 ffff88019cf167b0 ffffed00339e2cf5 00ff88019cf15f00 ffff8801db6218f0
Call Trace:
 [<ffffffff8277e5bf>] schedule+0x7f/0x1b0 kernel/sched/core.c:3553
 [<ffffffff8277ef43>] schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3586
 [<ffffffff82780bc6>] __mutex_lock_common kernel/locking/mutex.c:582 [inline]
 [<ffffffff82780bc6>] mutex_lock_nested+0x326/0x870 kernel/locking/mutex.c:621
 [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
 [<ffffffff816b94ca>] ext4_evict_inode+0x72a/0x1230 fs/ext4/inode.c:296
 [<ffffffff81541e3e>] evict+0x22e/0x4f0 fs/inode.c:553
 [<ffffffff81542f31>] iput_final fs/inode.c:1516 [inline]
 [<ffffffff81542f31>] iput+0x371/0x900 fs/inode.c:1543
 [<ffffffff81530175>] dentry_unlink_inode+0x265/0x320 fs/dcache.c:367
 [<ffffffff815338f0>] __dentry_kill+0x280/0x4c0 fs/dcache.c:569
 [<ffffffff81534b67>] dentry_kill fs/dcache.c:610 [inline]
 [<ffffffff81534b67>] dput.part.9+0x4f7/0x7a0 fs/dcache.c:827
 [<ffffffff81534e2f>] dput+0x1f/0x30 fs/dcache.c:789
 [<ffffffff815092a1>] path_put+0x31/0x70 fs/namei.c:516
 [<ffffffff8158fba2>] free_fs_struct+0x22/0x60 fs/fs_struct.c:90
 [<ffffffff815907f9>] exit_fs+0xe9/0x120 fs/fs_struct.c:108
 [<ffffffff810e3c91>] do_exit+0x761/0x2750 kernel/exit.c:829
 [<ffffffff810ea111>] do_group_exit+0x111/0x300 kernel/exit.c:937
 [<ffffffff8110b0a1>] get_signal+0x4e1/0x1460 kernel/signal.c:2321
 [<ffffffff81051225>] do_signal+0x95/0x1b00 arch/x86/kernel/signal.c:807
 [<ffffffff81003e2e>] exit_to_usermode_loop+0x10e/0x150 arch/x86/entry/common.c:157
 [<ffffffff8100570d>] prepare_exit_to_usermode arch/x86/entry/common.c:191 [inline]
 [<ffffffff8100570d>] syscall_return_slowpath arch/x86/entry/common.c:260 [inline]
 [<ffffffff8100570d>] do_syscall_64+0x35d/0x480 arch/x86/entry/common.c:287
 [<ffffffff8278c193>] entry_SYSCALL_64_after_swapgs+0x5d/0xdb

Showing all locks held in the system:
2 locks held by khungtaskd/24:
 #0:  (rcu_read_lock){......}, at: [<ffffffff81309abc>] check_hung_uninterruptible_tasks kernel/hung_task.c:168 [inline]
 #0:  (rcu_read_lock){......}, at: [<ffffffff81309abc>] watchdog+0x11c/0xa20 kernel/hung_task.c:239
 #1:  (tasklist_lock){.+.+..}, at: [<ffffffff813e68fc>] debug_show_all_locks+0x79/0x218 kernel/locking/lockdep.c:4336
2 locks held by cron/1949:
 #0:  (&mm->mmap_sem){++++++}, at: [<ffffffff810b0b32>] __do_page_fault+0x272/0xa60 arch/x86/mm/fault.c:1337
 #1:  (&ei->i_mmap_sem){++++++}, at: [<ffffffff816bd5d6>] ext4_filemap_fault+0x66/0xa0 fs/ext4/inode.c:5852
2 locks held by getty/2025:
 #0:  (&tty->ldisc_sem){++++++}, at: [<ffffffff8278a262>] ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:367
 #1:  (&ldata->atomic_read_lock){+.+.+.}, at: [<ffffffff81cdd442>] n_tty_read+0x202/0x16e0 drivers/tty/n_tty.c:2142
2 locks held by syz-fuzzer/2044:
 #0:  (&mm->mmap_sem){++++++}, at: [<ffffffff810b0b32>] __do_page_fault+0x272/0xa60 arch/x86/mm/fault.c:1337
 #1:  (&ei->i_mmap_sem){++++++}, at: [<ffffffff816bd5d6>] ext4_filemap_fault+0x66/0xa0 fs/ext4/inode.c:5852
3 locks held by syz-executor0/6670:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
 #1:  (jbd2_handle){++++-.}, at: [<ffffffff817b96e3>] start_this_handle+0x443/0xe40 fs/jbd2/transaction.c:387
 #2:  (&sbi->s_orphan_lock){+.+...}, at: [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
3 locks held by syz-executor0/6810:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
 #1:  (jbd2_handle){++++-.}, at: [<ffffffff817b96e3>] start_this_handle+0x443/0xe40 fs/jbd2/transaction.c:387
 #2:  (&sbi->s_orphan_lock){+.+...}, at: [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
3 locks held by syz-executor0/6921:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
 #1:  (jbd2_handle){++++-.}, at: [<ffffffff817b96e3>] start_this_handle+0x443/0xe40 fs/jbd2/transaction.c:387
 #2:  (&sbi->s_orphan_lock){+.+...}, at: [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
3 locks held by syz-executor0/6929:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
 #1:  (jbd2_handle){++++-.}, at: [<ffffffff817b96e3>] start_this_handle+0x443/0xe40 fs/jbd2/transaction.c:387
 #2:  (&sbi->s_orphan_lock){+.+...}, at: [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
3 locks held by syz-executor0/7068:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
 #1:  (jbd2_handle){++++-.}, at: [<ffffffff817b96e3>] start_this_handle+0x443/0xe40 fs/jbd2/transaction.c:387
 #2:  (&sbi->s_orphan_lock){+.+...}, at: [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
3 locks held by syz-executor0/7366:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
 #1:  (jbd2_handle){++++-.}, at: [<ffffffff817b96e3>] start_this_handle+0x443/0xe40 fs/jbd2/transaction.c:387
 #2:  (&sbi->s_orphan_lock){+.+...}, at: [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
3 locks held by syz-executor0/7413:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
 #1:  (jbd2_handle){++++-.}, at: [<ffffffff817b96e3>] start_this_handle+0x443/0xe40 fs/jbd2/transaction.c:387
 #2:  (&sbi->s_orphan_lock){+.+...}, at: [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
1 lock held by syz-executor0/7507:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
3 locks held by syz-executor0/7572:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
 #1:  (jbd2_handle){++++-.}, at: [<ffffffff817b96e3>] start_this_handle+0x443/0xe40 fs/jbd2/transaction.c:387
 #2:  (&sbi->s_orphan_lock){+.+...}, at: [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
3 locks held by syz-executor0/7604:
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] sb_start_intwrite include/linux/fs.h:1620 [inline]
 #0:  (sb_internal){.+.+..}, at: [<ffffffff816b919d>] ext4_evict_inode+0x3fd/0x1230 fs/ext4/inode.c:240
 #1:  (jbd2_handle){++++-.}, at: [<ffffffff817b96e3>] start_this_handle+0x443/0xe40 fs/jbd2/transaction.c:387
 #2:  (&sbi->s_orphan_lock){+.+...}, at: [<ffffffff816da44c>] ext4_orphan_del+0x21c/0x7b0 fs/ext4/namei.c:2816
2 locks held by getty/7640:
 #0:  (&mm->mmap_sem){++++++}, at: [<ffffffff810b0b32>] __do_page_fault+0x272/0xa60 arch/x86/mm/fault.c:1337
 #1:  (&ei->i_mmap_sem){++++++}, at: [<ffffffff816bd5d6>] ext4_filemap_fault+0x66/0xa0 fs/ext4/inode.c:5852
2 locks held by getty/7642:
 #0:  (&mm->mmap_sem){++++++}, at: [<ffffffff810b0b32>] __do_page_fault+0x272/0xa60 arch/x86/mm/fault.c:1337
 #1:  (&ei->i_mmap_sem){++++++}, at: [<ffffffff816bd5d6>] ext4_filemap_fault+0x66/0xa0 fs/ext4/inode.c:5852

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

NMI backtrace for cpu 1
CPU: 1 PID: 24 Comm: khungtaskd Not tainted 4.9.125+ #37
 ffff8801d9907d08 ffffffff81af0ae9 0000000000000000 0000000000000001
 0000000000000001 0000000000000001 ffffffff810967d0 ffff8801d9907d40
 ffffffff81afb849 0000000000000001 0000000000000000 0000000000000003
Call Trace:
 [<ffffffff81af0ae9>] __dump_stack lib/dump_stack.c:15 [inline]
 [<ffffffff81af0ae9>] dump_stack+0xc1/0x128 lib/dump_stack.c:51
 [<ffffffff81afb849>] nmi_cpu_backtrace.cold.0+0x48/0x87 lib/nmi_backtrace.c:99
 [<ffffffff81afb7dc>] nmi_trigger_cpumask_backtrace+0x12c/0x151 lib/nmi_backtrace.c:60
 [<ffffffff810968d4>] arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:37
 [<ffffffff8130a04d>] trigger_all_cpu_backtrace include/linux/nmi.h:58 [inline]
 [<ffffffff8130a04d>] check_hung_task kernel/hung_task.c:125 [inline]
 [<ffffffff8130a04d>] check_hung_uninterruptible_tasks kernel/hung_task.c:182 [inline]
 [<ffffffff8130a04d>] watchdog+0x6ad/0xa20 kernel/hung_task.c:239
 [<ffffffff8113d9ad>] kthread+0x26d/0x300 kernel/kthread.c:211
 [<ffffffff8278c35c>] ret_from_fork+0x5c/0x70 arch/x86/entry/entry_64.S:373
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 471 Comm: udevd Not tainted 4.9.125+ #37
task: ffff8801d3a9af80 task.stack: ffff8801d3070000
RIP: 0010:[<ffffffff8120ceb3>] c [<ffffffff8120ceb3>] do_raw_spin_unlock+0x13/0x1d0 kernel/locking/spinlock_debug.c:133
RSP: 0018:ffff8801d3077498  EFLAGS: 00000287
RAX: dffffc0000000000 RBX: ffff8801d52e9f80 RCX: 0000000000000004
RDX: 0000000000000000 RSI: 0000000000000004 RDI: ffff8801d52e9f80
RBP: ffff8801d30774b0 R08: ffff8801d3a9b8f0 R09: 0000000000000001
R10: 0000000000000000 R11: 0000000000000001 R12: dffffc0000000000
R13: ffff8801d68c61c0 R14: ffff8801d52e97c0 R15: 0000000000000800
FS:  00007fb4acf5f7a0(0000) GS:ffff8801db600000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000000041afe0 CR3: 00000001d3bfd000 CR4: 00000000001606b0
DR0: 0000000020000040 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000600
Stack:
 ffff8801d52e9f80c dffffc0000000000c ffff8801d68c61c0c ffff8801d30774c8c
 ffffffff8278b802c ffff8801d52e97c0c ffff8801d3077578c ffffffff82178496c
 ffffffff8217819ec ffff8801d3077520c ffffffff81b54b5bc 0000000000000246c
Call Trace:
 [<ffffffff8278b802>] __raw_spin_unlock include/linux/spinlock_api_smp.h:153 [inline]
 [<ffffffff8278b802>] _raw_spin_unlock+0x22/0x50 kernel/locking/spinlock.c:183
 [<ffffffff82178496>] spin_unlock include/linux/spinlock.h:347 [inline]
 [<ffffffff82178496>] task_unlock include/linux/sched.h:3262 [inline]
 [<ffffffff82178496>] lowmem_scan+0x546/0xaf0 drivers/staging/android/lowmemorykiller.c:146
 [<ffffffff8142f366>] do_shrink_slab mm/vmscan.c:398 [inline]
 [<ffffffff8142f366>] shrink_slab.part.8+0x3c6/0xa00 mm/vmscan.c:501
 [<ffffffff81439e9d>] shrink_slab mm/vmscan.c:465 [inline]
 [<ffffffff81439e9d>] shrink_node+0x1ed/0x740 mm/vmscan.c:2602
 [<ffffffff8143a767>] shrink_zones mm/vmscan.c:2749 [inline]
 [<ffffffff8143a767>] do_try_to_free_pages mm/vmscan.c:2791 [inline]
 [<ffffffff8143a767>] try_to_free_pages+0x377/0xb80 mm/vmscan.c:3002
 [<ffffffff8140f9f1>] __perform_reclaim mm/page_alloc.c:3324 [inline]
 [<ffffffff8140f9f1>] __alloc_pages_direct_reclaim mm/page_alloc.c:3345 [inline]
 [<ffffffff8140f9f1>] __alloc_pages_slowpath mm/page_alloc.c:3697 [inline]
 [<ffffffff8140f9f1>] __alloc_pages_nodemask+0x981/0x1bd0 mm/page_alloc.c:3862
 [<ffffffff813fdc31>] __alloc_pages include/linux/gfp.h:433 [inline]
 [<ffffffff813fdc31>] __alloc_pages_node include/linux/gfp.h:446 [inline]
 [<ffffffff813fdc31>] alloc_pages_node include/linux/gfp.h:460 [inline]
 [<ffffffff813fdc31>] __page_cache_alloc include/linux/pagemap.h:208 [inline]
 [<ffffffff813fdc31>] page_cache_read mm/filemap.c:2007 [inline]
 [<ffffffff813fdc31>] filemap_fault+0xaf1/0x1110 mm/filemap.c:2192
 [<ffffffff816bd5e1>] ext4_filemap_fault+0x71/0xa0 fs/ext4/inode.c:5853
 [<ffffffff814759a3>] __do_fault+0x223/0x500 mm/memory.c:2842
 [<ffffffff81486086>] do_read_fault mm/memory.c:3189 [inline]
 [<ffffffff81486086>] do_fault mm/memory.c:3324 [inline]
 [<ffffffff81486086>] handle_pte_fault mm/memory.c:3525 [inline]
 [<ffffffff81486086>] __handle_mm_fault mm/memory.c:3612 [inline]
 [<ffffffff81486086>] handle_mm_fault+0x1326/0x2350 mm/memory.c:3649
 [<ffffffff810b0cc3>] __do_page_fault+0x403/0xa60 arch/x86/mm/fault.c:1408
 [<ffffffff810b1377>] do_page_fault+0x27/0x30 arch/x86/mm/fault.c:1471
 [<ffffffff8278d1b5>] page_fault+0x25/0x30 arch/x86/entry/entry_64.S:951
Code: cc3 ce8 ca2 c67 c2c c00 ceb c80 ce8 c5b c68 c2c c00 ceb ce4 ce8 c34 c68 c2c c00 ceb cb5 c66 c90 c48 cb8 c00 c00 c00 c00 c00 cfc cff cdf c55 c48 c89 ce5 c41 c55 c41 c54 c53 c<48> c89 cfb c48 c83 cc7 c04 c48 c89 cfa c48 cc1 cea c03 c0f cb6 c14 c02 c48 c89 cf8 c

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2018/09/10 03:08 https://android.googlesource.com/kernel/common android-4.9 dcae9fa1319b 6b5120a4 .config console log report ci-android-49-kasan-gce-root
* Struck through repros no longer work on HEAD.