./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor3611429833 <...> Warning: Permanently added '10.128.0.148' (ED25519) to the list of known hosts. execve("./syz-executor3611429833", ["./syz-executor3611429833"], 0x7fff3df9aed0 /* 10 vars */) = 0 brk(NULL) = 0x5555561b1000 brk(0x5555561b1d00) = 0x5555561b1d00 arch_prctl(ARCH_SET_FS, 0x5555561b1380) = 0 set_tid_address(0x5555561b1650) = 5056 set_robust_list(0x5555561b1660, 24) = 0 rseq(0x5555561b1ca0, 0x20, 0, 0x53053053) = 0 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 readlink("/proc/self/exe", "/root/syz-executor3611429833", 4096) = 28 getrandom("\xae\x63\x31\x30\x18\xb4\x20\xd2", 8, GRND_NONBLOCK) = 8 brk(NULL) = 0x5555561b1d00 brk(0x5555561d2d00) = 0x5555561d2d00 brk(0x5555561d3000) = 0x5555561d3000 mprotect(0x7f7280259000, 16384, PROT_READ) = 0 mmap(0x1ffff000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x1ffff000 mmap(0x20000000, 16777216, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x20000000 mmap(0x21000000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x21000000 unshare(CLONE_NEWPID) = 0 clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5057 attached , child_tidptr=0x5555561b1650) = 5057 [pid 5057] set_robust_list(0x5555561b1660, 24) = 0 [pid 5057] mount(NULL, "/sys/fs/fuse/connections", "fusectl", 0, NULL) = -1 EBUSY (Device or resource busy) [pid 5057] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5057] setsid() = 1 [pid 5057] prlimit64(0, RLIMIT_AS, {rlim_cur=204800*1024, rlim_max=204800*1024}, NULL) = 0 [pid 5057] prlimit64(0, RLIMIT_MEMLOCK, {rlim_cur=32768*1024, rlim_max=32768*1024}, NULL) = 0 [pid 5057] prlimit64(0, RLIMIT_FSIZE, {rlim_cur=139264*1024, rlim_max=139264*1024}, NULL) = 0 [pid 5057] prlimit64(0, RLIMIT_STACK, {rlim_cur=1024*1024, rlim_max=1024*1024}, NULL) = 0 [pid 5057] prlimit64(0, RLIMIT_CORE, {rlim_cur=131072*1024, rlim_max=131072*1024}, NULL) = 0 [pid 5057] prlimit64(0, RLIMIT_NOFILE, {rlim_cur=256, rlim_max=256}, NULL) = 0 [pid 5057] unshare(CLONE_NEWNS) = 0 [pid 5057] mount(NULL, "/", NULL, MS_REC|MS_PRIVATE, NULL) = 0 [pid 5057] unshare(CLONE_NEWIPC) = 0 [pid 5057] unshare(CLONE_NEWCGROUP) = 0 [pid 5057] unshare(CLONE_NEWUTS) = 0 [pid 5057] unshare(CLONE_SYSVSEM) = 0 [pid 5057] openat(AT_FDCWD, "/proc/sys/kernel/shmmax", O_WRONLY|O_CLOEXEC) = 3 [pid 5057] write(3, "16777216", 8) = 8 [pid 5057] close(3) = 0 [pid 5057] openat(AT_FDCWD, "/proc/sys/kernel/shmall", O_WRONLY|O_CLOEXEC) = 3 [pid 5057] write(3, "536870912", 9) = 9 [pid 5057] close(3) = 0 [pid 5057] openat(AT_FDCWD, "/proc/sys/kernel/shmmni", O_WRONLY|O_CLOEXEC) = 3 [pid 5057] write(3, "1024", 4) = 4 [pid 5057] close(3) = 0 [pid 5057] openat(AT_FDCWD, "/proc/sys/kernel/msgmax", O_WRONLY|O_CLOEXEC) = 3 [pid 5057] write(3, "8192", 4) = 4 [pid 5057] close(3) = 0 [pid 5057] openat(AT_FDCWD, "/proc/sys/kernel/msgmni", O_WRONLY|O_CLOEXEC) = 3 [pid 5057] write(3, "1024", 4) = 4 [pid 5057] close(3) = 0 [pid 5057] openat(AT_FDCWD, "/proc/sys/kernel/msgmnb", O_WRONLY|O_CLOEXEC) = 3 [pid 5057] write(3, "1024", 4) = 4 [pid 5057] close(3) = 0 [pid 5057] openat(AT_FDCWD, "/proc/sys/kernel/sem", O_WRONLY|O_CLOEXEC) = 3 [pid 5057] write(3, "1024 1048576 500 1024", 21) = 21 [pid 5057] close(3) = 0 [pid 5057] getpid() = 1 [pid 5057] capget({version=_LINUX_CAPABILITY_VERSION_3, pid=1}, {effective=1<tree_lock/1){+.+.}-{3:3}, at: hfsplus_find_init+0x14a/0x1c0 [ 56.241482][ T11] [ 56.241482][ T11] but task is already holding lock: [ 56.248852][ T11] ffff88801e1200b0 (&tree->tree_lock/1){+.+.}-{3:3}, at: hfsplus_find_init+0x14a/0x1c0 [ 56.258711][ T11] [ 56.258711][ T11] other info that might help us debug this: [ 56.266776][ T11] Possible unsafe locking scenario: [ 56.266776][ T11] [ 56.274697][ T11] CPU0 [ 56.277989][ T11] ---- [ 56.281299][ T11] lock(&tree->tree_lock/1); [ 56.286182][ T11] lock(&tree->tree_lock/1); [ 56.291224][ T11] [ 56.291224][ T11] *** DEADLOCK *** [ 56.291224][ T11] [ 56.299470][ T11] May be due to missing lock nesting notation [ 56.299470][ T11] [ 56.307817][ T11] 5 locks held by kworker/u4:0/11: [ 56.313039][ T11] #0: ffff88801ae49d38 ((wq_completion)writeback){+.+.}-{0:0}, at: process_scheduled_works+0x825/0x1400 [ 56.324354][ T11] #1: ffffc90000107d20 ((work_completion)(&(&wb->dwork)->work)){+.+.}-{0:0}, at: process_scheduled_works+0x825/0x1400 [ 56.336980][ T11] #2: ffff88807645a988 (&hip->extents_lock){+.+.}-{3:3}, at: hfsplus_ext_write_extent+0x8e/0x1f0 [ 56.348567][ T11] #3: ffff88801e1200b0 (&tree->tree_lock/1){+.+.}-{3:3}, at: hfsplus_find_init+0x14a/0x1c0 [ 56.359398][ T11] #4: ffff888076458108 (&HFSPLUS_I(inode)->extents_lock){+.+.}-{3:3}, at: hfsplus_file_extend+0x21b/0x1b70 [ 56.371303][ T11] [ 56.371303][ T11] stack backtrace: [ 56.377632][ T11] CPU: 1 PID: 11 Comm: kworker/u4:0 Not tainted 6.6.0-syzkaller-15494-g6bc986ab839c #0 [ 56.387806][ T11] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/09/2023 [ 56.398743][ T11] Workqueue: writeback wb_workfn (flush-7:0) [ 56.405271][ T11] Call Trace: [ 56.408600][ T11] [ 56.412249][ T11] dump_stack_lvl+0x1e7/0x2d0 [ 56.416948][ T11] ? nf_tcp_handle_invalid+0x650/0x650 [ 56.422424][ T11] ? print_deadlock_bug+0x462/0x600 [ 56.428103][ T11] ? _find_first_zero_bit+0xd4/0x100 [ 56.433392][ T11] __lock_acquire+0x6a81/0x7f70 [ 56.438258][ T11] ? verify_lock_unused+0x140/0x140 [ 56.443535][ T11] ? lockdep_hardirqs_on_prepare+0x43c/0x7a0 [ 56.450326][ T11] ? print_irqtrace_events+0x220/0x220 [ 56.456054][ T11] ? _raw_spin_unlock_irqrestore+0x8f/0x140 [ 56.462410][ T11] ? lockdep_hardirqs_on+0x98/0x140 [ 56.467727][ T11] ? _raw_spin_unlock_irqrestore+0xdd/0x140 [ 56.473741][ T11] ? _raw_spin_unlock+0x40/0x40 [ 56.478944][ T11] ? stack_trace_save+0x117/0x1c0 [ 56.483987][ T11] ? stack_trace_snprint+0xf0/0xf0 [ 56.489127][ T11] lock_acquire+0x1e3/0x520 [ 56.493632][ T11] ? hfsplus_find_init+0x14a/0x1c0 [ 56.498744][ T11] ? read_lock_is_recursive+0x20/0x20 [ 56.504118][ T11] ? __writeback_single_inode+0x69b/0xfa0 [ 56.509827][ T11] ? writeback_sb_inodes+0x8e3/0x1210 [ 56.515216][ T11] ? __might_sleep+0xc0/0xc0 [ 56.519808][ T11] ? kthread+0x2d3/0x370 [ 56.524055][ T11] ? ret_from_fork+0x48/0x80 [ 56.528655][ T11] ? ret_from_fork_asm+0x11/0x20 [ 56.533595][ T11] __mutex_lock+0x136/0xd60 [ 56.538106][ T11] ? hfsplus_find_init+0x14a/0x1c0 [ 56.543214][ T11] ? hfsplus_find_init+0x14a/0x1c0 [ 56.548317][ T11] ? mutex_lock_nested+0x20/0x20 [ 56.553249][ T11] ? hfsplus_find_init+0x85/0x1c0 [ 56.558283][ T11] ? rcu_is_watching+0x15/0xb0 [ 56.563052][ T11] ? hfsplus_find_init+0x85/0x1c0 [ 56.568086][ T11] ? __kmalloc+0xe6/0x230 [ 56.572518][ T11] ? rcu_is_watching+0x15/0xb0 [ 56.577283][ T11] hfsplus_find_init+0x14a/0x1c0 [ 56.582215][ T11] hfsplus_file_extend+0x454/0x1b70 [ 56.587411][ T11] ? hfsplus_get_block+0x14e0/0x14e0 [ 56.592684][ T11] ? rcu_is_watching+0x15/0xb0 [ 56.597440][ T11] ? trace_contention_end+0x3c/0xf0 [ 56.602647][ T11] ? hfsplus_brec_find+0x19d/0x570 [ 56.607867][ T11] ? hfsplus_find_init+0x14a/0x1c0 [ 56.613074][ T11] hfsplus_bmap_reserve+0x105/0x4e0 [ 56.618289][ T11] __hfsplus_ext_write_extent+0x2a4/0x5b0 [ 56.624013][ T11] hfsplus_ext_write_extent+0x16a/0x1f0 [ 56.629551][ T11] ? hfsplus_ext_cmp_key+0x2f0/0x2f0 [ 56.634830][ T11] ? do_raw_spin_lock+0x14d/0x3a0 [ 56.639968][ T11] hfsplus_write_inode+0x22/0x5e0 [ 56.645044][ T11] __writeback_single_inode+0x69b/0xfa0 [ 56.650795][ T11] writeback_sb_inodes+0x8e3/0x1210 [ 56.656023][ T11] ? queue_io+0x570/0x570 [ 56.660388][ T11] ? __writeback_inodes_wb+0x260/0x260 [ 56.665883][ T11] ? queue_io+0x3d5/0x570 [ 56.670231][ T11] wb_writeback+0x44d/0xc60 [ 56.674756][ T11] ? percpu_ref_tryget+0x260/0x260 [ 56.679952][ T11] ? lockdep_hardirqs_on_prepare+0x43c/0x7a0 [ 56.685933][ T11] wb_workfn+0x400/0xff0 [ 56.690173][ T11] ? inode_wait_for_writeback+0x290/0x290 [ 56.695978][ T11] ? read_lock_is_recursive+0x20/0x20 [ 56.701433][ T11] ? lockdep_hardirqs_on_prepare+0x43c/0x7a0 [ 56.707407][ T11] ? print_irqtrace_events+0x220/0x220 [ 56.712861][ T11] ? process_scheduled_works+0x825/0x1400 [ 56.718597][ T11] process_scheduled_works+0x90f/0x1400 [ 56.724143][ T11] ? assign_work+0x3d0/0x3d0 [ 56.728813][ T11] ? assign_work+0x364/0x3d0 [ 56.733397][ T11] worker_thread+0xa5f/0xff0 [ 56.737985][ T11] kthread+0x2d3/0x370 [ 56.742043][ T11] ? pr_cont_work+0x5e0/0x5e0 [ 56.746728][ T11] ? kthread_blkcg+0xd0/0xd0 [ 56.751315][ T11] ret_from_fork+0x48/0x80 [ 56.755736][ T11] ? kthread_blkcg+0xd0/0xd0 [ 56.760339][ T11] ret_from_fork_asm+0x11/0x20 [ 56.765110][ T11]