INFO: task hung in io_finish_async (2)
4 views
Skip to first unread message
syzbot
Jan 19, 2021, 3:58:21 AM1/19/21
to syzkaller-upst...@googlegroups.com
Hello,
syzbot found the following issue on:
HEAD commit: f4e087c6 Merge tag 'acpi-5.11-rc4' of git://git.kernel.org..
git tree: upstream
console output: https://syzkaller.appspot.com/x/log.txt?x=152d713f500000
kernel config: https://syzkaller.appspot.com/x/.config?x=ee2266946ed36986
dashboard link: https://syzkaller.appspot.com/bug?extid=5c051f061be1430d106b
compiler: clang version 11.0.1
CC: [ax...@kernel.dk io-u...@vger.kernel.org linux-...@vger.kernel.org linux-...@vger.kernel.org vi...@zeniv.linux.org.uk]
Unfortunately, I don't have any reproducer for this issue yet.
IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+5c051f...@syzkaller.appspotmail.com
INFO: task kworker/u4:1:24 blocked for more than 143 seconds.
Not tainted 5.11.0-rc3-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/u4:1 state:D stack:20440 pid: 24 ppid: 2 flags:0x00004000
Workqueue: events_unbound io_ring_exit_work
Call Trace:
context_switch kernel/sched/core.c:4313 [inline]
__schedule+0xa4d/0xf80 kernel/sched/core.c:5064
schedule+0x14b/0x200 kernel/sched/core.c:5143
schedule_timeout+0x98/0x2b0 kernel/time/timer.c:1854
do_wait_for_common+0x2dd/0x450 kernel/sched/completion.c:85
__wait_for_common kernel/sched/completion.c:106 [inline]
wait_for_common kernel/sched/completion.c:117 [inline]
wait_for_completion+0x43/0x50 kernel/sched/completion.c:138
kthread_park+0x10d/0x1c0 kernel/kthread.c:557
io_sq_thread_park fs/io_uring.c:7408 [inline]
io_sq_thread_stop fs/io_uring.c:7426 [inline]
io_finish_async+0xd7/0x460 fs/io_uring.c:7444
io_ring_ctx_free fs/io_uring.c:8609 [inline]
io_ring_exit_work+0x55/0x4c0 fs/io_uring.c:8702
process_one_work+0x833/0x10c0 kernel/workqueue.c:2275
worker_thread+0xaa4/0x1460 kernel/workqueue.c:2421
kthread+0x39a/0x3c0 kernel/kthread.c:292
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:296
Showing all locks held in the system:
2 locks held by kworker/u4:0/8:
#0: ffff888010869138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x7aa/0x10c0 kernel/workqueue.c:2248
#1: ffff8880b9d1ff48 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x224/0x5c0 kernel/sched/psi.c:833
3 locks held by kworker/u4:1/24:
#0: ffff888010869138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x7aa/0x10c0 kernel/workqueue.c:2248
#1: ffffc90000defd20 ((work_completion)(&ctx->exit_work)){+.+.}-{0:0}, at: process_one_work+0x7e8/0x10c0 kernel/workqueue.c:2250
#2: ffff88801837bc70 (&sqd->lock){+.+.}-{3:3}, at: io_sq_thread_park fs/io_uring.c:7407 [inline]
#2: ffff88801837bc70 (&sqd->lock){+.+.}-{3:3}, at: io_sq_thread_stop fs/io_uring.c:7426 [inline]
#2: ffff88801837bc70 (&sqd->lock){+.+.}-{3:3}, at: io_finish_async+0xbb/0x460 fs/io_uring.c:7444
1 lock held by khungtaskd/1647:
#0: ffffffff8c311580 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30 arch/x86/pci/mmconfig_64.c:151
2 locks held by kworker/u4:6/4542:
#0: ffff8880b9c34758 (&rq->lock){-.-.}-{2:2}, at: rq_lock kernel/sched/sched.h:1319 [inline]
#0: ffff8880b9c34758 (&rq->lock){-.-.}-{2:2}, at: __schedule+0x144/0xf80 kernel/sched/core.c:4981
#1: ffff8880b9c1ff48 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x224/0x5c0 kernel/sched/psi.c:833
1 lock held by systemd-udevd/4884:
2 locks held by kworker/1:3/4894:
#0: ffff88801087c538 ((wq_completion)rcu_gp){+.+.}-{0:0}, at: process_one_work+0x7aa/0x10c0 kernel/workqueue.c:2248
#1: ffffc9000170fd20 ((work_completion)(&rew.rew_work)){+.+.}-{0:0}, at: process_one_work+0x7e8/0x10c0 kernel/workqueue.c:2250
1 lock held by in:imklog/8134:
#0: ffff88801c5abc70 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x24e/0x2f0 fs/file.c:947
1 lock held by syz-executor.0/8447:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
1 lock held by syz-executor.1/8449:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
2 locks held by syz-executor.2/8451:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
#1: ffffffff8c315928 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:290 [inline]
#1: ffffffff8c315928 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x255/0x6d0 kernel/rcu/tree_exp.h:836
1 lock held by syz-executor.3/8453:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
1 lock held by syz-executor.4/8455:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
1 lock held by syz-executor.5/8499:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
=============================================
NMI backtrace for cpu 1
CPU: 1 PID: 1647 Comm: khungtaskd Not tainted 5.11.0-rc3-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:79 [inline]
dump_stack+0x183/0x22e lib/dump_stack.c:120
nmi_cpu_backtrace+0x16c/0x190 lib/nmi_backtrace.c:105
nmi_trigger_cpumask_backtrace+0x191/0x2f0 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:209 [inline]
watchdog+0xce9/0xd30 kernel/hung_task.c:294
kthread+0x39a/0x3c0 kernel/kthread.c:292
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:296
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 4877 Comm: systemd-journal Not tainted 5.11.0-rc3-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:smk_access+0x3d7/0x480 security/smack/smack_access.c:173
Code: 89 e6 e8 0c cb cd fd 44 89 e3 44 21 f3 89 df 44 89 f6 e8 8c c8 cd fd 44 39 f3 b8 f3 ff ff ff 44 0f 45 f8 45 85 e4 44 0f 4e f8 <48> 8b 5c 24 10 4d 85 ed 74 65 45 89 f4 49 be 00 00 00 00 00 fc ff
RSP: 0018:ffffc900016ff5e0 EFLAGS: 00000246
RAX: ffffffff83af52da RBX: ffffffff8a663fe0 RCX: ffff8880126c0000
RDX: 0000000000000000 RSI: ffffffff8c8541a0 RDI: ffffffff8c8541a0
RBP: ffffffff8c8541a0 R08: dffffc0000000000 R09: ffffc900016ff708
R10: fffff520002dfee5 R11: 0000000000000000 R12: 1ffffffff190a838
R13: 0000000000000000 R14: 0000000000000001 R15: 0000000000000000
FS: 00007feeb71f08c0(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007feeb4620000 CR3: 00000000122e4000 CR4: 00000000001506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
smk_tskacc+0x56/0x370 security/smack/smack_access.c:229
smack_inode_permission+0x283/0x320 security/smack/smack_lsm.c:1182
security_inode_permission+0x92/0x110 security/security.c:1260
may_lookup fs/namei.c:1575 [inline]
link_path_walk+0x273/0xc50 fs/namei.c:2128
path_openat+0x252/0x3ac0 fs/namei.c:3367
do_filp_open+0x1f1/0x430 fs/namei.c:3398
do_sys_openat2+0x124/0x460 fs/open.c:1172
do_sys_open fs/open.c:1188 [inline]
__do_sys_open fs/open.c:1196 [inline]
__se_sys_open fs/open.c:1192 [inline]
__x64_sys_open+0x221/0x270 fs/open.c:1192
do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46
entry_SYSCALL_64_after_hwframe+0x44/0xa9
RIP: 0033:0x7feeb6780840
Code: 73 01 c3 48 8b 0d 68 77 20 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 83 3d 89 bb 20 00 00 75 10 b8 02 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 1e f6 ff ff 48 89 04 24
RSP: 002b:00007ffd313954d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007ffd313957e0 RCX: 00007feeb6780840
RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 0000559a3b54e7c0
RBP: 000000000000000d R08: 000000000000c0c1 R09: 00000000ffffffff
R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000559a3b541040 R14: 00007ffd313957a0 R15: 0000559a3b54e810
---
This report is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.
syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.
syzbot found the following issue on:
HEAD commit: f4e087c6 Merge tag 'acpi-5.11-rc4' of git://git.kernel.org..
git tree: upstream
console output: https://syzkaller.appspot.com/x/log.txt?x=152d713f500000
kernel config: https://syzkaller.appspot.com/x/.config?x=ee2266946ed36986
dashboard link: https://syzkaller.appspot.com/bug?extid=5c051f061be1430d106b
compiler: clang version 11.0.1
CC: [ax...@kernel.dk io-u...@vger.kernel.org linux-...@vger.kernel.org linux-...@vger.kernel.org vi...@zeniv.linux.org.uk]
Unfortunately, I don't have any reproducer for this issue yet.
IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+5c051f...@syzkaller.appspotmail.com
INFO: task kworker/u4:1:24 blocked for more than 143 seconds.
Not tainted 5.11.0-rc3-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/u4:1 state:D stack:20440 pid: 24 ppid: 2 flags:0x00004000
Workqueue: events_unbound io_ring_exit_work
Call Trace:
context_switch kernel/sched/core.c:4313 [inline]
__schedule+0xa4d/0xf80 kernel/sched/core.c:5064
schedule+0x14b/0x200 kernel/sched/core.c:5143
schedule_timeout+0x98/0x2b0 kernel/time/timer.c:1854
do_wait_for_common+0x2dd/0x450 kernel/sched/completion.c:85
__wait_for_common kernel/sched/completion.c:106 [inline]
wait_for_common kernel/sched/completion.c:117 [inline]
wait_for_completion+0x43/0x50 kernel/sched/completion.c:138
kthread_park+0x10d/0x1c0 kernel/kthread.c:557
io_sq_thread_park fs/io_uring.c:7408 [inline]
io_sq_thread_stop fs/io_uring.c:7426 [inline]
io_finish_async+0xd7/0x460 fs/io_uring.c:7444
io_ring_ctx_free fs/io_uring.c:8609 [inline]
io_ring_exit_work+0x55/0x4c0 fs/io_uring.c:8702
process_one_work+0x833/0x10c0 kernel/workqueue.c:2275
worker_thread+0xaa4/0x1460 kernel/workqueue.c:2421
kthread+0x39a/0x3c0 kernel/kthread.c:292
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:296
Showing all locks held in the system:
2 locks held by kworker/u4:0/8:
#0: ffff888010869138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x7aa/0x10c0 kernel/workqueue.c:2248
#1: ffff8880b9d1ff48 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x224/0x5c0 kernel/sched/psi.c:833
3 locks held by kworker/u4:1/24:
#0: ffff888010869138 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x7aa/0x10c0 kernel/workqueue.c:2248
#1: ffffc90000defd20 ((work_completion)(&ctx->exit_work)){+.+.}-{0:0}, at: process_one_work+0x7e8/0x10c0 kernel/workqueue.c:2250
#2: ffff88801837bc70 (&sqd->lock){+.+.}-{3:3}, at: io_sq_thread_park fs/io_uring.c:7407 [inline]
#2: ffff88801837bc70 (&sqd->lock){+.+.}-{3:3}, at: io_sq_thread_stop fs/io_uring.c:7426 [inline]
#2: ffff88801837bc70 (&sqd->lock){+.+.}-{3:3}, at: io_finish_async+0xbb/0x460 fs/io_uring.c:7444
1 lock held by khungtaskd/1647:
#0: ffffffff8c311580 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30 arch/x86/pci/mmconfig_64.c:151
2 locks held by kworker/u4:6/4542:
#0: ffff8880b9c34758 (&rq->lock){-.-.}-{2:2}, at: rq_lock kernel/sched/sched.h:1319 [inline]
#0: ffff8880b9c34758 (&rq->lock){-.-.}-{2:2}, at: __schedule+0x144/0xf80 kernel/sched/core.c:4981
#1: ffff8880b9c1ff48 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x224/0x5c0 kernel/sched/psi.c:833
1 lock held by systemd-udevd/4884:
2 locks held by kworker/1:3/4894:
#0: ffff88801087c538 ((wq_completion)rcu_gp){+.+.}-{0:0}, at: process_one_work+0x7aa/0x10c0 kernel/workqueue.c:2248
#1: ffffc9000170fd20 ((work_completion)(&rew.rew_work)){+.+.}-{0:0}, at: process_one_work+0x7e8/0x10c0 kernel/workqueue.c:2250
1 lock held by in:imklog/8134:
#0: ffff88801c5abc70 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x24e/0x2f0 fs/file.c:947
1 lock held by syz-executor.0/8447:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
1 lock held by syz-executor.1/8449:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
2 locks held by syz-executor.2/8451:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
#1: ffffffff8c315928 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:290 [inline]
#1: ffffffff8c315928 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x255/0x6d0 kernel/rcu/tree_exp.h:836
1 lock held by syz-executor.3/8453:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
1 lock held by syz-executor.4/8455:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
1 lock held by syz-executor.5/8499:
#0: ffff888021b08d88 (&xt[i].mutex){+.+.}-{3:3}, at: xt_find_table_lock+0x37/0x410 net/netfilter/x_tables.c:1206
=============================================
NMI backtrace for cpu 1
CPU: 1 PID: 1647 Comm: khungtaskd Not tainted 5.11.0-rc3-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:79 [inline]
dump_stack+0x183/0x22e lib/dump_stack.c:120
nmi_cpu_backtrace+0x16c/0x190 lib/nmi_backtrace.c:105
nmi_trigger_cpumask_backtrace+0x191/0x2f0 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:209 [inline]
watchdog+0xce9/0xd30 kernel/hung_task.c:294
kthread+0x39a/0x3c0 kernel/kthread.c:292
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:296
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 4877 Comm: systemd-journal Not tainted 5.11.0-rc3-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:smk_access+0x3d7/0x480 security/smack/smack_access.c:173
Code: 89 e6 e8 0c cb cd fd 44 89 e3 44 21 f3 89 df 44 89 f6 e8 8c c8 cd fd 44 39 f3 b8 f3 ff ff ff 44 0f 45 f8 45 85 e4 44 0f 4e f8 <48> 8b 5c 24 10 4d 85 ed 74 65 45 89 f4 49 be 00 00 00 00 00 fc ff
RSP: 0018:ffffc900016ff5e0 EFLAGS: 00000246
RAX: ffffffff83af52da RBX: ffffffff8a663fe0 RCX: ffff8880126c0000
RDX: 0000000000000000 RSI: ffffffff8c8541a0 RDI: ffffffff8c8541a0
RBP: ffffffff8c8541a0 R08: dffffc0000000000 R09: ffffc900016ff708
R10: fffff520002dfee5 R11: 0000000000000000 R12: 1ffffffff190a838
R13: 0000000000000000 R14: 0000000000000001 R15: 0000000000000000
FS: 00007feeb71f08c0(0000) GS:ffff8880b9c00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007feeb4620000 CR3: 00000000122e4000 CR4: 00000000001506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
smk_tskacc+0x56/0x370 security/smack/smack_access.c:229
smack_inode_permission+0x283/0x320 security/smack/smack_lsm.c:1182
security_inode_permission+0x92/0x110 security/security.c:1260
may_lookup fs/namei.c:1575 [inline]
link_path_walk+0x273/0xc50 fs/namei.c:2128
path_openat+0x252/0x3ac0 fs/namei.c:3367
do_filp_open+0x1f1/0x430 fs/namei.c:3398
do_sys_openat2+0x124/0x460 fs/open.c:1172
do_sys_open fs/open.c:1188 [inline]
__do_sys_open fs/open.c:1196 [inline]
__se_sys_open fs/open.c:1192 [inline]
__x64_sys_open+0x221/0x270 fs/open.c:1192
do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46
entry_SYSCALL_64_after_hwframe+0x44/0xa9
RIP: 0033:0x7feeb6780840
Code: 73 01 c3 48 8b 0d 68 77 20 00 f7 d8 64 89 01 48 83 c8 ff c3 66 0f 1f 44 00 00 83 3d 89 bb 20 00 00 75 10 b8 02 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 1e f6 ff ff 48 89 04 24
RSP: 002b:00007ffd313954d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002
RAX: ffffffffffffffda RBX: 00007ffd313957e0 RCX: 00007feeb6780840
RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 0000559a3b54e7c0
RBP: 000000000000000d R08: 000000000000c0c1 R09: 00000000ffffffff
R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff
R13: 0000559a3b541040 R14: 00007ffd313957a0 R15: 0000559a3b54e810
---
This report is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.
syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.
syzbot
May 11, 2021, 5:58:15 AM5/11/21
to syzkaller-upst...@googlegroups.com
Auto-closing this bug as obsolete.
Crashes did not happen for a while, no reproducer and no activity.
Crashes did not happen for a while, no reproducer and no activity.
Reply all
Reply to author
Forward
0 new messages