syzbot


INFO: task hung in pipe_write

Status: auto-closed as invalid on 2019/07/30 21:09
Reported-by: syzbot+afac45302cedcb5a54ec@syzkaller.appspotmail.com
First crash: 1514d, last: 1399d
similar bugs (9):
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in pipe_write (3) 1 1040d 1040d 0/24 auto-closed as invalid on 2020/04/25 05:37
android-44 INFO: task hung in pipe_write 1 1455d 1326d 0/2 auto-closed as invalid on 2019/06/05 06:19
upstream INFO: task hung in pipe_write (2) C done 6 1169d 1168d 0/24 closed as invalid on 2019/09/28 02:25
linux-4.14 INFO: task hung in pipe_write C 1 38d 38d 0/1 upstream: reported C repro on 2022/10/24 05:45
android-44 INFO: task hung in pipe_write (2) 1 1095d 1095d 0/2 auto-closed as invalid on 2020/03/31 01:26
android-49 INFO: task hung in pipe_write C 3 1120d 1326d 0/3 public: reported C repro on 2019/04/14 08:51
linux-4.19 INFO: task hung in pipe_write C error 13 7d23h 907d 0/1 upstream: reported C repro on 2020/06/07 01:40
upstream INFO: task hung in pipe_write (4) syz error 7 306d 854d 0/24 auto-obsoleted due to no activity on 2022/10/22 17:00
android-414 INFO: task hung in pipe_write 1 1143d 1143d 0/1 auto-closed as invalid on 2020/02/11 22:17

Sample crash report:
protocol 88fb is buggy, dev hsr_slave_0
protocol 88fb is buggy, dev hsr_slave_1
protocol 88fb is buggy, dev hsr_slave_0
protocol 88fb is buggy, dev hsr_slave_1
protocol 88fb is buggy, dev hsr_slave_0
INFO: task syz-executor3:2401 blocked for more than 140 seconds.
protocol 88fb is buggy, dev hsr_slave_1
protocol 88fb is buggy, dev hsr_slave_0
      Not tainted 5.0.0-rc4+ #54
protocol 88fb is buggy, dev hsr_slave_1
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor3   D26232  2401   8133 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2844 [inline]
 __schedule+0x897/0x1e60 kernel/sched/core.c:3485
 schedule+0xfe/0x350 kernel/sched/core.c:3529
 schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3587
 __mutex_lock_common kernel/locking/mutex.c:1002 [inline]
 __mutex_lock+0xa3b/0x1670 kernel/locking/mutex.c:1072
 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1087
 pipe_lock_nested fs/pipe.c:62 [inline]
 pipe_lock fs/pipe.c:70 [inline]
 pipe_wait+0x26b/0x2d0 fs/pipe.c:118
 pipe_write+0x5f0/0xfb0 fs/pipe.c:471
 call_write_iter include/linux/fs.h:1863 [inline]
 new_sync_write fs/read_write.c:474 [inline]
 __vfs_write+0x764/0xb40 fs/read_write.c:487
 vfs_write+0x20c/0x580 fs/read_write.c:549
 ksys_write+0x105/0x260 fs/read_write.c:598
 __do_sys_write fs/read_write.c:610 [inline]
 __se_sys_write fs/read_write.c:607 [inline]
 __x64_sys_write+0x73/0xb0 fs/read_write.c:607
 do_syscall_64+0x1a3/0x800 arch/x86/entry/common.c:290
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x457e39
Code: 44 24 18 00 00 00 00 e8 a5 b0 fc ff 48 8b 44 24 38 48 89 04 24 e8 d7 77 fb ff 48 8b 6c 24 40 48 83 c4 48 c3 48 89 d1 eb 90 e8 <73> 20 00 00 e9 7e fe ff ff cc cc cc cc cc cc cc cc cc cc cc cc cc
RSP: 002b:00007f2947b80c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000000457e39
RDX: 000000004240a2a0 RSI: 0000000020000140 RDI: 0000000000000004
RBP: 000000000073bf00 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f2947b816d4
R13: 00000000004c7183 R14: 00000000004dcb78 R15: 00000000ffffffff
INFO: task syz-executor3:2407 blocked for more than 140 seconds.
      Not tainted 5.0.0-rc4+ #54
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor3   D25288  2407   8133 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2844 [inline]
 __schedule+0x897/0x1e60 kernel/sched/core.c:3485
 schedule+0xfe/0x350 kernel/sched/core.c:3529
 schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3587
 __mutex_lock_common kernel/locking/mutex.c:1002 [inline]
 __mutex_lock+0xa3b/0x1670 kernel/locking/mutex.c:1072
 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1087
 genl_lock net/netlink/genetlink.c:33 [inline]
 genl_rcv_msg+0x13e/0x16c net/netlink/genetlink.c:624
 netlink_rcv_skb+0x17d/0x410 net/netlink/af_netlink.c:2477
