XFS (loop5): log mount/recovery failed: error -5 XFS (loop5): log mount failed XFS (loop4): empty log check failed XFS (loop4): log mount/recovery failed: error -5 XFS (loop4): log mount failed INFO: task syz-executor0:5605 blocked for more than 140 seconds. Not tainted 4.19.0+ #308 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor0 D18472 5605 1 0x00000004 Call Trace: context_switch kernel/sched/core.c:2831 [inline] __schedule+0x8cf/0x21d0 kernel/sched/core.c:3480 schedule+0xfe/0x460 kernel/sched/core.c:3524 schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:3582 __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 vfat_lookup+0xf1/0x640 fs/fat/namei_vfat.c:709 __lookup_slow+0x2b5/0x540 fs/namei.c:1671 lookup_slow+0x57/0x80 fs/namei.c:1688 walk_component+0x92b/0x2590 fs/namei.c:1810 link_path_walk.part.40+0xa61/0x1530 fs/namei.c:2141 link_path_walk fs/namei.c:2072 [inline] path_openat+0x270/0x5150 fs/namei.c:3533 do_filp_open+0x255/0x380 fs/namei.c:3564 do_sys_open+0x568/0x700 fs/open.c:1063 __do_sys_open fs/open.c:1081 [inline] __se_sys_open fs/open.c:1076 [inline] __x64_sys_open+0x7e/0xc0 fs/open.c:1076 do_syscall_64+0x1b9/0x820 arch/x86/entry/common.c:290 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x455570 Code: Bad RIP value. RSP: 002b:00007ffe391ffb70 EFLAGS: 00000202 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 0000000000455570 RDX: 0000000000000000 RSI: 0000000000090800 RDI: 00000000004bc2ae RBP: 0000000000000731 R08: 0000000000000001 R09: 0000000002734940 R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000004 R13: 000000000007611f R14: 00000000000001ee R15: 0000000000000000 INFO: task syz-executor0:20046 blocked for more than 140 seconds. Not tainted 4.19.0+ #308 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor0 D22240 20046 5605 0x00000004 Call Trace: context_switch kernel/sched/core.c:2831 [inline] __schedule+0x8cf/0x21d0 kernel/sched/core.c:3480 schedule+0xfe/0x460 kernel/sched/core.c:3524 d_wait_lookup fs/dcache.c:2408 [inline] d_alloc_parallel+0x1478/0x1f40 fs/dcache.c:2490 lookup_open+0x560/0x1b90 fs/namei.c:3147 do_last fs/namei.c:3324 [inline] path_openat+0x2464/0x5150 fs/namei.c:3534 do_filp_open+0x255/0x380 fs/namei.c:3564 do_sys_open+0x568/0x700 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:0x457569 Code: Bad RIP value. RSP: 002b:00007f7747c6ac78 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 0000000000457569 RDX: 0000000000000002 RSI: 0000000020000100 RDI: ffffffffffffff9c RBP: 000000000072bfa0 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00007f7747c6b6d4 R13: 00000000004c2e2e R14: 00000000004d4760 R15: 00000000ffffffff Showing all locks held in the system: 1 lock held by khungtaskd/983: #0: 00000000276c2a36 (rcu_read_lock){....}, at: debug_show_all_locks+0xd0/0x424 kernel/locking/lockdep.c:4379 1 lock held by rsyslogd/5477: #0: 0000000076f76b2d (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1bb/0x200 fs/file.c:766 2 locks held by getty/5566: #0: 000000002c7563d4 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353 #1: 00000000947d1628 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140 2 locks held by getty/5567: #0: 00000000f378af52 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353 #1: 00000000f72ced09 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140 2 locks held by getty/5568: #0: 000000000fbc4c74 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353 #1: 00000000aa241eac (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140 2 locks held by getty/5569: #0: 000000004931ef18 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353 #1: 00000000f2933323 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140 2 locks held by getty/5570: #0: 00000000dcc7c958 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353 #1: 00000000e7505b5e (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140 2 locks held by getty/5571: #0: 0000000055873550 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353 #1: 00000000fcbffef7 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140 2 locks held by getty/5572: #0: 00000000b94bad29 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x32/0x40 drivers/tty/tty_ldsem.c:353 #1: 00000000938b7504 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x335/0x1ce0 drivers/tty/n_tty.c:2140 2 locks held by syz-executor0/5605: #0: 00000000fa30c57e (&sb->s_type->i_mutex_key#28){++++}, at: inode_lock_shared include/linux/fs.h:767 [inline] #0: 00000000fa30c57e (&sb->s_type->i_mutex_key#28){++++}, at: lookup_slow+0x49/0x80 fs/namei.c:1687 #1: 000000008fa56478 (&sbi->s_lock){+.+.}, at: vfat_lookup+0xf1/0x640 fs/fat/namei_vfat.c:709 4 locks held by syz-executor0/20044: 2 locks held by syz-executor0/20046: #0: 00000000bd61cd6f (sb_writers#22){.+.+}, at: sb_start_write include/linux/fs.h:1585 [inline] #0: 00000000bd61cd6f (sb_writers#22){.+.+}, at: mnt_want_write+0x3f/0xc0 fs/namespace.c:360 #1: 00000000fa30c57e (&sb->s_type->i_mutex_key#28){++++}, at: inode_lock_shared include/linux/fs.h:767 [inline] #1: 00000000fa30c57e (&sb->s_type->i_mutex_key#28){++++}, at: do_last fs/namei.c:3323 [inline] #1: 00000000fa30c57e (&sb->s_type->i_mutex_key#28){++++}, at: path_openat+0x2444/0x5150 fs/namei.c:3534 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 983 Comm: khungtaskd Not tainted 4.19.0+ #308 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.1+0x5c/0xa1 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x1b3/0x1ed 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:144 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:204 [inline] watchdog+0xb3e/0x1050 kernel/hung_task.c:265 kthread+0x35a/0x440 kernel/kthread.c:246 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:350 Sending NMI from CPU 1 to CPUs 0: INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 1.236 msecs NMI backtrace for cpu 0 CPU: 0 PID: 20044 Comm: syz-executor0 Not tainted 4.19.0+ #308 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:__read_once_size include/linux/compiler.h:179 [inline] RIP: 0010:__sanitizer_cov_trace_pc+0x3b/0x50 kernel/kcov.c:106 Code: 65 8b 15 58 b5 82 7e 81 e2 00 01 1f 00 75 2b 8b 90 d0 12 00 00 83 fa 02 75 20 48 8b 88 d8 12 00 00 8b 80 d4 12 00 00 48 8b 11 <48> 83 c2 01 48 39 d0 76 07 48 89 34 d1 48 89 11 5d c3 0f 1f 00 55 RSP: 0018:ffff88018837ece0 EFLAGS: 00000246 RAX: 0000000000040000 RBX: ffffea00069feb80 RCX: ffffc90001e5a000 RDX: 000000000003ffff RSI: ffffffff819bd9bb RDI: 0000000000000007 RBP: ffff88018837ece0 R08: ffff8801d37a4300 R09: ffffed003b5c5b4f R10: 0000000000000004 R11: ffff8801dae2da7b R12: 1ffff1003106fda0 R13: ffffea00069feb80 R14: 0000000000000000 R15: ffffea0006af6808 FS: 00007f7747c8c700(0000) GS:ffff8801dae00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: ffffffffff600400 CR3: 00000001c1e6e000 CR4: 00000000001406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: constant_test_bit arch/x86/include/asm/bitops.h:325 [inline] PageLocked include/linux/page-flags.h:281 [inline] unlock_page+0x26b/0x4c0 mm/filemap.c:1199 grow_dev_page fs/buffer.c:981 [inline] grow_buffers fs/buffer.c:1016 [inline] __getblk_slow fs/buffer.c:1043 [inline] __getblk_gfp+0x88a/0xd50 fs/buffer.c:1320 __bread_gfp+0x2d/0x310 fs/buffer.c:1354 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:477 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+0x2464/0x5150 fs/namei.c:3534 do_filp_open+0x255/0x380 fs/namei.c:3564 do_sys_open+0x568/0x700 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: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:00007f7747c8bc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 0000000000457569 RDX: 0000000000000002 RSI: 0000000020000100 RDI: ffffffffffffff9c RBP: 000000000072bf00 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00007f7747c8c6d4 R13: 00000000004c2e2e R14: 00000000004d4760 R15: 00000000ffffffff