syzbot


INFO: task hung in pipe_release

Status: auto-closed as invalid on 2021/07/23 11:59
Reported-by: syzbot+cab1072889f1fdd9b0de@syzkaller.appspotmail.com
First crash: 504d, last: 504d
similar bugs (7):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
android-414 INFO: task hung in pipe_release 1 1111d 1111d 0/1 auto-closed as invalid on 2019/11/24 16:38
upstream INFO: task hung in pipe_release (2) syz done 3 708d 739d 17/23 fixed on 2020/09/25 01:17
android-49 INFO: task hung in pipe_release (2) 1 1074d 1074d 0/3 auto-closed as invalid on 2019/12/30 22:08
android-49 INFO: task hung in pipe_release 2 1304d 1215d 0/3 auto-closed as invalid on 2019/07/14 20:38
upstream INFO: task hung in pipe_release (3) 4 268d 382d 0/23 closed as invalid on 2022/02/07 19:19
upstream INFO: task hung in pipe_release 2 1201d 1365d 0/23 auto-closed as invalid on 2019/10/25 10:11
upstream INFO: task can't die in pipe_release (2) 1 560d 556d 0/23 auto-closed as invalid on 2021/03/29 10:10

Sample crash report:
INFO: task syz-executor.3:14887 blocked for more than 140 seconds.
      Not tainted 4.19.183-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3  D28752 14887   8137 0x80000002
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 schedule+0x8d/0x1b0 kernel/sched/core.c:3561
 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
 __mutex_lock_common kernel/locking/mutex.c:1002 [inline]
 __mutex_lock+0x647/0x1260 kernel/locking/mutex.c:1072
squashfs: SQUASHFS error: unable to read xattr id index table
 __pipe_lock fs/pipe.c:83 [inline]
 pipe_release+0x49/0x340 fs/pipe.c:578
 __fput+0x2ce/0x890 fs/file_table.c:278
squashfs: SQUASHFS error: unable to read xattr id index table
 task_work_run+0x148/0x1c0 kernel/task_work.c:113
 exit_task_work include/linux/task_work.h:22 [inline]
 do_exit+0xbf3/0x2be0 kernel/exit.c:870
 do_group_exit+0x125/0x310 kernel/exit.c:967
 get_signal+0x3f2/0x1f70 kernel/signal.c:2589
 do_signal+0x8f/0x1670 arch/x86/kernel/signal.c:799
 exit_to_usermode_loop+0x204/0x2a0 arch/x86/entry/common.c:163
 prepare_exit_to_usermode+0x277/0x2d0 arch/x86/entry/common.c:198
 retint_user+0x8/0x18
RIP: 0033:0x466461
Code: 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 48 3d 01 f0 ff ff 73 01 <c3> 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48 83 c8 ff c3 66 2e 0f 1f
RSP: 002b:0000000020000200 EFLAGS: 00010217
RAX: 0000000000000000 RBX: 000000000056c008 RCX: 0000000000466459
RDX: 0000000020000040 RSI: 0000000020000200 RDI: 00000000a6808000
RBP: 00000000004bf9fb R08: 0000000020000140 R09: 0000000000000000
R10: 0000000020000080 R11: 0000000000000246 R12: 000000000056c008
R13: 00007ffc1f4ba71f R14: 00007f8778bf6300 R15: 0000000000022000

Showing all locks held in the system:
1 lock held by khungtaskd/1567:
 #0: 000000009d92b859 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440
1 lock held by in:imklog/7816:
 #0: 00000000098f5256 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
hub 9-0:1.0: USB hub found
4 locks held by syz-executor.3/8137:
3 locks held by syz-executor.5/14177:
1 lock held by syz-executor.3/14878:
 #0: 000000002f278bfd (&pipe->mutex/1){+.+.}, at: pipe_lock_nested fs/pipe.c:62 [inline]
 #0: 000000002f278bfd (&pipe->mutex/1){+.+.}, at: pipe_lock+0x63/0x80 fs/pipe.c:70
