audit: type=1800 audit(703.270:351): pid=21252 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor4" name="file0" dev="sda1" ino=17023 res=0
MINIX-fs: mounting unchecked file system, running fsck is recommended
minix_free_inode: bit 1 already cleared
INFO: task syz-executor0:21201 blocked for more than 140 seconds.
Not tainted 4.20.0-rc4+ #352
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor0 D25256 21201 6028 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2831 [inline]
__schedule+0x8cf/0x21d0 kernel/sched/core.c:3472
schedule+0xfe/0x460 kernel/sched/core.c:3516
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3574
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0xaff/0x16f0 kernel/locking/mutex.c:1072
mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1087
lock_fat fs/fat/fatent.c:279 [inline]
fat_trim_fs+0x4a0/0x1450 fs/fat/fatent.c:733
fat_ioctl_fitrim fs/fat/file.c:145 [inline]
fat_generic_ioctl+0x290/0x17c0 fs/fat/file.c:168
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:509 [inline]
do_vfs_ioctl+0x1de/0x1790 fs/ioctl.c:696
ksys_ioctl+0xa9/0xd0 fs/ioctl.c:713
__do_sys_ioctl fs/ioctl.c:720 [inline]
__se_sys_ioctl fs/ioctl.c:718 [inline]
__x64_sys_ioctl+0x73/0xb0 fs/ioctl.c:718
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x457569
Code: fd b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 0f 83 cb b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007ff2dbb39c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000000457569
RDX: 0000000020000100 RSI: 00000000c0185879 RDI: 0000000000000005
RBP: 000000000072bfa0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007ff2dbb3a6d4
R13: 00000000004bf74b R14: 00000000004cff50 R15: 00000000ffffffff
INFO: task syz-executor0:21231 blocked for more than 140 seconds.
Not tainted 4.20.0-rc4+ #352
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor0 D25208 21231 6028 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2831 [inline]
__schedule+0x8cf/0x21d0 kernel/sched/core.c:3472
schedule+0xfe/0x460 kernel/sched/core.c:3516
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3574
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0xaff/0x16f0 kernel/locking/mutex.c:1072
mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1087
lock_fat fs/fat/fatent.c:279 [inline]
fat_trim_fs+0x4a0/0x1450 fs/fat/fatent.c:733
fat_ioctl_fitrim fs/fat/file.c:145 [inline]
fat_generic_ioctl+0x290/0x17c0 fs/fat/file.c:168
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:509 [inline]
do_vfs_ioctl+0x1de/0x1790 fs/ioctl.c:696
ksys_ioctl+0xa9/0xd0 fs/ioctl.c:713
__do_sys_ioctl fs/ioctl.c:720 [inline]
__se_sys_ioctl fs/ioctl.c:718 [inline]
__x64_sys_ioctl+0x73/0xb0 fs/ioctl.c:718
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x457569
Code: fd b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 0f 83 cb b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007ff2dbb18c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000000457569
RDX: 0000000020000180 RSI: 00000000c0185879 RDI: 0000000000000008
RBP: 000000000072c040 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007ff2dbb196d4
R13: 00000000004bf74b R14: 00000000004cff50 R15: 00000000ffffffff
INFO: task syz-executor0:21235 blocked for more than 140 seconds.
Not tainted 4.20.0-rc4+ #352
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor0 D25208 21235 6028 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2831 [inline]
__schedule+0x8cf/0x21d0 kernel/sched/core.c:3472
schedule+0xfe/0x460 kernel/sched/core.c:3516
schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3574
__mutex_lock_common kernel/locking/mutex.c:1002 [inline]
__mutex_lock+0xaff/0x16f0 kernel/locking/mutex.c:1072
mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1087
lock_fat fs/fat/fatent.c:279 [inline]
fat_trim_fs+0x4a0/0x1450 fs/fat/fatent.c:733
fat_ioctl_fitrim fs/fat/file.c:145 [inline]
fat_generic_ioctl+0x290/0x17c0 fs/fat/file.c:168
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:509 [inline]
do_vfs_ioctl+0x1de/0x1790 fs/ioctl.c:696
ksys_ioctl+0xa9/0xd0 fs/ioctl.c:713
__do_sys_ioctl fs/ioctl.c:720 [inline]
__se_sys_ioctl fs/ioctl.c:718 [inline]
__x64_sys_ioctl+0x73/0xb0 fs/ioctl.c:718
do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x457569
Code: fd b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 0f 83 cb b3 fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007ff2dbaf7c78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 0000000000457569
RDX: 0000000020000100 RSI: 00000000c0185879 RDI: 0000000000000008
RBP: 000000000072c0e0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007ff2dbaf86d4
R13: 00000000004bf74b R14: 00000000004cff50 R15: 00000000ffffffff
Showing all locks held in the system:
4 locks held by kworker/u4:2/44:
1 lock held by khungtaskd/1019:
#0: 00000000b69504ea (rcu_read_lock){....}, at: debug_show_all_locks+0xd0/0x424 kernel/locking/lockdep.c:4379
3 locks held by rs:main Q:Reg/5895:
#0: 000000003edbe440 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1126 [inline]
#0: 000000003edbe440 (&rq->lock){-.-.}, at: __schedule+0x236/0x21d0 kernel/sched/core.c:3410
#1: 00000000b69504ea (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:418 [inline]
#1: 00000000b69504ea (rcu_read_lock){....}, at: update_curr+0x383/0xbd0 kernel/sched/fair.c:830
#2: 000000002553d749 (&sb->s_type->i_mutex_key#10){++++}, at: inode_trylock include/linux/fs.h:777 [inline]
#2: 000000002553d749 (&sb->s_type->i_mutex_key#10){++++}, at: ext4_file_write_iter+0x2a1/0x1420 fs/ext4/file.c:232
1 lock held by rsyslogd/5897:
#0: 00000000b76e3971 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1bb/0x200 fs/file.c:766
2 locks held by getty/5988:
#0: 000000000b3c480f (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000001e9f72a1 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/5989:
#0: 00000000fe0b027d (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 00000000a72889ff (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/5990:
#0: 000000002b21b0d3 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 000000004043af2f (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/5991:
#0: 00000000fc0d3b4d (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 00000000f3e9dff5 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/5992:
#0: 000000000a25e5f0 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 0000000024f7844e (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/5993:
#0: 0000000011ece2dd (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 00000000acdff7fb (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
2 locks held by getty/5994:
#0: 000000005453010d (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353
#1: 00000000f2709eec (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1e80 drivers/tty/n_tty.c:2154
1 lock held by syz-executor0/21197:
1 lock held by syz-executor0/21201:
#0: 0000000043f6c166 (&sbi->fat_lock){+.+.}, at: lock_fat fs/fat/fatent.c:279 [inline]
#0: 0000000043f6c166 (&sbi->fat_lock){+.+.}, at: fat_trim_fs+0x4a0/0x1450 fs/fat/fatent.c:733
1 lock held by syz-executor0/21231:
#0: 0000000043f6c166 (&sbi->fat_lock){+.+.}, at: lock_fat fs/fat/fatent.c:279 [inline]
#0: 0000000043f6c166 (&sbi->fat_lock){+.+.}, at: fat_trim_fs+0x4a0/0x1450 fs/fat/fatent.c:733
1 lock held by syz-executor0/21235:
#0: 0000000043f6c166 (&sbi->fat_lock){+.+.}, at: lock_fat fs/fat/fatent.c:279 [inline]
#0: 0000000043f6c166 (&sbi->fat_lock){+.+.}, at: fat_trim_fs+0x4a0/0x1450 fs/fat/fatent.c:733
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 1019 Comm: khungtaskd Not tainted 4.20.0-rc4+ #352
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+0x244/0x39d lib/dump_stack.c:113
nmi_cpu_backtrace.cold.2+0x5c/0xa1 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x1e8/0x22a 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:205 [inline]
watchdog+0xb51/0x1060 kernel/hung_task.c:289
kthread+0x35a/0x440 kernel/kthread.c:246
ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352
Sending NMI from CPU 0 to CPUs 1:
INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 2.603 msecs
NMI backtrace for cpu 1
CPU: 1 PID: 44 Comm: kworker/u4:2 Not tainted 4.20.0-rc4+ #352
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: writeback wb_workfn (flush-7:0)
RIP: 0010:rcu_read_lock_held+0x87/0xc0 kernel/rcu/update.c:277
Code: 83 c0 03 38 d0 7c 04 84 d2 75 4d 8b 05 d6 97 ad 08 85 c0 74 09 e8 29 e7 ff ff 85 c0 75 07 b8 01 00 00 00 5d c3 e8 a9 a5 01 00 <84> c0 74 09 e8 20 19 01 00 84 c0 75 04 31 c0 5d c3 be ff ff ff ff
RSP: 0018:ffff8881d95a5b70 EFLAGS: 00000286
RAX: 0000000000000001 RBX: ffff8881d95a5d70 RCX: 1ffff1103b2b4b5e
RDX: dffffc0000000000 RSI: 0000000000000000 RDI: ffff8881d95a5af0
RBP: ffff8881d95a5b70 R08: ffffed103b5e5b68 R09: ffffed103b5e5b67
R10: ffffed103b5e5b67 R11: ffff8881daf2db3b R12: 0000000000000008
R13: ffff8881d5bce3c0 R14: ffffea0006bc7c40 R15: ffff8881d95a5c40
FS: 0000000000000000(0000) GS:ffff8881daf00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: ffffffffff600400 CR3: 00000001c28e7000 CR4: 00000000001426e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
Call Trace:
xa_entry include/linux/xarray.h:848 [inline]
xas_descend+0x413/0x510 lib/xarray.c:198
xas_load+0x145/0x1e0 lib/xarray.c:234
find_get_entry+0x3ca/0x1120 mm/filemap.c:1415
pagecache_get_page+0x12f/0xf00 mm/filemap.c:1518
find_or_create_page include/linux/pagemap.h:322 [inline]
grow_dev_page fs/buffer.c:947 [inline]
grow_buffers fs/buffer.c:1016 [inline]
__getblk_slow fs/buffer.c:1043 [inline]
__getblk_gfp+0x3aa/0xd50 fs/buffer.c:1320
__bread_gfp+0x2d/0x310 fs/buffer.c:1354
sb_bread include/linux/buffer_head.h:307 [inline]
__fat_write_inode+0x347/0xb60 fs/fat/inode.c:859
fat_write_inode+0x97/0x180 fs/fat/inode.c:908
write_inode fs/fs-writeback.c:1176 [inline]
__writeback_single_inode+0xf56/0x1660 fs/fs-writeback.c:1375
writeback_sb_inodes+0x71f/0x1210 fs/fs-writeback.c:1580
__writeback_inodes_wb+0x1b9/0x340 fs/fs-writeback.c:1649
wb_writeback+0xa73/0xfc0 fs/fs-writeback.c:1758
wb_check_old_data_flush fs/fs-writeback.c:1860 [inline]
wb_do_writeback fs/fs-writeback.c:1913 [inline]
wb_workfn+0x1008/0x1790 fs/fs-writeback.c:1942
process_one_work+0xc90/0x1c40 kernel/workqueue.c:2153
worker_thread+0x17f/0x1390 kernel/workqueue.c:2296
kthread+0x35a/0x440 kernel/kthread.c:246
ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352