protocol 88fb is buggy, dev hsr_slave_0
protocol 88fb is buggy, dev hsr_slave_1
 genl_rcv+0x29/0x40 net/netlink/genetlink.c:637
 netlink_unicast_kernel net/netlink/af_netlink.c:1310 [inline]
 netlink_unicast+0x574/0x770 net/netlink/af_netlink.c:1336
 netlink_sendmsg+0xa05/0xf90 net/netlink/af_netlink.c:1917
 sock_sendmsg_nosec net/socket.c:621 [inline]
 sock_sendmsg+0xdd/0x130 net/socket.c:631
 kernel_sendmsg+0x44/0x50 net/socket.c:639
 sock_no_sendpage+0x1cd/0x260 net/core/sock.c:2587
 kernel_sendpage+0x95/0xf0 net/socket.c:3360
 sock_sendpage+0x8b/0xc0 net/socket.c:846
 pipe_to_sendpage+0x2b4/0x390 fs/splice.c:452
 splice_from_pipe_feed fs/splice.c:503 [inline]
 __splice_from_pipe+0x39a/0x7e0 fs/splice.c:627
 splice_from_pipe+0x1ea/0x310 fs/splice.c:662
 generic_splice_sendpage+0x3c/0x50 fs/splice.c:832
 do_splice_from fs/splice.c:851 [inline]
 do_splice+0x64b/0x1410 fs/splice.c:1152
 __do_sys_splice fs/splice.c:1419 [inline]
 __se_sys_splice fs/splice.c:1399 [inline]
 __x64_sys_splice+0x2c6/0x330 fs/splice.c:1399
 do_syscall_64+0x1a3/0x800 arch/x86/entry/common.c:290
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x457e39
Code: Bad RIP value.
RSP: 002b:00007f2947b5fc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000113
RAX: ffffffffffffffda RBX: 0000000000000006 RCX: 0000000000457e39
RDX: 0000000000000005 RSI: 0000000000000000 RDI: 0000000000000003
RBP: 000000000073bfa0 R08: 0000000000010005 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f2947b606d4
R13: 00000000004c6b98 R14: 00000000004db618 R15: 00000000ffffffff

Showing all locks held in the system:
1 lock held by khungtaskd/1040:
 #0: 00000000a13b16e5 (rcu_read_lock){....}, at: debug_show_all_locks+0xc6/0x41d kernel/locking/lockdep.c:4389
1 lock held by rsyslogd/7938:
 #0: 000000002b0f02cd (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1b3/0x1f0 fs/file.c:795
2 locks held by getty/8084:
 #0: 0000000067ed6802 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 0000000030a2ee92 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 drivers/tty/n_tty.c:2154
2 locks held by getty/8085:
 #0: 000000003dfa3451 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 000000000b3cbe5c (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 drivers/tty/n_tty.c:2154
2 locks held by getty/8086:
 #0: 000000008dd31a88 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 000000002ae508bd (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 drivers/tty/n_tty.c:2154
2 locks held by getty/8087:
 #0: 0000000026a6bd6c (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 00000000ca239f1d (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 drivers/tty/n_tty.c:2154
2 locks held by getty/8088:
 #0: 00000000ff0bad3a (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 00000000e9e7a1d9 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 drivers/tty/n_tty.c:2154
2 locks held by getty/8089:
 #0: 00000000203e1973 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 00000000d523c077 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 drivers/tty/n_tty.c:2154
2 locks held by getty/8090:
 #0: 000000002ea54582 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341
 #1: 0000000031972fd1 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0 drivers/tty/n_tty.c:2154
3 locks held by syz-executor4/2377:
1 lock held by syz-executor3/2401:
 #0: 000000006898de67 (&pipe->mutex/1){+.+.}, at: pipe_lock_nested fs/pipe.c:62 [inline]
 #0: 000000006898de67 (&pipe->mutex/1){+.+.}, at: pipe_lock fs/pipe.c:70 [inline]
 #0: 000000006898de67 (&pipe->mutex/1){+.+.}, at: pipe_wait+0x26b/0x2d0 fs/pipe.c:118