hub 9-0:1.0: 8 ports detected
1 lock held by syz-executor.3/14887:
 #0: 000000002f278bfd (&pipe->mutex/1){+.+.}, at: __pipe_lock fs/pipe.c:83 [inline]
 #0: 000000002f278bfd (&pipe->mutex/1){+.+.}, at: pipe_release+0x49/0x340 fs/pipe.c:578
1 lock held by syz-executor.3/15078:
 #0: 00000000b312097c (&pipe->mutex/1){+.+.}, at: pipe_lock_nested fs/pipe.c:62 [inline]
 #0: 00000000b312097c (&pipe->mutex/1){+.+.}, at: pipe_lock+0x63/0x80 fs/pipe.c:70
squashfs: SQUASHFS error: unable to read xattr id index table
1 lock held by syz-executor.3/17191:
 #0: 00000000986a95b8 (&pipe->mutex/1){+.+.}, at: pipe_lock_nested fs/pipe.c:62 [inline]
 #0: 00000000986a95b8 (&pipe->mutex/1){+.+.}, at: pipe_lock+0x63/0x80 fs/pipe.c:70
3 locks held by syz-executor.0/23377:
1 lock held by syz-executor.0/23381:
 #0: 00000000cb973994 (&dev->mutex){....}, at: device_lock include/linux/device.h:1173 [inline]
 #0: 00000000cb973994 (&dev->mutex){....}, at: usbdev_do_ioctl+0x193/0x3030 drivers/usb/core/devio.c:2388
2 locks held by systemd-udevd/23394:
squashfs: SQUASHFS error: unable to read xattr id index table
 #0: 000000002ab1b836 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1824 [inline]
 #0: 000000002ab1b836 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
 #1: 000000009d92b859 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline]
 #1: 000000009d92b859 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857

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

NMI backtrace for cpu 0
CPU: 0 PID: 1567 Comm: khungtaskd Not tainted 4.19.183-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+0x1fc/0x2ef lib/dump_stack.c:118
 nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x1a6/0x1f0 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline]
 watchdog+0x991/0xe60 kernel/hung_task.c:287
