__find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 INFO: task syz-executor0:21897 blocked for more than 140 seconds. device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 Not tainted 4.20.0-rc7-next-20181224 #188 b_state=0x00000029, b_size=512 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 syz-executor0 D23824 21897 8096 0x00000004 b_state=0x00000029, b_size=512 Call Trace: device loop0 blocksize: 4096 context_switch kernel/sched/core.c:2831 [inline] __schedule+0x9de/0x1ed0 kernel/sched/core.c:3472 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 schedule+0xef/0x370 kernel/sched/core.c:3516 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 d_wait_lookup fs/dcache.c:2408 [inline] d_alloc_parallel+0x1471/0x1f40 fs/dcache.c:2490 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 lookup_open+0x560/0x1b90 fs/namei.c:3147 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 do_last fs/namei.c:3324 [inline] path_openat+0x252a/0x5160 fs/namei.c:3534 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 do_filp_open+0x255/0x380 fs/namei.c:3564 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 do_sys_open+0x568/0x780 fs/open.c:1063 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 __do_sys_openat fs/open.c:1090 [inline] __se_sys_openat fs/open.c:1084 [inline] __x64_sys_openat+0x9d/0x100 fs/open.c:1084 b_state=0x00000029, b_size=512 do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 entry_SYSCALL_64_after_hwframe+0x49/0xbe b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 RIP: 0033:0x457759 __find_get_block_slow() failed. block=1, b_blocknr=8 Code: 20 84 00 48 8b 88 58 12 00 00 48 85 c9 0f 85 09 01 00 00 48 8d 88 60 12 00 00 48 89 4c 24 28 48 89 0c 24 48 c7 44 24 08 05 00 <00> 00 e8 f0 5d fc ff 48 8b 44 24 18 0f b6 88 c0 00 00 00 84 c9 0f b_state=0x00000029, b_size=512 RSP: 002b:00007f28633eac78 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 device loop0 blocksize: 4096 RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 0000000000457759 __find_get_block_slow() failed. block=1, b_blocknr=8 RDX: 0000000000000002 RSI: 0000000020000200 RDI: 0000000000000004 b_state=0x00000029, b_size=512 RBP: 000000000073bfa0 R08: 0000000000000000 R09: 0000000000000000 device loop0 blocksize: 4096 R10: 0000000000000000 R11: 0000000000000246 R12: 00007f28633eb6d4 __find_get_block_slow() failed. block=1, b_blocknr=8 R13: 00000000004c353b R14: 00000000004d6210 R15: 00000000ffffffff b_state=0x00000029, b_size=512 Showing all locks held in the system: device loop0 blocksize: 4096 1 lock held by khungtaskd/1033: __find_get_block_slow() failed. block=1, b_blocknr=8 #0: 00000000eecef6af (rcu_read_lock){....}, at: debug_show_all_locks+0xd0/0x424 kernel/locking/lockdep.c:4389 4 locks held by rs:main Q:Reg/7970: b_state=0x00000029, b_size=512 1 lock held by rsyslogd/7972: device loop0 blocksize: 4096 #0: 000000008721dbcb (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1bb/0x200 fs/file.c:795 __find_get_block_slow() failed. block=1, b_blocknr=8 2 locks held by getty/8062: b_state=0x00000029, b_size=512 #0: 0000000048a61122 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:341 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 #1: 000000003aa791ec (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ee0 drivers/tty/n_tty.c:2154 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 2 locks held by getty/8063: b_state=0x00000029, b_size=512 #0: 000000001a5041b1 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:341 device loop0 blocksize: 4096 #1: 000000001837d860 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ee0 drivers/tty/n_tty.c:2154 __find_get_block_slow() failed. block=1, b_blocknr=8 2 locks held by getty/8064: #0: 00000000d0dacf2e (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:341 b_state=0x00000029, b_size=512 #1: 00000000f513b032 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ee0 drivers/tty/n_tty.c:2154 device loop0 blocksize: 4096 2 locks held by getty/8065: __find_get_block_slow() failed. block=1, b_blocknr=8 #0: 000000003a7a20d6 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:341 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 #1: 000000004d0000a1 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ee0 drivers/tty/n_tty.c:2154 b_state=0x00000029, b_size=512 2 locks held by getty/8066: device loop0 blocksize: 4096 #0: 000000007e6106c6 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:341 __find_get_block_slow() failed. block=1, b_blocknr=8 #1: 000000004e046004 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ee0 drivers/tty/n_tty.c:2154 b_state=0x00000029, b_size=512 2 locks held by getty/8067: device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 #0: 00000000373eb6cc (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:341 b_state=0x00000029, b_size=512 #1: 000000007fa56337 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ee0 drivers/tty/n_tty.c:2154 device loop0 blocksize: 4096 2 locks held by getty/8068: __find_get_block_slow() failed. block=1, b_blocknr=8 #0: 0000000038731e19 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:341 b_state=0x00000029, b_size=512 #1: 0000000063f51ca7 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ee0 drivers/tty/n_tty.c:2154 device loop0 blocksize: 4096 1 lock held by syz-executor5/19205: __find_get_block_slow() failed. block=1, b_blocknr=8 #0: 000000008e8a499c (&sig->cred_guard_mutex){+.+.}, at: prepare_bprm_creds fs/exec.c:1406 [inline] #0: 000000008e8a499c (&sig->cred_guard_mutex){+.+.}, at: __do_execve_file.isra.33+0x47a/0x2820 fs/exec.c:1749 b_state=0x00000029, b_size=512 7 locks held by syz-executor0/21894: device loop0 blocksize: 4096 2 locks held by syz-executor0/21897: __find_get_block_slow() failed. block=1, b_blocknr=8 #0: 00000000db1db6c2 (sb_writers#20){.+.+}, at: sb_start_write include/linux/fs.h:1618 [inline] #0: 00000000db1db6c2 (sb_writers#20){.+.+}, at: mnt_want_write+0x3f/0xc0 fs/namespace.c:359 b_state=0x00000029, b_size=512 #1: 00000000b0d63d52 (&sb->s_type->i_mutex_key#28){++++}, at: inode_lock_shared include/linux/fs.h:779 [inline] #1: 00000000b0d63d52 (&sb->s_type->i_mutex_key#28){++++}, at: do_last fs/namei.c:3323 [inline] #1: 00000000b0d63d52 (&sb->s_type->i_mutex_key#28){++++}, at: path_openat+0x250a/0x5160 fs/namei.c:3534 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 ============================================= b_state=0x00000029, b_size=512 NMI backtrace for cpu 0 device loop0 blocksize: 4096 CPU: 0 PID: 1033 Comm: khungtaskd Not tainted 4.20.0-rc7-next-20181224 #188 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 __find_get_block_slow() failed. block=1, b_blocknr=8 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x1d3/0x2c6 lib/dump_stack.c:113 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 nmi_cpu_backtrace.cold.4+0x63/0xa2 lib/nmi_backtrace.c:101 __find_get_block_slow() failed. block=1, b_blocknr=8 nmi_trigger_cpumask_backtrace+0x1c2/0x22c 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:203 [inline] watchdog+0xba1/0x11a0 kernel/hung_task.c:287 b_state=0x00000029, b_size=512 device loop0 blocksize: 4096 __find_get_block_slow() failed. block=1, b_blocknr=8 kthread+0x35a/0x440 kernel/kthread.c:246 b_state=0x00000029, b_size=512 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352 device loop0 blocksize: 4096 Sending NMI from CPU 0 to CPUs 1: __find_get_block_slow() failed. block=1, b_blocknr=8 NMI backtrace for cpu 1 INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 1.869 msecs CPU: 1 PID: 21894 Comm: syz-executor0 Not tainted 4.20.0-rc7-next-20181224 #188 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:native_apic_msr_eoi_write+0xf/0x20 arch/x86/include/asm/apic.h:215 Code: 50 ff ff ff e9 13 ff ff ff e8 1d b6 8f 00 e9 d7 fe ff ff e8 83 b4 14 00 90 90 90 55 31 c0 b9 0b 08 00 00 48 89 e5 89 c2 0f 30 <5d> c3 0f 1f 44 00 00 66 2e 0f 1f 84 00 00 00 00 00 c3 0f 1f 44 00 RSP: 0018:ffff8881dad07e90 EFLAGS: 00000046 RAX: 0000000000000000 RBX: ffffffff894984a0 RCX: 000000000000080b RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00000000000000b0 RBP: ffff8881dad07e90 R08: ffff8881cd330500 R09: ffffed103b5a0fce R10: ffffed103b5a0fce R11: 0000000000000003 R12: ffff8881d09d2dc0 R13: dffffc0000000000 R14: ffff8881d09d2e3c R15: 0000000000000000 FS: 00007f286340c700(0000) GS:ffff8881dad00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: ffffffffff600400 CR3: 000000017d68b000 CR4: 00000000001426e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: apic_eoi arch/x86/include/asm/apic.h:402 [inline] ack_APIC_irq arch/x86/include/asm/apic.h:448 [inline] apic_ack_irq+0x9e/0xe0 arch/x86/kernel/apic/vector.c:808 apic_ack_edge+0x53/0x70 arch/x86/kernel/apic/vector.c:814 irq_chip_ack_parent+0x76/0x90 kernel/irq/chip.c:1265 handle_edge_irq+0x1c3/0x880 kernel/irq/chip.c:772 generic_handle_irq_desc include/linux/irqdesc.h:154 [inline] handle_irq+0x252/0x3d8 arch/x86/kernel/irq_64.c:78 do_IRQ+0x98/0x1c0 arch/x86/kernel/irq.c:246 common_interrupt+0xf/0xf arch/x86/entry/entry_64.S:583 RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:766 [inline] RIP: 0010:console_trylock_spinning kernel/printk/printk.c:1744 [inline] RIP: 0010:vprintk_emit+0x881/0x990 kernel/printk/printk.c:1977 Code: 00 fc ff df 48 c1 e8 03 80 3c 10 00 0f 85 ef 00 00 00 48 83 3d 4f 34 0a 08 00 74 2d e8 d8 da 19 00 48 8b bd 90 fe ff ff 57 9d <0f> 1f 44 00 00 e9 a3 fe ff ff e8 c0 da 19 00 0f 0b e8 b9 da 19 00 RSP: 0018:ffff88817d5de9b0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffffdd RAX: 0000000000040000 RBX: 1ffff1102fabbd3c RCX: ffffc90005dc3000 RDX: 0000000000040000 RSI: ffffffff816815e8 RDI: 0000000000000246 RBP: ffff88817d5deb28 R08: ffff8881cd330e18 R09: 0000000000000006 R10: 0000000000000000 R11: ffff8881cd330500 R12: 0000000000000200 R13: 0000000000000000 R14: ffffed102fabbd50 R15: ffff88817d5deb00 vprintk_default+0x28/0x30 kernel/printk/printk.c:2005 vprintk_func+0x7e/0x181 kernel/printk/printk_safe.c:398 printk+0xa7/0xcf kernel/printk/printk.c:2038 __find_get_block_slow fs/buffer.c:231 [inline] __find_get_block.cold.57+0x3a/0xff fs/buffer.c:1325 __getblk_slow fs/buffer.c:1058 [inline] __getblk_gfp+0x3d3/0x1030 fs/buffer.c:1351 __bread_gfp+0x2d/0x300 fs/buffer.c:1385 sb_bread include/linux/buffer_head.h:307 [inline] fat__get_entry+0x5a6/0xa40 fs/fat/dir.c:101 fat_get_entry fs/fat/dir.c:129 [inline] fat_search_long+0x33b/0x1570 fs/fat/dir.c:479 vfat_find+0x16d/0x1a0 fs/fat/namei_vfat.c:697 vfat_lookup+0x107/0x640 fs/fat/namei_vfat.c:711 lookup_open+0x72e/0x1b90 fs/namei.c:3213 do_last fs/namei.c:3324 [inline] path_openat+0x252a/0x5160 fs/namei.c:3534 do_filp_open+0x255/0x380 fs/namei.c:3564 do_sys_open+0x568/0x780 fs/open.c:1063 __do_sys_openat fs/open.c:1090 [inline] __se_sys_openat fs/open.c:1084 [inline] __x64_sys_openat+0x9d/0x100 fs/open.c:1084 do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x457759 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:00007f286340bc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 0000000000457759 RDX: 0000000000000002 RSI: 0000000020000200 RDI: 0000000000000004 RBP: 000000000073bf00 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00007f286340c6d4 R13: 00000000004c353b R14: 00000000004d6210 R15: 00000000ffffffff