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