hub 9-0:1.0: USB hub found
 kthread+0x33f/0x460 kernel/kthread.c:259
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
hub 9-0:1.0: 8 ports detected
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 23381 Comm: syz-executor.0 Not tainted 4.19.183-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:__read_once_size include/linux/compiler.h:261 [inline]
RIP: 0010:debug_spin_lock_before kernel/locking/spinlock_debug.c:85 [inline]
RIP: 0010:do_raw_spin_lock+0x9d/0x220 kernel/locking/spinlock_debug.c:112
Code: 01 00 48 39 c2 0f 84 26 01 00 00 4c 8d 65 08 48 b8 00 00 00 00 00 fc ff df 4c 89 e2 48 c1 ea 03 0f b6 14 02 4c 89 e0 83 e0 07 <83> c0 03 38 d0 7c 08 84 d2 0f 85 19 01 00 00 8b 55 08 65 8b 05 8a
RSP: 0018:ffff8880ba107ec0 EFLAGS: 00000046
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00000000000073ba
RDX: 0000000000000000 RSI: 0000000027a8b8bf RDI: ffff8880ba124c84
RBP: ffff8880ba124c80 R08: ffffffff8cd4b310 R09: 0000000000000003
R10: ffff88804681c9c0 R11: ffffffff8c65f05b R12: ffff8880ba124c88
R13: ffff8880ba124c90 R14: ffff8880ba124c80 R15: 0000000000000000
FS:  00007fb57a9aa700(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fb57a988d58 CR3: 000000009d0a7000 CR4: 00000000001406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <IRQ>
 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:117 [inline]
 _raw_spin_lock_irqsave+0x94/0xc0 kernel/locking/spinlock.c:152
 hrtimer_interrupt+0xf9/0x9e0 kernel/time/hrtimer.c:1520
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1071 [inline]
 smp_apic_timer_interrupt+0x10c/0x550 arch/x86/kernel/apic/apic.c:1096
 apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894
 </IRQ>
RIP: 0010:arch_local_save_flags arch/x86/include/asm/paravirt.h:784 [inline]
RIP: 0010:arch_local_irq_save arch/x86/include/asm/paravirt.h:806 [inline]
RIP: 0010:lock_is_held_type+0x64/0x210 kernel/locking/lockdep.c:3941
Code: d0 7c 08 84 d2 0f 85 5e 01 00 00 8b 83 84 08 00 00 85 c0 0f 85 38 01 00 00 48 c7 c0 40 82 f1 89 48 ba 00 00 00 00 00 fc ff df <48> c1 e8 03 80 3c 10 00 0f 85 6a 01 00 00 48 83 3d f6 ed a6 08 00
RSP: 0018:ffff8880497f7568 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: ffffffff89f18240 RBX: ffff88804681c0c0 RCX: 0000000000000001
RDX: dffffc0000000000 RSI: 00000000ffffffff RDI: ffff88804681c944
RBP: ffffffff89f85e20 R08: ffffffff81c4470d R09: 0000000000000000
R10: 0000000000000003 R11: 0000000000000000 R12: 000000000000038c
R13: 0000000080000001 R14: ffffffff81c4470d R15: ffffffff8a070340
 lock_is_held include/linux/lockdep.h:344 [inline]
 ___might_sleep+0x227/0x2b0 kernel/sched/core.c:6157
 __mutex_lock_common kernel/locking/mutex.c:908 [inline]
 __mutex_lock+0xab/0x1260 kernel/locking/mutex.c:1072
 kernfs_drain fs/kernfs/dir.c:484 [inline]
 __kernfs_remove+0x57d/0xa80 fs/kernfs/dir.c:1316
 kernfs_remove+0x1f/0x30 fs/kernfs/dir.c:1351
 sysfs_remove_dir+0xc1/0x100 fs/sysfs/dir.c:101
 kobject_del lib/kobject.c:592 [inline]
 kobject_del+0x43/0xf0 lib/kobject.c:584
 device_del+0x6cb/0xaf0 drivers/base/core.c:2357
 device_unregister+0x22/0xc0 drivers/base/core.c:2377
 hub_disconnect+0x171/0x4b0 drivers/usb/core/hub.c:1696
 usb_unbind_interface+0x1d9/0x910 drivers/usb/core/driver.c:423
 __device_release_driver+0x394/0x6d0 drivers/base/dd.c:971
 device_release_driver_internal drivers/base/dd.c:1004 [inline]
 device_release_driver+0x23/0x30 drivers/base/dd.c:1029
 usb_driver_release_interface+0x102/0x180 drivers/usb/core/driver.c:599
 proc_ioctl+0x571/0x630 drivers/usb/core/devio.c:2179
 proc_ioctl_default drivers/usb/core/devio.c:2222 [inline]
 usbdev_do_ioctl+0x2773/0x3030 drivers/usb/core/devio.c:2536
 usbdev_ioctl+0x21/0x30 drivers/usb/core/devio.c:2580
 vfs_ioctl fs/ioctl.c:46 [inline]
 file_ioctl fs/ioctl.c:501 [inline]
 do_vfs_ioctl+0xcdb/0x12e0 fs/ioctl.c:688
 ksys_ioctl+0x9b/0xc0 fs/ioctl.c:705
 __do_sys_ioctl fs/ioctl.c:712 [inline]
 __se_sys_ioctl fs/ioctl.c:710 [inline]
 __x64_sys_ioctl+0x6f/0xb0 fs/ioctl.c:710
 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x466459
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007fb57a9aa188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 000000000056c008 RCX: 0000000000466459
RDX: 0000000020000380 RSI: 00000000c0105512 RDI: 0000000000000004
RBP: 00000000004bf9fb R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056c008
R13: 00007fff27d01e9f R14: 00007fb57a9aa300 R15: 0000000000022000

Crashes (1):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci2-linux-4-19 2021/03/25 11:58 linux-4.19.y 78fec1611cbf 6a383ecf .config log report info INFO: task hung in pipe_release