INFO: task can't die in tls_sw_sendpage
12 views
Skip to first unread message
syzbot
Jul 12, 2021, 6:08:20 AM7/12/21
to syzkaller-upst...@googlegroups.com
Hello,
syzbot found the following issue on:
HEAD commit: e2f74b13 Add linux-next specific files for 20210708
git tree: linux-next
console output: https://syzkaller.appspot.com/x/log.txt?x=16eede9c300000
kernel config: https://syzkaller.appspot.com/x/.config?x=59e1e3bbc3afca75
dashboard link: https://syzkaller.appspot.com/bug?extid=a0d9194812a32ec7d41f
CC: [and...@kernel.org a...@kernel.org bor...@nvidia.com b...@vger.kernel.org dan...@iogearbox.net da...@davemloft.net john.fa...@gmail.com ka...@fb.com kps...@kernel.org ku...@kernel.org linux-...@vger.kernel.org net...@vger.kernel.org songliu...@fb.com y...@fb.com]
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+a0d919...@syzkaller.appspotmail.com
INFO: task syz-executor.2:20381 can't die for more than 143 seconds.
task:syz-executor.2 state:D stack:23864 pid:20381 ppid: 32682 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4700 [inline]
__schedule+0x93a/0x26f0 kernel/sched/core.c:5957
schedule+0xd3/0x270 kernel/sched/core.c:6036
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6095
__mutex_lock_common kernel/locking/mutex.c:1036 [inline]
__mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104
tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
inet_sendpage+0xd0/0x140 net/ipv4/af_inet.c:835
kernel_sendpage.part.0+0x1a0/0x340 net/socket.c:3673
kernel_sendpage net/socket.c:3670 [inline]
sock_sendpage+0xe5/0x140 net/socket.c:1002
pipe_to_sendpage+0x2ad/0x380 fs/splice.c:364
splice_from_pipe_feed fs/splice.c:418 [inline]
__splice_from_pipe+0x43e/0x8a0 fs/splice.c:562
splice_from_pipe fs/splice.c:597 [inline]
generic_splice_sendpage+0xd4/0x140 fs/splice.c:746
do_splice_from fs/splice.c:767 [inline]
direct_splice_actor+0x110/0x180 fs/splice.c:936
splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891
do_splice_direct+0x1b3/0x280 fs/splice.c:979
do_sendfile+0x9f0/0x1120 fs/read_write.c:1260
__do_sys_sendfile64 fs/read_write.c:1325 [inline]
__se_sys_sendfile64 fs/read_write.c:1311 [inline]
__x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1311
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
RSP: 002b:00007fd0c5f8b188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000006 RDI: 0000000000000008
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000800100020001 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffd250cf62f R14: 00007fd0c5f8b300 R15: 0000000000022000
INFO: task syz-executor.2:20381 blocked for more than 143 seconds.
Not tainted 5.13.0-next-20210708-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.2 state:D stack:23864 pid:20381 ppid: 32682 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4700 [inline]
__schedule+0x93a/0x26f0 kernel/sched/core.c:5957
schedule+0xd3/0x270 kernel/sched/core.c:6036
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6095
__mutex_lock_common kernel/locking/mutex.c:1036 [inline]
__mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104
tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
inet_sendpage+0xd0/0x140 net/ipv4/af_inet.c:835
kernel_sendpage.part.0+0x1a0/0x340 net/socket.c:3673
kernel_sendpage net/socket.c:3670 [inline]
sock_sendpage+0xe5/0x140 net/socket.c:1002
pipe_to_sendpage+0x2ad/0x380 fs/splice.c:364
splice_from_pipe_feed fs/splice.c:418 [inline]
__splice_from_pipe+0x43e/0x8a0 fs/splice.c:562
splice_from_pipe fs/splice.c:597 [inline]
generic_splice_sendpage+0xd4/0x140 fs/splice.c:746
do_splice_from fs/splice.c:767 [inline]
direct_splice_actor+0x110/0x180 fs/splice.c:936
splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891
do_splice_direct+0x1b3/0x280 fs/splice.c:979
do_sendfile+0x9f0/0x1120 fs/read_write.c:1260
__do_sys_sendfile64 fs/read_write.c:1325 [inline]
__se_sys_sendfile64 fs/read_write.c:1311 [inline]
__x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1311
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
RSP: 002b:00007fd0c5f8b188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000006 RDI: 0000000000000008
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000800100020001 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffd250cf62f R14: 00007fd0c5f8b300 R15: 0000000000022000
INFO: task syz-executor.0:20404 can't die for more than 143 seconds.
task:syz-executor.0 state:D stack:24416 pid:20404 ppid: 11499 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4700 [inline]
__schedule+0x93a/0x26f0 kernel/sched/core.c:5957
schedule+0xd3/0x270 kernel/sched/core.c:6036
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6095
__mutex_lock_common kernel/locking/mutex.c:1036 [inline]
__mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104
tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
inet_sendpage+0xd0/0x140 net/ipv4/af_inet.c:835
kernel_sendpage.part.0+0x1a0/0x340 net/socket.c:3673
kernel_sendpage net/socket.c:3670 [inline]
sock_sendpage+0xe5/0x140 net/socket.c:1002
pipe_to_sendpage+0x2ad/0x380 fs/splice.c:364
splice_from_pipe_feed fs/splice.c:418 [inline]
__splice_from_pipe+0x43e/0x8a0 fs/splice.c:562
splice_from_pipe fs/splice.c:597 [inline]
generic_splice_sendpage+0xd4/0x140 fs/splice.c:746
do_splice_from fs/splice.c:767 [inline]
direct_splice_actor+0x110/0x180 fs/splice.c:936
splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891
do_splice_direct+0x1b3/0x280 fs/splice.c:979
do_sendfile+0x9f0/0x1120 fs/read_write.c:1260
__do_sys_sendfile64 fs/read_write.c:1325 [inline]
__se_sys_sendfile64 fs/read_write.c:1311 [inline]
__x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1311
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
RSP: 002b:00007f7ce8411188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000006 RDI: 0000000000000008
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000800100020001 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffcd2e54baf R14: 00007f7ce8411300 R15: 0000000000022000
INFO: task syz-executor.0:20404 blocked for more than 144 seconds.
Not tainted 5.13.0-next-20210708-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0 state:D stack:24416 pid:20404 ppid: 11499 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4700 [inline]
__schedule+0x93a/0x26f0 kernel/sched/core.c:5957
schedule+0xd3/0x270 kernel/sched/core.c:6036
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6095
__mutex_lock_common kernel/locking/mutex.c:1036 [inline]
__mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104
tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
inet_sendpage+0xd0/0x140 net/ipv4/af_inet.c:835
kernel_sendpage.part.0+0x1a0/0x340 net/socket.c:3673
kernel_sendpage net/socket.c:3670 [inline]
sock_sendpage+0xe5/0x140 net/socket.c:1002
pipe_to_sendpage+0x2ad/0x380 fs/splice.c:364
splice_from_pipe_feed fs/splice.c:418 [inline]
__splice_from_pipe+0x43e/0x8a0 fs/splice.c:562
splice_from_pipe fs/splice.c:597 [inline]
generic_splice_sendpage+0xd4/0x140 fs/splice.c:746
do_splice_from fs/splice.c:767 [inline]
direct_splice_actor+0x110/0x180 fs/splice.c:936
splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891
do_splice_direct+0x1b3/0x280 fs/splice.c:979
do_sendfile+0x9f0/0x1120 fs/read_write.c:1260
__do_sys_sendfile64 fs/read_write.c:1325 [inline]
__se_sys_sendfile64 fs/read_write.c:1311 [inline]
__x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1311
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
RSP: 002b:00007f7ce8411188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000006 RDI: 0000000000000008
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000800100020001 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffcd2e54baf R14: 00007f7ce8411300 R15: 0000000000022000
Showing all locks held in the system:
1 lock held by khungtaskd/1652:
#0: ffffffff8b97ea40 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6446
1 lock held by in:imklog/8161:
#0: ffff88802a58c5f0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:990
4 locks held by rs:main Q:Reg/8162:
#0: ffff8880b9c51a98 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested kernel/sched/core.c:460 [inline]
#0: ffff8880b9c51a98 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock kernel/sched/sched.h:1309 [inline]
#0: ffff8880b9c51a98 (&rq->__lock){-.-.}-{2:2}, at: rq_lock kernel/sched/sched.h:1612 [inline]
#0: ffff8880b9c51a98 (&rq->__lock){-.-.}-{2:2}, at: __schedule+0x233/0x26f0 kernel/sched/core.c:5871
#1: ffff8880b9c1f988 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x39d/0x480 kernel/sched/psi.c:880
#2: ffff88803576bfc0 (&sb->s_type->i_mutex_key#10){++++}-{3:3}, at: inode_lock include/linux/fs.h:779 [inline]
#2: ffff88803576bfc0 (&sb->s_type->i_mutex_key#10){++++}-{3:3}, at: ext4_buffered_write_iter+0xb6/0x4d0 fs/ext4/file.c:263
#3: ffff88803576c320 (&ei->i_raw_lock){+.+.}-{2:2}, at: spin_lock include/linux/spinlock.h:359 [inline]
#3: ffff88803576c320 (&ei->i_raw_lock){+.+.}-{2:2}, at: ext4_do_update_inode fs/ext4/inode.c:5032 [inline]
#3: ffff88803576c320 (&ei->i_raw_lock){+.+.}-{2:2}, at: ext4_mark_iloc_dirty+0x213/0x38d0 fs/ext4/inode.c:5723
3 locks held by kworker/0:8/9807:
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: atomic64_set include/asm-generic/atomic-instrumented.h:620 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: atomic_long_set include/asm-generic/atomic-long.h:41 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:617 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x871/0x1630 kernel/workqueue.c:2247
#1: ffffc9000bed7db0 ((work_completion)(&(&sw_ctx_tx->tx_work.work)->work)){+.+.}-{0:0}, at: process_one_work+0x8a5/0x1630 kernel/workqueue.c:2251
#2: ffff888011c29cd8 (&ctx->tx_lock){+.+.}-{3:3}, at: tx_work_handler+0x127/0x190 net/tls/tls_sw.c:2262
3 locks held by kworker/0:1/15983:
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: atomic64_set include/asm-generic/atomic-instrumented.h:620 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: atomic_long_set include/asm-generic/atomic-long.h:41 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:617 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x871/0x1630 kernel/workqueue.c:2247
#1: ffffc900090d7db0 ((work_completion)(&(&sw_ctx_tx->tx_work.work)->work)){+.+.}-{0:0}, at: process_one_work+0x8a5/0x1630 kernel/workqueue.c:2251
#2: ffff88802ebe28d8 (&ctx->tx_lock){+.+.}-{3:3}, at: tx_work_handler+0x127/0x190 net/tls/tls_sw.c:2262
1 lock held by syz-executor.2/20381:
#0: ffff88802ebe28d8 (&ctx->tx_lock){+.+.}-{3:3}, at: tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
1 lock held by syz-executor.0/20404:
#0: ffff888011c29cd8 (&ctx->tx_lock){+.+.}-{3:3}, at: tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 1652 Comm: khungtaskd Not tainted 5.13.0-next-20210708-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0xcd/0x134 lib/dump_stack.c:105
nmi_cpu_backtrace.cold+0x44/0xd7 lib/nmi_backtrace.c:105
nmi_trigger_cpumask_backtrace+0x1b3/0x230 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:254 [inline]
watchdog+0xd3e/0xf50 kernel/hung_task.c:339
kthread+0x3e5/0x4d0 kernel/kthread.c:319
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 18739 Comm: kworker/1:21 Not tainted 5.13.0-next-20210708-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: events nsim_dev_trap_report_work
RIP: 0010:mark_lock+0x131/0x17b0 kernel/locking/lockdep.c:4572
Code: c0 2c cd 8f 48 8d 78 50 48 ba 00 00 00 00 00 fc ff df 48 89 f9 48 c1 e9 03 80 3c 11 00 0f 85 15 12 00 00 48 89 ee 48 23 70 50 <41> ba 01 00 00 00 0f 84 79 01 00 00 48 b8 00 00 00 00 00 fc ff df
RSP: 0018:ffffc900021df990 EFLAGS: 00000002
RAX: ffffffff8fd26600 RBX: 1ffff9200043bf39 RCX: 1ffffffff1fa4cca
RDX: dffffc0000000000 RSI: 0000000000000004 RDI: ffffffff8fd26650
RBP: 0000000000000004 R08: 0000000000000000 R09: ffffffff8fcd297f
R10: fffffbfff1f9a52f R11: 00000000f6496ccc R12: 0000000000000002
R13: ffff88806c030a20 R14: 00000000000006f7 R15: ffff88806c030a40
FS: 0000000000000000(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f418404c000 CR3: 000000001fc40000 CR4: 00000000001506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
mark_held_locks+0x9f/0xe0 kernel/locking/lockdep.c:4194
__trace_hardirqs_on_caller kernel/locking/lockdep.c:4212 [inline]
lockdep_hardirqs_on_prepare kernel/locking/lockdep.c:4280 [inline]
lockdep_hardirqs_on_prepare+0x135/0x400 kernel/locking/lockdep.c:4232
trace_hardirqs_on+0x5b/0x1c0 kernel/trace/trace_preemptirq.c:49
__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline]
_raw_spin_unlock_irqrestore+0x50/0x70 kernel/locking/spinlock.c:191
extract_crng drivers/char/random.c:1016 [inline]
_get_random_bytes+0x229/0x670 drivers/char/random.c:1538
eth_random_addr include/linux/etherdevice.h:225 [inline]
nsim_dev_trap_skb_build drivers/net/netdevsim/dev.c:672 [inline]
nsim_dev_trap_report drivers/net/netdevsim/dev.c:721 [inline]
nsim_dev_trap_report_work+0x38a/0xbd0 drivers/net/netdevsim/dev.c:762
process_one_work+0x98d/0x1630 kernel/workqueue.c:2276
worker_thread+0x658/0x11f0 kernel/workqueue.c:2422
kthread+0x3e5/0x4d0 kernel/kthread.c:319
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
---
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: e2f74b13 Add linux-next specific files for 20210708
git tree: linux-next
console output: https://syzkaller.appspot.com/x/log.txt?x=16eede9c300000
kernel config: https://syzkaller.appspot.com/x/.config?x=59e1e3bbc3afca75
dashboard link: https://syzkaller.appspot.com/bug?extid=a0d9194812a32ec7d41f
CC: [and...@kernel.org a...@kernel.org bor...@nvidia.com b...@vger.kernel.org dan...@iogearbox.net da...@davemloft.net john.fa...@gmail.com ka...@fb.com kps...@kernel.org ku...@kernel.org linux-...@vger.kernel.org net...@vger.kernel.org songliu...@fb.com y...@fb.com]
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+a0d919...@syzkaller.appspotmail.com
INFO: task syz-executor.2:20381 can't die for more than 143 seconds.
task:syz-executor.2 state:D stack:23864 pid:20381 ppid: 32682 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4700 [inline]
__schedule+0x93a/0x26f0 kernel/sched/core.c:5957
schedule+0xd3/0x270 kernel/sched/core.c:6036
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6095
__mutex_lock_common kernel/locking/mutex.c:1036 [inline]
__mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104
tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
inet_sendpage+0xd0/0x140 net/ipv4/af_inet.c:835
kernel_sendpage.part.0+0x1a0/0x340 net/socket.c:3673
kernel_sendpage net/socket.c:3670 [inline]
sock_sendpage+0xe5/0x140 net/socket.c:1002
pipe_to_sendpage+0x2ad/0x380 fs/splice.c:364
splice_from_pipe_feed fs/splice.c:418 [inline]
__splice_from_pipe+0x43e/0x8a0 fs/splice.c:562
splice_from_pipe fs/splice.c:597 [inline]
generic_splice_sendpage+0xd4/0x140 fs/splice.c:746
do_splice_from fs/splice.c:767 [inline]
direct_splice_actor+0x110/0x180 fs/splice.c:936
splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891
do_splice_direct+0x1b3/0x280 fs/splice.c:979
do_sendfile+0x9f0/0x1120 fs/read_write.c:1260
__do_sys_sendfile64 fs/read_write.c:1325 [inline]
__se_sys_sendfile64 fs/read_write.c:1311 [inline]
__x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1311
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
RSP: 002b:00007fd0c5f8b188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000006 RDI: 0000000000000008
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000800100020001 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffd250cf62f R14: 00007fd0c5f8b300 R15: 0000000000022000
INFO: task syz-executor.2:20381 blocked for more than 143 seconds.
Not tainted 5.13.0-next-20210708-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.2 state:D stack:23864 pid:20381 ppid: 32682 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4700 [inline]
__schedule+0x93a/0x26f0 kernel/sched/core.c:5957
schedule+0xd3/0x270 kernel/sched/core.c:6036
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6095
__mutex_lock_common kernel/locking/mutex.c:1036 [inline]
__mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104
tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
inet_sendpage+0xd0/0x140 net/ipv4/af_inet.c:835
kernel_sendpage.part.0+0x1a0/0x340 net/socket.c:3673
kernel_sendpage net/socket.c:3670 [inline]
sock_sendpage+0xe5/0x140 net/socket.c:1002
pipe_to_sendpage+0x2ad/0x380 fs/splice.c:364
splice_from_pipe_feed fs/splice.c:418 [inline]
__splice_from_pipe+0x43e/0x8a0 fs/splice.c:562
splice_from_pipe fs/splice.c:597 [inline]
generic_splice_sendpage+0xd4/0x140 fs/splice.c:746
do_splice_from fs/splice.c:767 [inline]
direct_splice_actor+0x110/0x180 fs/splice.c:936
splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891
do_splice_direct+0x1b3/0x280 fs/splice.c:979
do_sendfile+0x9f0/0x1120 fs/read_write.c:1260
__do_sys_sendfile64 fs/read_write.c:1325 [inline]
__se_sys_sendfile64 fs/read_write.c:1311 [inline]
__x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1311
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
RSP: 002b:00007fd0c5f8b188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000006 RDI: 0000000000000008
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000800100020001 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffd250cf62f R14: 00007fd0c5f8b300 R15: 0000000000022000
INFO: task syz-executor.0:20404 can't die for more than 143 seconds.
task:syz-executor.0 state:D stack:24416 pid:20404 ppid: 11499 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4700 [inline]
__schedule+0x93a/0x26f0 kernel/sched/core.c:5957
schedule+0xd3/0x270 kernel/sched/core.c:6036
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6095
__mutex_lock_common kernel/locking/mutex.c:1036 [inline]
__mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104
tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
inet_sendpage+0xd0/0x140 net/ipv4/af_inet.c:835
kernel_sendpage.part.0+0x1a0/0x340 net/socket.c:3673
kernel_sendpage net/socket.c:3670 [inline]
sock_sendpage+0xe5/0x140 net/socket.c:1002
pipe_to_sendpage+0x2ad/0x380 fs/splice.c:364
splice_from_pipe_feed fs/splice.c:418 [inline]
__splice_from_pipe+0x43e/0x8a0 fs/splice.c:562
splice_from_pipe fs/splice.c:597 [inline]
generic_splice_sendpage+0xd4/0x140 fs/splice.c:746
do_splice_from fs/splice.c:767 [inline]
direct_splice_actor+0x110/0x180 fs/splice.c:936
splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891
do_splice_direct+0x1b3/0x280 fs/splice.c:979
do_sendfile+0x9f0/0x1120 fs/read_write.c:1260
__do_sys_sendfile64 fs/read_write.c:1325 [inline]
__se_sys_sendfile64 fs/read_write.c:1311 [inline]
__x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1311
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
RSP: 002b:00007f7ce8411188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000006 RDI: 0000000000000008
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000800100020001 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffcd2e54baf R14: 00007f7ce8411300 R15: 0000000000022000
INFO: task syz-executor.0:20404 blocked for more than 144 seconds.
Not tainted 5.13.0-next-20210708-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.0 state:D stack:24416 pid:20404 ppid: 11499 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4700 [inline]
__schedule+0x93a/0x26f0 kernel/sched/core.c:5957
schedule+0xd3/0x270 kernel/sched/core.c:6036
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6095
__mutex_lock_common kernel/locking/mutex.c:1036 [inline]
__mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104
tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
inet_sendpage+0xd0/0x140 net/ipv4/af_inet.c:835
kernel_sendpage.part.0+0x1a0/0x340 net/socket.c:3673
kernel_sendpage net/socket.c:3670 [inline]
sock_sendpage+0xe5/0x140 net/socket.c:1002
pipe_to_sendpage+0x2ad/0x380 fs/splice.c:364
splice_from_pipe_feed fs/splice.c:418 [inline]
__splice_from_pipe+0x43e/0x8a0 fs/splice.c:562
splice_from_pipe fs/splice.c:597 [inline]
generic_splice_sendpage+0xd4/0x140 fs/splice.c:746
do_splice_from fs/splice.c:767 [inline]
direct_splice_actor+0x110/0x180 fs/splice.c:936
splice_direct_to_actor+0x34b/0x8c0 fs/splice.c:891
do_splice_direct+0x1b3/0x280 fs/splice.c:979
do_sendfile+0x9f0/0x1120 fs/read_write.c:1260
__do_sys_sendfile64 fs/read_write.c:1325 [inline]
__se_sys_sendfile64 fs/read_write.c:1311 [inline]
__x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1311
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x44/0xae
RIP: 0033:0x4665d9
RSP: 002b:00007f7ce8411188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000056c038 RCX: 00000000004665d9
RDX: 0000000000000000 RSI: 0000000000000006 RDI: 0000000000000008
RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000800100020001 R11: 0000000000000246 R12: 000000000056c038
R13: 00007ffcd2e54baf R14: 00007f7ce8411300 R15: 0000000000022000
Showing all locks held in the system:
1 lock held by khungtaskd/1652:
#0: ffffffff8b97ea40 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6446
1 lock held by in:imklog/8161:
#0: ffff88802a58c5f0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:990
4 locks held by rs:main Q:Reg/8162:
#0: ffff8880b9c51a98 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested kernel/sched/core.c:460 [inline]
#0: ffff8880b9c51a98 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock kernel/sched/sched.h:1309 [inline]
#0: ffff8880b9c51a98 (&rq->__lock){-.-.}-{2:2}, at: rq_lock kernel/sched/sched.h:1612 [inline]
#0: ffff8880b9c51a98 (&rq->__lock){-.-.}-{2:2}, at: __schedule+0x233/0x26f0 kernel/sched/core.c:5871
#1: ffff8880b9c1f988 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x39d/0x480 kernel/sched/psi.c:880
#2: ffff88803576bfc0 (&sb->s_type->i_mutex_key#10){++++}-{3:3}, at: inode_lock include/linux/fs.h:779 [inline]
#2: ffff88803576bfc0 (&sb->s_type->i_mutex_key#10){++++}-{3:3}, at: ext4_buffered_write_iter+0xb6/0x4d0 fs/ext4/file.c:263
#3: ffff88803576c320 (&ei->i_raw_lock){+.+.}-{2:2}, at: spin_lock include/linux/spinlock.h:359 [inline]
#3: ffff88803576c320 (&ei->i_raw_lock){+.+.}-{2:2}, at: ext4_do_update_inode fs/ext4/inode.c:5032 [inline]
#3: ffff88803576c320 (&ei->i_raw_lock){+.+.}-{2:2}, at: ext4_mark_iloc_dirty+0x213/0x38d0 fs/ext4/inode.c:5723
3 locks held by kworker/0:8/9807:
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: atomic64_set include/asm-generic/atomic-instrumented.h:620 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: atomic_long_set include/asm-generic/atomic-long.h:41 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:617 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x871/0x1630 kernel/workqueue.c:2247
#1: ffffc9000bed7db0 ((work_completion)(&(&sw_ctx_tx->tx_work.work)->work)){+.+.}-{0:0}, at: process_one_work+0x8a5/0x1630 kernel/workqueue.c:2251
#2: ffff888011c29cd8 (&ctx->tx_lock){+.+.}-{3:3}, at: tx_work_handler+0x127/0x190 net/tls/tls_sw.c:2262
3 locks held by kworker/0:1/15983:
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: atomic64_set include/asm-generic/atomic-instrumented.h:620 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: atomic_long_set include/asm-generic/atomic-long.h:41 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:617 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline]
#0: ffff888010864d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x871/0x1630 kernel/workqueue.c:2247
#1: ffffc900090d7db0 ((work_completion)(&(&sw_ctx_tx->tx_work.work)->work)){+.+.}-{0:0}, at: process_one_work+0x8a5/0x1630 kernel/workqueue.c:2251
#2: ffff88802ebe28d8 (&ctx->tx_lock){+.+.}-{3:3}, at: tx_work_handler+0x127/0x190 net/tls/tls_sw.c:2262
1 lock held by syz-executor.2/20381:
#0: ffff88802ebe28d8 (&ctx->tx_lock){+.+.}-{3:3}, at: tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
1 lock held by syz-executor.0/20404:
#0: ffff888011c29cd8 (&ctx->tx_lock){+.+.}-{3:3}, at: tls_sw_sendpage+0x7f/0xe0 net/tls/tls_sw.c:1276
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 1652 Comm: khungtaskd Not tainted 5.13.0-next-20210708-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0xcd/0x134 lib/dump_stack.c:105
nmi_cpu_backtrace.cold+0x44/0xd7 lib/nmi_backtrace.c:105
nmi_trigger_cpumask_backtrace+0x1b3/0x230 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:254 [inline]
watchdog+0xd3e/0xf50 kernel/hung_task.c:339
kthread+0x3e5/0x4d0 kernel/kthread.c:319
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 18739 Comm: kworker/1:21 Not tainted 5.13.0-next-20210708-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: events nsim_dev_trap_report_work
RIP: 0010:mark_lock+0x131/0x17b0 kernel/locking/lockdep.c:4572
Code: c0 2c cd 8f 48 8d 78 50 48 ba 00 00 00 00 00 fc ff df 48 89 f9 48 c1 e9 03 80 3c 11 00 0f 85 15 12 00 00 48 89 ee 48 23 70 50 <41> ba 01 00 00 00 0f 84 79 01 00 00 48 b8 00 00 00 00 00 fc ff df
RSP: 0018:ffffc900021df990 EFLAGS: 00000002
RAX: ffffffff8fd26600 RBX: 1ffff9200043bf39 RCX: 1ffffffff1fa4cca
RDX: dffffc0000000000 RSI: 0000000000000004 RDI: ffffffff8fd26650
RBP: 0000000000000004 R08: 0000000000000000 R09: ffffffff8fcd297f
R10: fffffbfff1f9a52f R11: 00000000f6496ccc R12: 0000000000000002
R13: ffff88806c030a20 R14: 00000000000006f7 R15: ffff88806c030a40
FS: 0000000000000000(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f418404c000 CR3: 000000001fc40000 CR4: 00000000001506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
mark_held_locks+0x9f/0xe0 kernel/locking/lockdep.c:4194
__trace_hardirqs_on_caller kernel/locking/lockdep.c:4212 [inline]
lockdep_hardirqs_on_prepare kernel/locking/lockdep.c:4280 [inline]
lockdep_hardirqs_on_prepare+0x135/0x400 kernel/locking/lockdep.c:4232
trace_hardirqs_on+0x5b/0x1c0 kernel/trace/trace_preemptirq.c:49
__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline]
_raw_spin_unlock_irqrestore+0x50/0x70 kernel/locking/spinlock.c:191
extract_crng drivers/char/random.c:1016 [inline]
_get_random_bytes+0x229/0x670 drivers/char/random.c:1538
eth_random_addr include/linux/etherdevice.h:225 [inline]
nsim_dev_trap_skb_build drivers/net/netdevsim/dev.c:672 [inline]
nsim_dev_trap_report drivers/net/netdevsim/dev.c:721 [inline]
nsim_dev_trap_report_work+0x38a/0xbd0 drivers/net/netdevsim/dev.c:762
process_one_work+0x98d/0x1630 kernel/workqueue.c:2276
worker_thread+0x658/0x11f0 kernel/workqueue.c:2422
kthread+0x3e5/0x4d0 kernel/kthread.c:319
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295
---
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
Nov 19, 2021, 11:49:24 PM11/19/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