TSC Offset = 0xffffff81d7f2bf8a EPT pointer = 0x00000000b299601e Virtual processor ID = 0x0001 FAT-fs (loop2): Unrecognized mount option "dSu¹t©ãäkts" or missing value ====================================================== WARNING: possible circular locking dependency detected 4.14.212-syzkaller #0 Not tainted ------------------------------------------------------ kworker/0:5/11279 is trying to acquire lock: (&sb->s_type->i_mutex_key#10){++++}, at: [] inode_lock include/linux/fs.h:719 [inline] (&sb->s_type->i_mutex_key#10){++++}, at: [] __generic_file_fsync+0x9e/0x190 fs/libfs.c:989 netlink: 304 bytes leftover after parsing attributes in process `syz-executor.5'. but task is already holding lock: ((&dio->complete_work)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2091 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #2 ((&dio->complete_work)){+.+.}: process_one_work+0x736/0x14a0 kernel/workqueue.c:2092 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2250 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 -> #1 ("dio/%s"sb->s_id){+.+.}: flush_workqueue+0xfa/0x1310 kernel/workqueue.c:2624 drain_workqueue+0x177/0x3e0 kernel/workqueue.c:2789 destroy_workqueue+0x71/0x710 kernel/workqueue.c:4102 __alloc_workqueue_key+0xd50/0x1080 kernel/workqueue.c:4079 sb_init_dio_done_wq+0x34/0x80 fs/direct-io.c:624 dio_set_defer_completion fs/direct-io.c:647 [inline] get_more_blocks fs/direct-io.c:725 [inline] do_direct_IO fs/direct-io.c:1003 [inline] do_blockdev_direct_IO fs/direct-io.c:1336 [inline] __blockdev_direct_IO+0x77db/0xdc60 fs/direct-io.c:1422 ext4_direct_IO_write fs/ext4/inode.c:3716 [inline] ext4_direct_IO+0x888/0x1b80 fs/ext4/inode.c:3857 generic_file_direct_write+0x1df/0x420 mm/filemap.c:2958 __generic_file_write_iter+0x2a2/0x590 mm/filemap.c:3137 ext4_file_write_iter+0x276/0xd20 fs/ext4/file.c:270 call_write_iter include/linux/fs.h:1778 [inline] aio_write+0x2ed/0x560 fs/aio.c:1553 io_submit_one fs/aio.c:1641 [inline] do_io_submit+0x847/0x1570 fs/aio.c:1709 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb -> #0 (&sb->s_type->i_mutex_key#10){++++}: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 down_write+0x34/0x90 kernel/locking/rwsem.c:54 inode_lock include/linux/fs.h:719 [inline] __generic_file_fsync+0x9e/0x190 fs/libfs.c:989 ext4_sync_file+0x8ed/0x12c0 fs/ext4/fsync.c:118 vfs_fsync_range+0x103/0x260 fs/sync.c:196 generic_write_sync include/linux/fs.h:2682 [inline] dio_complete+0x561/0x8d0 fs/direct-io.c:330 process_one_work+0x793/0x14a0 kernel/workqueue.c:2116 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2250 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 other info that might help us debug this: Chain exists of: &sb->s_type->i_mutex_key#10 --> "dio/%s"sb->s_id --> (&dio->complete_work) Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock((&dio->complete_work)); lock("dio/%s"sb->s_id); lock((&dio->complete_work)); lock(&sb->s_type->i_mutex_key#10); *** DEADLOCK *** 2 locks held by kworker/0:5/11279: #0: ("dio/%s"sb->s_id){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2087 #1: ((&dio->complete_work)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2091 stack backtrace: CPU: 0 PID: 11279 Comm: kworker/0:5 Not tainted 4.14.212-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Workqueue: dio/sda1 dio_aio_complete_work Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x283 lib/dump_stack.c:58 print_circular_bug.constprop.0.cold+0x2d7/0x41e kernel/locking/lockdep.c:1258 check_prev_add kernel/locking/lockdep.c:1905 [inline] check_prevs_add kernel/locking/lockdep.c:2022 [inline] validate_chain kernel/locking/lockdep.c:2464 [inline] __lock_acquire+0x2e0e/0x3f20 kernel/locking/lockdep.c:3491 lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 down_write+0x34/0x90 kernel/locking/rwsem.c:54 inode_lock include/linux/fs.h:719 [inline] __generic_file_fsync+0x9e/0x190 fs/libfs.c:989 ext4_sync_file+0x8ed/0x12c0 fs/ext4/fsync.c:118 vfs_fsync_range+0x103/0x260 fs/sync.c:196 generic_write_sync include/linux/fs.h:2682 [inline] dio_complete+0x561/0x8d0 fs/direct-io.c:330 process_one_work+0x793/0x14a0 kernel/workqueue.c:2116 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2250 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 netlink: 304 bytes leftover after parsing attributes in process `syz-executor.5'. FAT-fs (loop2): Unrecognized mount option "dSu¹t©ãäkts" or missing value ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated hfs: unable to parse mount options print_req_error: I/O error, dev loop3, sector 0 can: request_module (can-proto-5) failed. ldm_validate_privheads(): Disk read failed. EXT4-fs (loop3): mounted filesystem without journal. Opts: ,errors=continue loop5: p2 < > loop5: partition table partially beyond EOD, truncated can: request_module (can-proto-5) failed. loop0: p1 < > p2 p3 < p5 p6 > p4 loop0: p2 size 1073741824 extends beyond EOD, truncated loop0: p5 size 1073741824 extends beyond EOD, truncated IPVS: ftp: loaded support on port[0] = 21 IPVS: ftp: loaded support on port[0] = 21 ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated ieee80211 phy6: Selected rate control algorithm 'minstrel_ht' ieee80211 phy6: hwaddr 02:00:00:00:06:00 registered ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated ldm_validate_privheads(): Disk read failed. loop5: p2 < > ip6_tables: ip6tables: counters copy to user failed while replacing table loop5: partition table partially beyond EOD, truncated ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated Dev loop3: unable to read RDB block 1 loop3: unable to read partition table ldm_validate_privheads(): Disk read failed. loop3: partition table beyond EOD, truncated loop5: p2 < > loop_reread_partitions: partition scan of loop3 () failed (rc=-5) loop5: partition table partially beyond EOD, truncated ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated Dev loop3: unable to read RDB block 1 loop3: unable to read partition table loop3: partition table beyond EOD, truncated loop_reread_partitions: partition scan of loop3 () failed (rc=-5) ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated ip6_tables: ip6tables: counters copy to user failed while replacing table syz-executor.2 (11858) used greatest stack depth: 24200 bytes left ip_tables: iptables: counters copy to user failed while replacing table ip_tables: iptables: counters copy to user failed while replacing table ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated audit: type=1804 audit(1608594250.374:42): pid=12054 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/62/bus" dev="sda1" ino=15962 res=1 tmpfs: Bad value '/' for mount option 'gid' tmpfs: Bad value '/' for mount option 'gid' audit: type=1804 audit(1608594250.404:43): pid=12054 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/62/bus" dev="sda1" ino=15962 res=1 audit: type=1804 audit(1608594251.074:44): pid=12057 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/62/bus" dev="sda1" ino=15962 res=1 audit: type=1804 audit(1608594251.084:45): pid=12075 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/62/bus" dev="sda1" ino=15962 res=1 ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated tmpfs: Bad value '/' for mount option 'gid' audit: type=1804 audit(1608594251.404:46): pid=12110 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/63/bus" dev="sda1" ino=15969 res=1 audit: type=1804 audit(1608594251.434:47): pid=12110 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/63/bus" dev="sda1" ino=15969 res=1 FAULT_INJECTION: forcing a failure. name fail_page_alloc, interval 1, probability 0, space 0, times 1 CPU: 1 PID: 12118 Comm: syz-executor.4 Not tainted 4.14.212-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x283 lib/dump_stack.c:58 fail_dump lib/fault-inject.c:51 [inline] should_fail.cold+0x10a/0x154 lib/fault-inject.c:149 should_fail_alloc_page mm/page_alloc.c:2898 [inline] prepare_alloc_pages mm/page_alloc.c:4131 [inline] __alloc_pages_nodemask+0x22c/0x2720 mm/page_alloc.c:4179 alloc_pages_current+0x155/0x260 mm/mempolicy.c:2113 alloc_pages include/linux/gfp.h:520 [inline] skb_page_frag_refill+0x1bf/0x490 net/core/sock.c:2206 tun_build_skb drivers/net/tun.c:1296 [inline] tun_get_user+0x1ba1/0x4260 drivers/net/tun.c:1468 tun_chr_write_iter+0xe8/0x1e0 drivers/net/tun.c:1612 call_write_iter include/linux/fs.h:1778 [inline] new_sync_write fs/read_write.c:469 [inline] __vfs_write+0x44c/0x630 fs/read_write.c:482 vfs_write+0x17f/0x4d0 fs/read_write.c:544 SYSC_write fs/read_write.c:590 [inline] SyS_write+0xf2/0x210 fs/read_write.c:582 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x4179e1 RSP: 002b:00007fae5d736c50 EFLAGS: 00000293 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 000000000119bf80 RCX: 00000000004179e1 RDX: 0000000000000046 RSI: 0000000020000080 RDI: 00000000000000f0 RBP: 00007fae5d736ca0 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000064 R11: 0000000000000293 R12: 0000000000000000 R13: 00007ffdcc680aaf R14: 00007fae5d7379c0 R15: 000000000119bf8c ldm_validate_privheads(): Disk read failed. FAULT_INJECTION: forcing a failure. name failslab, interval 1, probability 0, space 0, times 1 loop5: p2 < > CPU: 0 PID: 12139 Comm: syz-executor.4 Not tainted 4.14.212-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x283 lib/dump_stack.c:58 fail_dump lib/fault-inject.c:51 [inline] should_fail.cold+0x10a/0x154 lib/fault-inject.c:149 should_failslab+0xd6/0x130 mm/failslab.c:32 slab_pre_alloc_hook mm/slab.h:421 [inline] slab_alloc mm/slab.c:3376 [inline] kmem_cache_alloc+0x40/0x3c0 mm/slab.c:3550 __build_skb+0x2e/0x2d0 net/core/skbuff.c:281 build_skb+0x1a/0x160 net/core/skbuff.c:312 tun_build_skb drivers/net/tun.c:1357 [inline] tun_get_user+0x2199/0x4260 drivers/net/tun.c:1468 tun_chr_write_iter+0xe8/0x1e0 drivers/net/tun.c:1612 call_write_iter include/linux/fs.h:1778 [inline] new_sync_write fs/read_write.c:469 [inline] __vfs_write+0x44c/0x630 fs/read_write.c:482 vfs_write+0x17f/0x4d0 fs/read_write.c:544 SYSC_write fs/read_write.c:590 [inline] SyS_write+0xf2/0x210 fs/read_write.c:582 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x4179e1 RSP: 002b:00007fae5d736c50 EFLAGS: 00000293 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 000000000119bf80 RCX: 00000000004179e1 RDX: 0000000000000046 RSI: 0000000020000080 RDI: 00000000000000f0 RBP: 00007fae5d736ca0 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000064 R11: 0000000000000293 R12: 0000000000000001 R13: 00007ffdcc680aaf R14: 00007fae5d7379c0 R15: 000000000119bf8c loop5: partition table partially beyond EOD, truncated audit: type=1804 audit(1608594252.484:48): pid=12147 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/64/bus" dev="sda1" ino=15980 res=1 audit: type=1804 audit(1608594252.504:49): pid=12147 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/64/bus" dev="sda1" ino=15980 res=1 IPv6: sit1: Disabled Multicast RS ********************************************************** ldm_validate_privheads(): Disk read failed. ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** loop5: p2 < > loop5: partition table partially beyond EOD, truncated ** ** ** trace_printk() being used. Allocating extra memory. ** ** ** ** This means that this is a DEBUG kernel and it is ** audit: type=1804 audit(1608594253.424:50): pid=12211 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/65/bus" dev="sda1" ino=15974 res=1 ** unsafe for production use. ** ** ** audit: type=1804 audit(1608594253.454:51): pid=12211 uid=0 auid=0 ses=4 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir522346951/syzkaller.gAdUWU/65/bus" dev="sda1" ino=15974 res=1 ** If you see this message and you are not debugging ** ** the kernel, report this immediately to your vendor! ** ** ** ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** ********************************************************** f2fs_msg: 6 callbacks suppressed F2FS-fs (loop3): Magic Mismatch, valid(0xf2f52010) - read(0x0) ldm_validate_privheads(): Disk read failed. loop5: p2 < > F2FS-fs (loop3): Can't find valid F2FS filesystem in 1th superblock loop5: partition table partially beyond EOD, truncated F2FS-fs (loop3): Corrupted extension count (469762048 > 64) F2FS-fs (loop3): Can't find valid F2FS filesystem in 2th superblock F2FS-fs (loop3): Magic Mismatch, valid(0xf2f52010) - read(0x0) F2FS-fs (loop3): Can't find valid F2FS filesystem in 1th superblock F2FS-fs (loop3): Corrupted extension count (469762048 > 64) F2FS-fs (loop3): Can't find valid F2FS filesystem in 2th superblock netlink: 12 bytes leftover after parsing attributes in process `syz-executor.2'. ldm_validate_privheads(): Disk read failed. loop5: p2 < > loop5: partition table partially beyond EOD, truncated sp0: Synchronizing with TNC sp0: Synchronizing with TNC