bisecting fixing commit since c98875d930e915d01e8c40c7d3c16f00b3c8abe1 building syzkaller on 53199d6e8aee5f0ebd3775d2b1c674f4e6e64e2b testing commit c98875d930e915d01e8c40c7d3c16f00b3c8abe1 compiler: gcc version 8.4.1 20210217 (GCC) kernel signature: 2384b2413b3e30009dac5642db10b433f0d691ed362df34bf90adec2c3731eaf all runs: crashed: INFO: task hung in aead_recvmsg testing current HEAD b172b44fcb1771e083aad806fa96f3f60e2ddfac testing commit b172b44fcb1771e083aad806fa96f3f60e2ddfac compiler: gcc version 8.4.1 20210217 (GCC) kernel signature: a08b94b08ed487020b4090070c6130b7ae91968ff76a416d842210e14f2e691c all runs: crashed: INFO: task hung in aead_recvmsg revisions tested: 2, total time: 31m39.693100277s (build: 16m34.717652606s, test: 14m19.74213431s) the crash still happens on HEAD commit msg: Linux 4.19.206 crash: INFO: task hung in aead_recvmsg 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 8021q: adding VLAN 0 to HW filter on device batadv0 INFO: task syz-executor.1:6554 blocked for more than 140 seconds. Not tainted 4.19.206-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D27640 6554 5632 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:859 [inline] sock_recvmsg net/socket.c:866 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:862 sock_read_iter+0x252/0x440 net/socket.c:944 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:00007feb6e9dc198 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: 00007feb6e9dc6bc INFO: task syz-executor.5:6552 blocked for more than 140 seconds. Not tainted 4.19.206-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D28344 6552 5630 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:859 [inline] sock_recvmsg net/socket.c:866 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:862 sock_read_iter+0x252/0x440 net/socket.c:944 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:00007ff097c46198 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: 00007ff097c466bc INFO: task syz-executor.2:6563 blocked for more than 140 seconds. Not tainted 4.19.206-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D28168 6563 5631 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:859 [inline] sock_recvmsg net/socket.c:866 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:862 sock_read_iter+0x252/0x440 net/socket.c:944 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:00007fc9a5dbd198 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: 00007fc9a5dbd6bc INFO: task syz-executor.4:6571 blocked for more than 140 seconds. Not tainted 4.19.206-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D28120 6571 5636 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:859 [inline] sock_recvmsg net/socket.c:866 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:862 sock_read_iter+0x252/0x440 net/socket.c:944 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:00007f7928a40198 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: 00007f7928a406bc INFO: task syz-executor.3:6572 blocked for more than 140 seconds. Not tainted 4.19.206-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.3 D28168 6572 5626 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:859 [inline] sock_recvmsg net/socket.c:866 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:862 sock_read_iter+0x252/0x440 net/socket.c:944 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:00007f48cbe5c198 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: 00007f48cbe5c6bc INFO: task syz-executor.0:6573 blocked for more than 140 seconds. Not tainted 4.19.206-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D28168 6573 5633 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:859 [inline] sock_recvmsg net/socket.c:866 [inline] sock_recvmsg+0xb9/0xf0 net/socket.c:862 sock_read_iter+0x252/0x440 net/socket.c:944 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:00007f8105468198 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: 00007f81054686bc Showing all locks held in the system: 1 lock held by khungtaskd/1042: #0: 000000004aa16655 (rcu_read_lock){....}, at: debug_show_all_locks+0x5b/0x27a kernel/locking/lockdep.c:4443 1 lock held by in:imklog/5323: #0: 0000000059b372fa (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xa7/0xd0 fs/file.c:767 1 lock held by syz-executor.1/6554: #0: 00000000061657d7 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 00000000061657d7 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.5/6552: #0: 00000000d1c87ff0 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 00000000d1c87ff0 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.2/6563: #0: 0000000069b1be0b (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 0000000069b1be0b (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.4/6571: #0: 0000000016aeed5b (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 0000000016aeed5b (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.3/6572: #0: 00000000b49316f9 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 00000000b49316f9 (sk_lock-AF_ALG){+.+.}, at: af_alg_wait_for_data+0x1b3/0x440 crypto/af_alg.c:774 1 lock held by syz-executor.0/6573: #0: 0000000087828649 (sk_lock-AF_ALG){+.+.}, at: lock_sock include/net/sock.h:1510 [inline] #0: 0000000087828649 (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: 1042 Comm: khungtaskd Not tainted 4.19.206-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 CPU: 1 PID: 10 Comm: rcu_preempt Not tainted 4.19.206-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:debug_lockdep_rcu_enabled.part.3+0xb/0x60 kernel/rcu/update.c:251 Code: b7 fd ff ff 48 8b 7d d0 e8 02 66 40 00 e9 90 fd ff ff 66 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 b8 00 00 00 00 00 fc ff df 55 <48> 89 e5 53 65 48 8b 1c 25 80 ee 01 00 48 8d bb 84 08 00 00 48 89 RSP: 0018:ffff8881f56dfb28 EFLAGS: 00000002 RAX: dffffc0000000000 RBX: ffffffff884f1ee0 RCX: 0000000000000002 RDX: 0000000000000001 RSI: 0000000000000000 RDI: ffffffff88d3778c RBP: ffff8881f56dfb40 R08: fffffbfff13bddf2 R09: fffffbfff13bddf1 R10: fffffbfff13bddf1 R11: ffffffff89deef8b R12: ffff8881f6923af0 R13: ffff8881f56dfce0 R14: 0000000000000001 R15: ffff8881f6923a40 FS: 0000000000000000(0000) GS:ffff8881f6900000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fe5e4f22000 CR3: 000000000806d002 CR4: 00000000001606e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: trace_timer_cancel include/trace/events/timer.h:132 [inline] debug_deactivate kernel/time/timer.c:780 [inline] detach_timer kernel/time/timer.c:824 [inline] detach_if_pending+0x2e0/0x3c0 kernel/time/timer.c:843 try_to_del_timer_sync+0xa0/0x100 kernel/time/timer.c:1240 del_timer_sync+0x156/0x1e0 kernel/time/timer.c:1305 schedule_timeout+0x3b3/0xe00 kernel/time/timer.c:1819 rcu_gp_kthread+0xce6/0x23d0 kernel/rcu/tree.c:2202 kthread+0x34a/0x410 kernel/kthread.c:259 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:415 ---------------- Code disassembly (best guess), 3 bytes skipped: 0: ff 48 8b decl -0x75(%rax) 3: 7d d0 jge 0xffffffd5 5: e8 02 66 40 00 callq 0x40660c a: e9 90 fd ff ff jmpq 0xfffffd9f f: 66 66 2e 0f 1f 84 00 data16 nopw %cs:0x0(%rax,%rax,1) 16: 00 00 00 00 1a: 66 90 xchg %ax,%ax 1c: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 23: fc ff df 26: 55 push %rbp * 27: 48 89 e5 mov %rsp,%rbp <-- trapping instruction 2a: 53 push %rbx 2b: 65 48 8b 1c 25 80 ee mov %gs:0x1ee80,%rbx 32: 01 00 34: 48 8d bb 84 08 00 00 lea 0x884(%rbx),%rdi 3b: 48 rex.W 3c: 89 .byte 0x89