bisecting fixing commit since c98875d930e915d01e8c40c7d3c16f00b3c8abe1 building syzkaller on 53199d6e8aee5f0ebd3775d2b1c674f4e6e64e2b testing commit c98875d930e915d01e8c40c7d3c16f00b3c8abe1 with gcc (GCC) 8.4.1 20210217 kernel signature: 2384b2413b3e30009dac5642db10b433f0d691ed362df34bf90adec2c3731eaf all runs: crashed: INFO: task hung in aead_recvmsg testing current HEAD 2d19be4653f5e74ed95560b69f94eb6791d49af3 testing commit 2d19be4653f5e74ed95560b69f94eb6791d49af3 with gcc (GCC) 8.4.1 20210217 kernel signature: 2c4e600c8b0a485ca7ceddf8085469827114804eb9cd6862f6999e8b646c067b all runs: crashed: INFO: task hung in aead_recvmsg revisions tested: 2, total time: 29m53.038363935s (build: 14m53.713257163s, test: 13m47.847484675s) the crash still happens on HEAD commit msg: Linux 4.19.177 crash: INFO: task hung in aead_recvmsg IPv6: ADDRCONF(NETDEV_UP): vxcan1: link is not ready 8021q: adding VLAN 0 to HW filter on device batadv0 8021q: adding VLAN 0 to HW filter on device batadv0 8021q: adding VLAN 0 to HW filter on device batadv0 INFO: task syz-executor.2:6768 blocked for more than 140 seconds. Not tainted 4.19.177-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D26592 6768 5640 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0xdbb/0x1d60 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 schedule_timeout+0x6e3/0xe00 kernel/time/timer.c:1794 do_wait_for_common kernel/sched/completion.c:83 [inline] __wait_for_common kernel/sched/completion.c:104 [inline] wait_for_common kernel/sched/completion.c:115 [inline] wait_for_completion+0x282/0x460 kernel/sched/completion.c:136 crypto_wait_req include/linux/crypto.h:517 [inline] _aead_recvmsg crypto/algif_aead.c:314 [inline] aead_recvmsg+0x1144/0x1fb0 crypto/algif_aead.c:335 sock_recvmsg_nosec net/socket.c:795 [inline] sock_recvmsg net/socket.c:802 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:798 sock_read_iter+0x252/0x440 net/socket.c:879 call_read_iter include/linux/fs.h:1815 [inline] do_iter_readv_writev+0x3f2/0x960 fs/read_write.c:679 do_iter_read+0x1e0/0x5a0 fs/read_write.c:923 vfs_readv+0xc9/0x130 fs/read_write.c:987 do_readv+0x110/0x330 fs/read_write.c:1020 __do_sys_readv fs/read_write.c:1107 [inline] __se_sys_readv fs/read_write.c:1104 [inline] __x64_sys_readv+0x70/0xb0 fs/read_write.c:1104 do_syscall_64+0xd6/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x4603b9 Code: Bad RIP value. RSP: 002b:00007f7324fe6198 EFLAGS: 00000246 ORIG_RAX: 0000000000000013 RAX: ffffffffffffffda RBX: 000000000052bf00 RCX: 00000000004603b9 RDX: 0000000000000001 RSI: 00000000200007c0 RDI: 0000000000000004 RBP: 00000000004a9a99 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000000000000003 R14: 00000000004b129a R15: 00007f7324fe66bc INFO: task syz-executor.0:6781 blocked for more than 140 seconds. Not tainted 4.19.177-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D26592 6781 5669 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0xdbb/0x1d60 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 schedule_timeout+0x6e3/0xe00 kernel/time/timer.c:1794 do_wait_for_common kernel/sched/completion.c:83 [inline] __wait_for_common kernel/sched/completion.c:104 [inline] wait_for_common kernel/sched/completion.c:115 [inline] wait_for_completion+0x282/0x460 kernel/sched/completion.c:136 crypto_wait_req include/linux/crypto.h:517 [inline] _aead_recvmsg crypto/algif_aead.c:314 [inline] aead_recvmsg+0x1144/0x1fb0 crypto/algif_aead.c:335 sock_recvmsg_nosec net/socket.c:795 [inline] sock_recvmsg net/socket.c:802 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:798 sock_read_iter+0x252/0x440 net/socket.c:879 call_read_iter include/linux/fs.h:1815 [inline] do_iter_readv_writev+0x3f2/0x960 fs/read_write.c:679 do_iter_read+0x1e0/0x5a0 fs/read_write.c:923 vfs_readv+0xc9/0x130 fs/read_write.c:987 do_readv+0x110/0x330 fs/read_write.c:1020 __do_sys_readv fs/read_write.c:1107 [inline] __se_sys_readv fs/read_write.c:1104 [inline] __x64_sys_readv+0x70/0xb0 fs/read_write.c:1104 do_syscall_64+0xd6/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x4603b9 Code: Bad RIP value. RSP: 002b:00007f8f501a1198 EFLAGS: 00000246 ORIG_RAX: 0000000000000013 RAX: ffffffffffffffda RBX: 000000000052bf00 RCX: 00000000004603b9 RDX: 0000000000000001 RSI: 00000000200007c0 RDI: 0000000000000004 RBP: 00000000004a9a99 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000000000000003 R14: 00000000004b129a R15: 00007f8f501a16bc INFO: task syz-executor.0:6782 blocked for more than 140 seconds. Not tainted 4.19.177-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D26592 6782 5669 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0xdbb/0x1d60 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 schedule_timeout+0x6e3/0xe00 kernel/time/timer.c:1794 do_wait_for_common kernel/sched/completion.c:83 [inline] __wait_for_common kernel/sched/completion.c:104 [inline] wait_for_common kernel/sched/completion.c:115 [inline] wait_for_completion+0x282/0x460 kernel/sched/completion.c:136 crypto_wait_req include/linux/crypto.h:517 [inline] _aead_recvmsg crypto/algif_aead.c:314 [inline] aead_recvmsg+0x1144/0x1fb0 crypto/algif_aead.c:335 sock_recvmsg_nosec net/socket.c:795 [inline] sock_recvmsg net/socket.c:802 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:798 sock_read_iter+0x252/0x440 net/socket.c:879 call_read_iter include/linux/fs.h:1815 [inline] do_iter_readv_writev+0x3f2/0x960 fs/read_write.c:679 do_iter_read+0x1e0/0x5a0 fs/read_write.c:923 vfs_readv+0xc9/0x130 fs/read_write.c:987 do_readv+0x110/0x330 fs/read_write.c:1020 __do_sys_readv fs/read_write.c:1107 [inline] __se_sys_readv fs/read_write.c:1104 [inline] __x64_sys_readv+0x70/0xb0 fs/read_write.c:1104 do_syscall_64+0xd6/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x4603b9 Code: Bad RIP value. RSP: 002b:00007f8f50180198 EFLAGS: 00000246 ORIG_RAX: 0000000000000013 RAX: ffffffffffffffda RBX: 000000000052bfa0 RCX: 00000000004603b9 RDX: 0000000000000001 RSI: 00000000200007c0 RDI: 0000000000000004 RBP: 00000000004a9a99 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000000000000003 R14: 00000000004b129a R15: 00007f8f501806bc INFO: task syz-executor.3:6785 blocked for more than 140 seconds. Not tainted 4.19.177-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.3 D26592 6785 5642 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0xdbb/0x1d60 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 schedule_timeout+0x6e3/0xe00 kernel/time/timer.c:1794 do_wait_for_common kernel/sched/completion.c:83 [inline] __wait_for_common kernel/sched/completion.c:104 [inline] wait_for_common kernel/sched/completion.c:115 [inline] wait_for_completion+0x282/0x460 kernel/sched/completion.c:136 crypto_wait_req include/linux/crypto.h:517 [inline] _aead_recvmsg crypto/algif_aead.c:314 [inline] aead_recvmsg+0x1144/0x1fb0 crypto/algif_aead.c:335 sock_recvmsg_nosec net/socket.c:795 [inline] sock_recvmsg net/socket.c:802 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:798 sock_read_iter+0x252/0x440 net/socket.c:879 call_read_iter include/linux/fs.h:1815 [inline] do_iter_readv_writev+0x3f2/0x960 fs/read_write.c:679 do_iter_read+0x1e0/0x5a0 fs/read_write.c:923 vfs_readv+0xc9/0x130 fs/read_write.c:987 do_readv+0x110/0x330 fs/read_write.c:1020 __do_sys_readv fs/read_write.c:1107 [inline] __se_sys_readv fs/read_write.c:1104 [inline] __x64_sys_readv+0x70/0xb0 fs/read_write.c:1104 do_syscall_64+0xd6/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x4603b9 Code: Bad RIP value. RSP: 002b:00007f90afa95198 EFLAGS: 00000246 ORIG_RAX: 0000000000000013 RAX: ffffffffffffffda RBX: 000000000052bf00 RCX: 00000000004603b9 RDX: 0000000000000001 RSI: 00000000200007c0 RDI: 0000000000000004 RBP: 00000000004a9a99 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000000000000003 R14: 00000000004b129a R15: 00007f90afa956bc INFO: task syz-executor.5:6805 blocked for more than 140 seconds. Not tainted 4.19.177-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D26592 6805 5652 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0xdbb/0x1d60 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 schedule_timeout+0x6e3/0xe00 kernel/time/timer.c:1794 do_wait_for_common kernel/sched/completion.c:83 [inline] __wait_for_common kernel/sched/completion.c:104 [inline] wait_for_common kernel/sched/completion.c:115 [inline] wait_for_completion+0x282/0x460 kernel/sched/completion.c:136 crypto_wait_req include/linux/crypto.h:517 [inline] _aead_recvmsg crypto/algif_aead.c:314 [inline] aead_recvmsg+0x1144/0x1fb0 crypto/algif_aead.c:335 sock_recvmsg_nosec net/socket.c:795 [inline] sock_recvmsg net/socket.c:802 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:798 sock_read_iter+0x252/0x440 net/socket.c:879 call_read_iter include/linux/fs.h:1815 [inline] do_iter_readv_writev+0x3f2/0x960 fs/read_write.c:679 do_iter_read+0x1e0/0x5a0 fs/read_write.c:923 vfs_readv+0xc9/0x130 fs/read_write.c:987 do_readv+0x110/0x330 fs/read_write.c:1020 __do_sys_readv fs/read_write.c:1107 [inline] __se_sys_readv fs/read_write.c:1104 [inline] __x64_sys_readv+0x70/0xb0 fs/read_write.c:1104 do_syscall_64+0xd6/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x4603b9 Code: Bad RIP value. RSP: 002b:00007f3303611198 EFLAGS: 00000246 ORIG_RAX: 0000000000000013 RAX: ffffffffffffffda RBX: 000000000052bf00 RCX: 00000000004603b9 RDX: 0000000000000001 RSI: 00000000200007c0 RDI: 0000000000000004 RBP: 00000000004a9a99 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000000000000003 R14: 00000000004b129a R15: 00007f33036116bc INFO: task syz-executor.4:6819 blocked for more than 140 seconds. Not tainted 4.19.177-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D26592 6819 5646 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0xdbb/0x1d60 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 schedule_timeout+0x6e3/0xe00 kernel/time/timer.c:1794 do_wait_for_common kernel/sched/completion.c:83 [inline] __wait_for_common kernel/sched/completion.c:104 [inline] wait_for_common kernel/sched/completion.c:115 [inline] wait_for_completion+0x282/0x460 kernel/sched/completion.c:136 crypto_wait_req include/linux/crypto.h:517 [inline] _aead_recvmsg crypto/algif_aead.c:314 [inline] aead_recvmsg+0x1144/0x1fb0 crypto/algif_aead.c:335 sock_recvmsg_nosec net/socket.c:795 [inline] sock_recvmsg net/socket.c:802 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:798 sock_read_iter+0x252/0x440 net/socket.c:879 call_read_iter include/linux/fs.h:1815 [inline] do_iter_readv_writev+0x3f2/0x960 fs/read_write.c:679 do_iter_read+0x1e0/0x5a0 fs/read_write.c:923 vfs_readv+0xc9/0x130 fs/read_write.c:987 do_readv+0x110/0x330 fs/read_write.c:1020 __do_sys_readv fs/read_write.c:1107 [inline] __se_sys_readv fs/read_write.c:1104 [inline] __x64_sys_readv+0x70/0xb0 fs/read_write.c:1104 do_syscall_64+0xd6/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x4603b9 Code: Bad RIP value. RSP: 002b:00007f35f989f198 EFLAGS: 00000246 ORIG_RAX: 0000000000000013 RAX: ffffffffffffffda RBX: 000000000052bf00 RCX: 00000000004603b9 RDX: 0000000000000001 RSI: 00000000200007c0 RDI: 0000000000000004 RBP: 00000000004a9a99 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000000000000003 R14: 00000000004b129a R15: 00007f35f989f6bc INFO: task syz-executor.1:6828 blocked for more than 140 seconds. Not tainted 4.19.177-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D26592 6828 5663 0x00000004 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0xdbb/0x1d60 kernel/sched/core.c:3517 schedule+0x7f/0x1b0 kernel/sched/core.c:3561 schedule_timeout+0x6e3/0xe00 kernel/time/timer.c:1794 do_wait_for_common kernel/sched/completion.c:83 [inline] __wait_for_common kernel/sched/completion.c:104 [inline] wait_for_common kernel/sched/completion.c:115 [inline] wait_for_completion+0x282/0x460 kernel/sched/completion.c:136 crypto_wait_req include/linux/crypto.h:517 [inline] _aead_recvmsg crypto/algif_aead.c:314 [inline] aead_recvmsg+0x1144/0x1fb0 crypto/algif_aead.c:335 sock_recvmsg_nosec net/socket.c:795 [inline] sock_recvmsg net/socket.c:802 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:798 sock_read_iter+0x252/0x440 net/socket.c:879 call_read_iter include/linux/fs.h:1815 [inline] do_iter_readv_writev+0x3f2/0x960 fs/read_write.c:679 do_iter_read+0x1e0/0x5a0 fs/read_write.c:923 vfs_readv+0xc9/0x130 fs/read_write.c:987 do_readv+0x110/0x330 fs/read_write.c:1020 __do_sys_readv fs/read_write.c:1107 [inline] __se_sys_readv fs/read_write.c:1104 [inline] __x64_sys_readv+0x70/0xb0 fs/read_write.c:1104 do_syscall_64+0xd6/0x4e0 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x4603b9 Code: Bad RIP value. RSP: 002b:00007f1827a36198 EFLAGS: 00000246 ORIG_RAX: 0000000000000013 RAX: ffffffffffffffda RBX: 000000000052bf00 RCX: 00000000004603b9 RDX: 0000000000000001 RSI: 00000000200007c0 RDI: 0000000000000004 RBP: 00000000004a9a99 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00000000ffffffff R13: 0000000000000003 R14: 00000000004b129a R15: 00007f1827a366bc Showing all locks held in the system: 1 lock held by khungtaskd/1032: #0: 000000005530266e (rcu_read_lock){....}, at: debug_show_all_locks+0x5b/0x27a kernel/locking/lockdep.c:4442 1 lock held by in:imklog/5304: #0: 00000000b299af48 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xa7/0xd0 fs/file.c:767 1 lock held by syz-executor.2/6768: #0: 00000000a0f89281 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 00000000a0f89281 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.0/6781: #0: 000000008e543166 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 000000008e543166 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.0/6782: #0: 00000000097d12c4 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 00000000097d12c4 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.3/6785: #0: 00000000cfc55fc9 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 00000000cfc55fc9 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.5/6805: #0: 00000000eb6bc781 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 00000000eb6bc781 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.4/6819: #0: 0000000017e37381 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 0000000017e37381 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.1/6828: #0: 00000000b4a84c80 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 00000000b4a84c80 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1032 Comm: khungtaskd Not tainted 4.19.177-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+0x123/0x171 lib/dump_stack.c:118 nmi_cpu_backtrace.cold.4+0x3e/0x76 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0xe6/0x120 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+0x5c3/0xb40 kernel/hung_task.c:287 kthread+0x34a/0x410 kernel/kthread.c:259 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:415 Sending NMI from CPU 0 to CPUs 1: NMI backtrace for cpu 1 skipped: idling at native_safe_halt+0x12/0x20 arch/x86/include/asm/irqflags.h:60