INFO: task syz-executor.1:16463 blocked for more than 143 seconds.
Not tainted 5.16.0-rc1-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.1 state:D stack:24264 pid:16463 ppid: 15851 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4972 [inline]
__schedule+0xa9a/0x4940 kernel/sched/core.c:6253
schedule+0xd2/0x260 kernel/sched/core.c:6326
__bio_queue_enter+0x3c5/0x6a0 block/blk-core.c:441
bio_queue_enter block/blk.h:91 [inline]
blk_mq_get_request block/blk-mq.c:2588 [inline]
blk_mq_submit_bio+0x4ae/0x22c0 block/blk-mq.c:2632
__submit_bio block/blk-core.c:849 [inline]
__submit_bio_noacct_mq block/blk-core.c:924 [inline]
submit_bio_noacct block/blk-core.c:950 [inline]
submit_bio_noacct+0x82c/0xa20 block/blk-core.c:939
submit_bio block/blk-core.c:1011 [inline]
submit_bio+0x1ea/0x430 block/blk-core.c:969
mpage_bio_submit fs/mpage.c:66 [inline]
mpage_readahead+0x589/0x750 fs/mpage.c:393
read_pages+0x1e4/0xfa0 mm/readahead.c:129
page_cache_ra_unbounded+0x64b/0x940 mm/readahead.c:238
do_page_cache_ra mm/readahead.c:268 [inline]
ondemand_readahead+0x594/0x1090 mm/readahead.c:551
page_cache_sync_ra+0x1cb/0x200 mm/readahead.c:579
page_cache_sync_readahead include/linux/pagemap.h:1013 [inline]
filemap_get_pages+0x2b2/0x1be0 mm/filemap.c:2556
filemap_read+0x315/0xeb0 mm/filemap.c:2642
generic_file_read_iter+0x3b6/0x5a0 mm/filemap.c:2792
blkdev_read_iter+0xf8/0x1d0 block/fops.c:583
call_read_iter include/linux/fs.h:2156 [inline]
generic_file_splice_read+0x453/0x6d0 fs/splice.c:311
do_splice_to+0x1bf/0x250 fs/splice.c:796
splice_direct_to_actor+0x2c2/0x8c0 fs/splice.c:870
do_splice_direct+0x1b3/0x280 fs/splice.c:979
do_sendfile+0xaf2/0x1250 fs/read_write.c:1245
__do_sys_sendfile64 fs/read_write.c:1310 [inline]
__se_sys_sendfile64 fs/read_write.c:1296 [inline]
__x64_sys_sendfile64+0x1cc/0x210 fs/read_write.c:1296
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:0x7f6fa9a18ae9
RSP: 002b:00007f6fa6f8e188 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 00007f6fa9b2bf60 RCX: 00007f6fa9a18ae9
RDX: 0000000000000000 RSI: 0000000000000004 RDI: 0000000000000004
RBP: 00007f6fa9a72f6d R08: 0000000000000000 R09: 0000000000000000
R10: 0000000024002da8 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffd5eb56bbf R14: 00007f6fa6f8e300 R15: 0000000000022000
INFO: task syz-executor.1:16465 blocked for more than 143 seconds.
Not tainted 5.16.0-rc1-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.1 state:D stack:27712 pid:16465 ppid: 15851 flags:0x00004004
Call Trace:
context_switch kernel/sched/core.c:4972 [inline]
__schedule+0xa9a/0x4940 kernel/sched/core.c:6253
schedule+0xd2/0x260 kernel/sched/core.c:6326
blk_mq_freeze_queue_wait+0x112/0x160 block/blk-mq.c:178
loop_set_status+0x440/0x930 drivers/block/loop.c:1264
loop_set_status_old+0x148/0x1b0 drivers/block/loop.c:1396
lo_ioctl+0x3e9/0x17c0 drivers/block/loop.c:1566
blkdev_ioctl+0x37a/0x800 block/ioctl.c:609
vfs_ioctl fs/ioctl.c:51 [inline]
__do_sys_ioctl fs/ioctl.c:874 [inline]
__se_sys_ioctl fs/ioctl.c:860 [inline]
__x64_sys_ioctl+0x193/0x200 fs/ioctl.c:860
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:0x7f6fa9a18ae9
RSP: 002b:00007f6fa6f6d188 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007f6fa9b2c020 RCX: 00007f6fa9a18ae9
RDX: 0000000020000940 RSI: 0000000000004c02 RDI: 0000000000000004
RBP: 00007f6fa9a72f6d R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffd5eb56bbf R14: 00007f6fa6f6d300 R15: 0000000000022000
Showing all locks held in the system:
1 lock held by khungtaskd/27:
#0: ffffffff8bb83ae0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6458
1 lock held by in:imklog/6216:
#0: ffff88801c4119f0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:990
5 locks held by kworker/u4:7/11038:
2 locks held by systemd-udevd/16455:
#0: ffff88801ac5f918 (&disk->open_mutex){+.+.}-{3:3}, at: blkdev_get_by_dev.part.0+0x9b/0xb50 block/bdev.c:819
#1: ffff88801ac5b360 (&lo->lo_mutex){+.+.}-{3:3}, at: lo_open+0x75/0x120 drivers/block/loop.c:1733
1 lock held by syz-executor.1/16463:
#0: ffff8880190ef348 (mapping.invalidate_lock#2){.+.+}-{3:3}, at: filemap_invalidate_lock_shared include/linux/fs.h:838 [inline]
#0: ffff8880190ef348 (mapping.invalidate_lock#2){.+.+}-{3:3}, at: page_cache_ra_unbounded+0x1b3/0x940 mm/readahead.c:194
1 lock held by syz-executor.1/16465:
#0: ffff88801ac5b360 (&lo->lo_mutex){+.+.}-{3:3}, at: loop_set_status+0x2a/0x930 drivers/block/loop.c:1248
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 27 Comm: khungtaskd Not tainted 5.16.0-rc1-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:106
nmi_cpu_backtrace.cold+0x47/0x144 lib/nmi_backtrace.c:105
nmi_trigger_cpumask_backtrace+0x1ae/0x220 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:210 [inline]
watchdog+0xc1d/0xf50 kernel/hung_task.c:295
kthread+0x405/0x4f0 kernel/kthread.c:327
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: 6216 Comm: in:imklog Not tainted 5.16.0-rc1-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:check_kcov_mode kernel/kcov.c:166 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0x7/0x60 kernel/kcov.c:200
Code: 46 00 5d be 03 00 00 00 e9 96 0c 63 02 66 0f 1f 44 00 00 48 8b be b0 01 00 00 e8 b4 ff ff ff 31 c0 c3 90 65 8b 05 19 88 8a 7e <89> c1 48 8b 34 24 81 e1 00 01 00 00 65 48 8b 14 25 40 70 02 00 a9
RSP: 0018:ffffc9000c5c76c8 EFLAGS: 00000246
RAX: 0000000080000000 RBX: ffffffff89acc926 RCX: 0000000000000005
RDX: 0000000000000000 RSI: ffff88801eee8000 RDI: 0000000000000003
RBP: ffffc9000c5c77e8 R08: ffffffff8a08e2a0 R09: ffffffff840dce1a
R10: 0000000000000005 R11: 0000000000000030 R12: ffffffff89acc927
R13: ffffffff89acc926 R14: 1ffff920018b8edd R15: 0000000000000030
FS: 00007f77e2b0f700(0000) GS:ffff8880b9d00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f31ad4bf000 CR3: 000000007ec6d000 CR4: 0000000000350ee0
Call Trace:
format_decode+0x715/0xad0 lib/vsprintf.c:2561
vsnprintf+0x157/0x14f0 lib/vsprintf.c:2757
sprintf+0xc0/0x100 lib/vsprintf.c:3007
print_time kernel/printk/printk.c:1261 [inline]
info_print_prefix+0x248/0x340 kernel/printk/printk.c:1287
record_print_text+0x14d/0x3e0 kernel/printk/printk.c:1336
syslog_print+0x48a/0x580 kernel/printk/printk.c:1536
do_syslog.part.0+0x202/0x640 kernel/printk/printk.c:1655
do_syslog+0x49/0x60 kernel/printk/printk.c:1640
kmsg_read+0x90/0xb0 fs/proc/kmsg.c:40
pde_read fs/proc/inode.c:311 [inline]
proc_reg_read+0x119/0x300 fs/proc/inode.c:321
vfs_read+0x1b5/0x600 fs/read_write.c:479
ksys_read+0x12d/0x250 fs/read_write.c:619
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:0x7f77e515222d
Code: c1 20 00 00 75 10 b8 00 00 00 00 0f 05 48 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 4e fc ff ff 48 89 04 24 b8 00 00 00 00 0f 05 <48> 8b 3c 24 48 89 c2 e8 97 fc ff ff 48 89 d0 48 83 c4 08 48 3d 01
RSP: 002b:00007f77e2aee580 EFLAGS: 00000293 ORIG_RAX: 0000000000000000
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f77e515222d
RDX: 0000000000001fa0 RSI: 00007f77e2aeeda0 RDI: 0000000000000004
RBP: 000055a3d7c3e9d0 R08: 0000000000000000 R09: 0000000004000001
R10: 0000000000000001 R11: 0000000000000293 R12: 00007f77e2aeeda0
R13: 0000000000001fa0 R14: 0000000000001f9f R15: 00007f77e2aeedbb
----------------
Code disassembly (best guess):
0: 46 00 5d be rex.RX add %r11b,-0x42(%rbp)
4: 03 00 add (%rax),%eax
6: 00 00 add %al,(%rax)
8: e9 96 0c 63 02 jmpq 0x2630ca3
d: 66 0f 1f 44 00 00 nopw 0x0(%rax,%rax,1)
13: 48 8b be b0 01 00 00 mov 0x1b0(%rsi),%rdi
1a: e8 b4 ff ff ff callq 0xffffffd3
1f: 31 c0 xor %eax,%eax
21: c3 retq
22: 90 nop
23: 65 8b 05 19 88 8a 7e mov %gs:0x7e8a8819(%rip),%eax # 0x7e8a8843
* 2a: 89 c1 mov %eax,%ecx <-- trapping instruction
2c: 48 8b 34 24 mov (%rsp),%rsi
30: 81 e1 00 01 00 00 and $0x100,%ecx
36: 65 48 8b 14 25 40 70 mov %gs:0x27040,%rdx
3d: 02 00
3f: a9 .byte 0xa9