3 locks held by syz-executor3/2407:
 #0: 000000006898de67 (&pipe->mutex/1){+.+.}, at: pipe_lock_nested fs/pipe.c:62 [inline]
 #0: 000000006898de67 (&pipe->mutex/1){+.+.}, at: pipe_lock+0x6e/0x80 fs/pipe.c:70
 #1: 000000006ce2d922 (cb_lock){++++}, at: genl_rcv+0x1a/0x40 net/netlink/genetlink.c:636
 #2: 0000000064d8769e (genl_mutex){+.+.}, at: genl_lock net/netlink/genetlink.c:33 [inline]
 #2: 0000000064d8769e (genl_mutex){+.+.}, at: genl_rcv_msg+0x13e/0x16c net/netlink/genetlink.c:624

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

NMI backtrace for cpu 1
CPU: 1 PID: 1040 Comm: khungtaskd Not tainted 5.0.0-rc4+ #54
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+0x1db/0x2d0 lib/dump_stack.c:113
 nmi_cpu_backtrace.cold+0x63/0xa4 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x1be/0x236 lib/nmi_backtrace.c:62
 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline]
 watchdog+0xbbb/0x1170 kernel/hung_task.c:287
 kthread+0x357/0x430 kernel/kthread.c:246
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 8381 Comm: kworker/u4:5 Not tainted 5.0.0-rc4+ #54
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue:            (null) (flush-8:0)
RIP: 0010:__perf_event_task_sched_out+0x6/0x19c0 kernel/events/core.c:3206
Code: ff 48 89 95 58 fe ff ff e8 e7 86 2a 00 48 8b 95 58 fe ff ff e9 2e fc ff ff 90 66 2e 0f 1f 84 00 00 00 00 00 55 48 89 e5 41 57 <48> 8d 85 f8 fd ff ff 41 56 49 89 fe 41 55 4c 8d 7d d8 48 c1 e8 03
RSP: 0000:ffff88806926fa80 EFLAGS: 00000006
RAX: dffffc0000000000 RBX: 00000000004cf154 RCX: 1ffff1100b6e8918
RDX: 1ffff11015cc5b54 RSI: ffff88805b744500 RDI: ffff8880682c4400
RBP: ffff88806926fa88 R08: ffff8880682c4400 R09: ffffed1015cc5b98
R10: ffffed1015cc5b97 R11: ffff8880ae62dcbb R12: ffff88805b744500
R13: ffff8880ae62d868 R14: ffff8880ae62ce40 R15: ffff8880682c4400
FS:  0000000000000000(0000) GS:ffff8880ae600000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000000130 CR3: 000000006e8ae000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
Call Trace:
 perf_event_task_sched_out include/linux/perf_event.h:1112 [inline]
 prepare_task_switch kernel/sched/core.c:2628 [inline]
 context_switch kernel/sched/core.c:2809 [inline]
 __schedule+0xc77/0x1e60 kernel/sched/core.c:3485
 schedule+0xfe/0x350 kernel/sched/core.c:3529
 worker_thread+0x3bc/0x14a0 kernel/workqueue.c:2317
 kthread+0x357/0x430 kernel/kthread.c:246
 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352

Crashes (8):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci-upstream-kasan-gce-selinux-root 2019/01/31 21:08 upstream 9f789567142c 0e8ea0a3 .config log report
ci-upstream-kasan-gce-root 2018/11/10 21:32 upstream ab6e1f378f54 f3c4e618 .config log report
ci-upstream-kasan-gce-smack-root 2018/11/04 10:35 upstream 71e56028173b 8bd6bd63 .config log report
ci-upstream-kasan-gce 2018/11/01 12:26 upstream 59fc453b21f7 1f38e9ae .config log report
ci-upstream-kasan-gce-selinux-root 2018/10/27 07:25 upstream 345671ea0f92 a8292de9 .config log report
ci-upstream-kasan-gce-smack-root 2018/10/26 18:33 upstream 18d0eae30e6a a8292de9 .config log report
ci-upstream-kasan-gce-root 2018/10/11 06:22 upstream 9dcd936c5312 5f818b4b .config log report
ci-upstream-kasan-gce-root 2018/10/08 09:04 upstream 0238df646e62 8b311eaf .config log report
* Struck through repros no longer work on HEAD.