./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor9217688

<...>
Warning: Permanently added '10.128.0.85' (ED25519) to the list of known hosts.
execve("./syz-executor9217688", ["./syz-executor9217688"], 0x7fffe3e8bd80 /* 10 vars */) = 0
brk(NULL)                               = 0x5555667a7000
brk(0x5555667a7d00)                     = 0x5555667a7d00
arch_prctl(ARCH_SET_FS, 0x5555667a7380) = 0
set_tid_address(0x5555667a7650)         = 5775
set_robust_list(0x5555667a7660, 24)     = 0
rseq(0x5555667a7ca0, 0x20, 0, 0x53053053) = 0
prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
readlink("/proc/self/exe", "/root/syz-executor9217688", 4096) = 25
getrandom("\xf8\xf3\xa2\x3b\xdf\x16\xa8\x8b", 8, GRND_NONBLOCK) = 8
brk(NULL)                               = 0x5555667a7d00
brk(0x5555667c8d00)                     = 0x5555667c8d00
brk(0x5555667c9000)                     = 0x5555667c9000
mprotect(0x7f69dff64000, 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
clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x5555667a7650) = 5776
./strace-static-x86_64: Process 5776 attached
[pid  5775] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5776] set_robust_list(0x5555667a7660, 24./strace-static-x86_64: Process 5777 attached
 <unfinished ...>
[pid  5777] set_robust_list(0x5555667a7660, 24 <unfinished ...>
[pid  5776] <... set_robust_list resumed>) = 0
[pid  5777] <... set_robust_list resumed>) = 0
[pid  5776] openat(AT_FDCWD, "/dev/loop0", O_RDWR <unfinished ...>
[pid  5775] <... clone resumed>, child_tidptr=0x5555667a7650) = 5777
[pid  5777] openat(AT_FDCWD, "/dev/loop1", O_RDWR <unfinished ...>
[pid  5775] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5776] <... openat resumed>)       = 3
[pid  5777] <... openat resumed>)       = 3
[pid  5777] ioctl(3, LOOP_CLR_FD <unfinished ...>
[pid  5776] ioctl(3, LOOP_CLR_FD./strace-static-x86_64: Process 5778 attached
 <unfinished ...>
[pid  5775] <... clone resumed>, child_tidptr=0x5555667a7650) = 5778
[pid  5777] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5775] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5778] set_robust_list(0x5555667a7660, 24) = 0
[pid  5777] close(3 <unfinished ...>
[pid  5776] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
./strace-static-x86_64: Process 5779 attached
[pid  5779] set_robust_list(0x5555667a7660, 24) = 0
[pid  5779] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = 3
[pid  5777] <... close resumed>)        = 0
[pid  5779] ioctl(3, LOOP_CLR_FD)       = -1 ENXIO (No such device or address)
[pid  5779] close(3)                    = 0
[pid  5779] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5780 attached
, child_tidptr=0x5555667a7650) = 5780
[pid  5780] set_robust_list(0x5555667a7660, 24) = 0
[pid  5780] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0
[pid  5780] setpgid(0, 0)               = 0
[pid  5780] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXECexecuting program
 <unfinished ...>
[pid  5775] <... clone resumed>, child_tidptr=0x5555667a7650) = 5779
[pid  5780] <... openat resumed>)       = 3
[pid  5778] openat(AT_FDCWD, "/dev/loop2", O_RDWR <unfinished ...>
[pid  5777] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5776] close(3 <unfinished ...>
[pid  5780] write(3, "1000", 4)         = 4
[pid  5776] <... close resumed>)        = 0
[pid  5780] close(3)                    = 0
[pid  5780] write(1, "executing program\n", 18) = 18
[pid  5780] bpf(BPF_MAP_CREATE, {map_type=BPF_MAP_TYPE_ARRAY, key_size=4, value_size=8, max_entries=1, map_flags=BPF_F_RDONLY_PROG, inner_map_fd=-1, map_name="", map_ifindex=0, btf_fd=-1, btf_key_type_id=0, btf_value_type_id=0, btf_vmlinux_value_type_id=0, map_extra=0}, 72./strace-static-x86_64: Process 5781 attached
 <unfinished ...>
[pid  5775] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5776] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5780] <... bpf resumed>)          = 3
[pid  5780] sched_setscheduler(0, SCHED_FIFO, [7] <unfinished ...>
[pid  5781] set_robust_list(0x5555667a7660, 24 <unfinished ...>
[pid  5780] <... sched_setscheduler resumed>) = 0
[pid  5778] <... openat resumed>)       = 3
[pid  5781] <... set_robust_list resumed>) = 0
[pid  5780] memfd_create("syzkaller", 0 <unfinished ...>
[pid  5781] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5780] <... memfd_create resumed>) = 4
[pid  5780] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f69d7a00000
[pid  5778] ioctl(3, LOOP_CLR_FD <unfinished ...>
[pid  5777] <... clone resumed>, child_tidptr=0x5555667a7650) = 5781
[pid  5778] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5775] <... clone resumed>, child_tidptr=0x5555667a7650) = 5782
[pid  5778] close(3 <unfinished ...>
[pid  5776] <... clone resumed>, child_tidptr=0x5555667a7650) = 5783
[pid  5778] <... close resumed>)        = 0
[pid  5778] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5782 attached
./strace-static-x86_64: Process 5784 attached
 <unfinished ...>
[pid  5782] set_robust_list(0x5555667a7660, 24 <unfinished ...>
[pid  5784] set_robust_list(0x5555667a7660, 24 <unfinished ...>
[pid  5782] <... set_robust_list resumed>) = 0
[pid  5782] openat(AT_FDCWD, "/dev/loop4", O_RDWR <unfinished ...>
[pid  5784] <... set_robust_list resumed>) = 0
[pid  5778] <... clone resumed>, child_tidptr=0x5555667a7650) = 5784
[pid  5784] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5782] <... openat resumed>)       = 3
[pid  5784] <... prctl resumed>)        = 0
[pid  5784] setpgid(0, 0)               = 0
[pid  5784] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3
[pid  5782] ioctl(3, LOOP_CLR_FD <unfinished ...>
[pid  5784] write(3, "1000", 4 <unfinished ...>
[pid  5782] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5784] <... write resumed>)        = 4
[pid  5784] close(3 <unfinished ...>
[pid  5782] close(3executing program
 <unfinished ...>
[pid  5784] <... close resumed>)        = 0
[pid  5782] <... close resumed>)        = 0
[pid  5784] write(1, "executing program\n", 18 <unfinished ...>
[pid  5782] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5784] <... write resumed>)        = 18
[pid  5784] bpf(BPF_MAP_CREATE, {map_type=BPF_MAP_TYPE_ARRAY, key_size=4, value_size=8, max_entries=1, map_flags=BPF_F_RDONLY_PROG, inner_map_fd=-1, map_name="", map_ifindex=0, btf_fd=-1, btf_key_type_id=0, btf_value_type_id=0, btf_vmlinux_value_type_id=0, map_extra=0}, 72./strace-static-x86_64: Process 5786 attached
) = 3
[pid  5786] set_robust_list(0x5555667a7660, 24 <unfinished ...>
[pid  5784] sched_setscheduler(0, SCHED_FIFO, [7] <unfinished ...>
[pid  5786] <... set_robust_list resumed>) = 0
[pid  5784] <... sched_setscheduler resumed>) = 0
[pid  5786] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5784] memfd_create("syzkaller", 0 <unfinished ...>
[pid  5782] <... clone resumed>, child_tidptr=0x5555667a7650) = 5786
[pid  5784] <... memfd_create resumed>) = 4
[pid  5784] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid  5786] <... prctl resumed>)        = 0
[pid  5784] <... mmap resumed>)         = 0x7f69d7a00000
./strace-static-x86_64: Process 5783 attached
[pid  5786] setpgid(0, 0 <unfinished ...>
[pid  5780] write(4, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 <unfinished ...>
[pid  5786] <... setpgid resumed>)      = 0
[pid  5784] write(4, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 <unfinished ...>
[pid  5783] set_robust_list(0x5555667a7660, 24 <unfinished ...>
[pid  5781] <... prctl resumed>)        = 0
[pid  5780] <... write resumed>)        = 16777216
[pid  5780] munmap(0x7f69d7a00000, 138412032) = 0
[pid  5780] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = 5
[pid  5780] ioctl(5, LOOP_SET_FD, 4)    = 0
[pid  5780] close(4)                    = 0
[pid  5780] close(5)                    = 0
[pid  5780] mkdir("./file1", 0777)      = 0
[pid  5780] mount("/dev/loop3", "./file1", "bcachefs", MS_I_VERSION, "data_checksum=crc64,journal_transaction_names,norecovery,fsck,journal_flush_disabled,btree_node_pref"... <unfinished ...>
[pid  5783] <... set_robust_list resumed>) = 0
[pid  5783] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5781] setpgid(0, 0 <unfinished ...>
[pid  5783] <... prctl resumed>)        = 0
[pid  5781] <... setpgid resumed>)      = 0
[pid  5783] setpgid(0, 0 <unfinished ...>
[  182.624872][ T5780] loop3: detected capacity change from 0 to 32768
[pid  5781] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5783] <... setpgid resumed>)      = 0
[pid  5781] <... openat resumed>)       = 3
[pid  5783] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5786] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5781] write(3, "1000", 4 <unfinished ...>
[pid  5786] <... openat resumed>)       = 3
[pid  5783] <... openat resumed>)       = 3
[pid  5786] write(3, "1000", 4 <unfinished ...>
[pid  5783] write(3, "1000", 4 <unfinished ...>
[pid  5786] <... write resumed>)        = 4
[pid  5783] <... write resumed>)        = 4
[pid  5781] <... write resumed>)        = 4
[pid  5786] close(3 <unfinished ...>
[pid  5783] close(3executing program
 <unfinished ...>
[pid  5781] close(3 <unfinished ...>
[pid  5786] <... close resumed>)        = 0
[pid  5783] <... close resumed>)        = 0
[pid  5786] write(1, "executing program\n", 18executing program
 <unfinished ...>
[pid  5783] write(1, "executing program\n", 18 <unfinished ...>
[pid  5781] <... close resumed>)        = 0
executing program
[pid  5786] <... write resumed>)        = 18
[pid  5784] <... write resumed>)        = 16777216
[pid  5783] <... write resumed>)        = 18
[pid  5781] write(1, "executing program\n", 18 <unfinished ...>
[pid  5786] bpf(BPF_MAP_CREATE, {map_type=BPF_MAP_TYPE_ARRAY, key_size=4, value_size=8, max_entries=1, map_flags=BPF_F_RDONLY_PROG, inner_map_fd=-1, map_name="", map_ifindex=0, btf_fd=-1, btf_key_type_id=0, btf_value_type_id=0, btf_vmlinux_value_type_id=0, map_extra=0}, 72 <unfinished ...>
[pid  5784] munmap(0x7f69d7a00000, 138412032 <unfinished ...>
[pid  5783] bpf(BPF_MAP_CREATE, {map_type=BPF_MAP_TYPE_ARRAY, key_size=4, value_size=8, max_entries=1, map_flags=BPF_F_RDONLY_PROG, inner_map_fd=-1, map_name="", map_ifindex=0, btf_fd=-1, btf_key_type_id=0, btf_value_type_id=0, btf_vmlinux_value_type_id=0, map_extra=0}, 72 <unfinished ...>
[pid  5781] <... write resumed>)        = 18
[pid  5786] <... bpf resumed>)          = 3
[pid  5784] <... munmap resumed>)       = 0
[pid  5784] openat(AT_FDCWD, "/dev/loop2", O_RDWR) = 5
[pid  5786] sched_setscheduler(0, SCHED_FIFO, [7] <unfinished ...>
[pid  5784] ioctl(5, LOOP_SET_FD, 4 <unfinished ...>
[pid  5786] <... sched_setscheduler resumed>) = 0
[pid  5783] <... bpf resumed>)          = 3
[  182.785538][ T5784] loop2: detected capacity change from 0 to 32768
[  182.838972][ T5780] bcachefs (loop3): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=crc64,journal_flush_disabled,fsck,norecovery,reconstruct_alloc
[  182.856313][ T5780] bcachefs (loop3): recovering from clean shutdown, journal seq 10
[  182.865978][ T5780] bcachefs (loop3): Version upgrade required:
[  182.865978][ T5780] Version upgrade from 0.24: unwritten_extents to 1.7: mi_btree_bitmap incomplete
[  182.865978][ T5780] Doing incompatible version upgrade from 0.24: unwritten_extents to 1.13: inode_has_child_snapshots
[  182.865978][ T5780]   running recovery passes: check_allocations,check_alloc_info,check_lrus,check_btree_backpointers,check_backpointers_to_extents,check_extents_to_backpointers,check_alloc_to_lru_refs,bucket_gens_init,check_snapshot_trees,check_snapshots,check_subvols,check_subvol_children,delete_dead_snapshots,check_inodes,check_extents,check_indirect_extents,check_dirents,check_xattrs,check_root,check_unreachable_inodes,check_subvolume_structure,check_directory_structure,check_nlinks,set_fs_needs_rebalance
[pid  5781] bpf(BPF_MAP_CREATE, {map_type=BPF_MAP_TYPE_ARRAY, key_size=4, value_size=8, max_entries=1, map_flags=BPF_F_RDONLY_PROG, inner_map_fd=-1, map_name="", map_ifindex=0, btf_fd=-1, btf_key_type_id=0, btf_value_type_id=0, btf_vmlinux_value_type_id=0, map_extra=0}, 72 <unfinished ...>
[pid  5786] memfd_create("syzkaller", 0 <unfinished ...>
[pid  5784] <... ioctl resumed>)        = 0
[pid  5783] sched_setscheduler(0, SCHED_FIFO, [7] <unfinished ...>
[pid  5786] <... memfd_create resumed>) = 4
[pid  5784] close(4 <unfinished ...>
[pid  5786] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid  5784] <... close resumed>)        = 0
[pid  5786] <... mmap resumed>)         = 0x7f69d7a00000
[  182.943293][ T5780] bcachefs (loop3): dropping and reconstructing all alloc info
[pid  5784] close(5)                    = 0
[pid  5783] <... sched_setscheduler resumed>) = 0
[pid  5781] <... bpf resumed>)          = 3
[pid  5784] mkdir("./file1", 0777 <unfinished ...>
[pid  5783] memfd_create("syzkaller", 0 <unfinished ...>
[pid  5784] <... mkdir resumed>)        = -1 EEXIST (File exists)
[pid  5783] <... memfd_create resumed>) = 4
[pid  5784] mount("/dev/loop2", "./file1", "bcachefs", MS_I_VERSION, "data_checksum=crc64,journal_transaction_names,norecovery,fsck,journal_flush_disabled,btree_node_pref"... <unfinished ...>
[pid  5783] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid  5781] sched_setscheduler(0, SCHED_FIFO, [7] <unfinished ...>
[pid  5783] <... mmap resumed>)         = 0x7f69d7a00000
[pid  5781] <... sched_setscheduler resumed>) = 0
[pid  5786] write(4, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 <unfinished ...>
[  183.307568][ T5780] bcachefs (loop3): check_topology... done
[pid  5781] memfd_create("syzkaller", 0 <unfinished ...>
[pid  5783] write(4, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 <unfinished ...>
[pid  5781] <... memfd_create resumed>) = 4
[pid  5786] <... write resumed>)        = 16777216
[pid  5781] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid  5786] munmap(0x7f69d7a00000, 138412032 <unfinished ...>
[pid  5781] <... mmap resumed>)         = 0x7f69d7a00000
[pid  5786] <... munmap resumed>)       = 0
[pid  5783] <... write resumed>)        = 16777216
[pid  5786] openat(AT_FDCWD, "/dev/loop4", O_RDWR <unfinished ...>
[  183.314106][ T5780] bcachefs (loop3): accounting_read... done
[  183.828032][ T5780] bcachefs (loop3): alloc_read... done
[  183.834317][ T5780] bcachefs (loop3): stripes_read... done
[  183.840360][ T5780] bcachefs (loop3): snapshots_read... done
[pid  5783] munmap(0x7f69d7a00000, 138412032 <unfinished ...>
[pid  5786] <... openat resumed>)       = 5
[pid  5783] <... munmap resumed>)       = 0
[pid  5786] ioctl(5, LOOP_SET_FD, 4 <unfinished ...>
[pid  5783] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 5
[pid  5783] ioctl(5, LOOP_SET_FD, 4 <unfinished ...>
[  183.847059][ T5780] bcachefs (loop3): check_allocations... done
[  183.986733][ T5780] bcachefs (loop3): going read-write
[pid  5781] write(4, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 <unfinished ...>
[pid  5786] <... ioctl resumed>)        = 0
[pid  5783] <... ioctl resumed>)        = 0
[pid  5786] close(4 <unfinished ...>
[pid  5783] close(4 <unfinished ...>
[pid  5786] <... close resumed>)        = 0
[pid  5783] <... close resumed>)        = 0
[pid  5786] close(5 <unfinished ...>
[pid  5783] close(5 <unfinished ...>
[pid  5786] <... close resumed>)        = 0
[pid  5783] <... close resumed>)        = 0
[pid  5786] mkdir("./file1", 0777 <unfinished ...>
[pid  5783] mkdir("./file1", 0777 <unfinished ...>
[pid  5786] <... mkdir resumed>)        = -1 EEXIST (File exists)
[pid  5783] <... mkdir resumed>)        = -1 EEXIST (File exists)
[  184.016801][ T5786] loop4: detected capacity change from 0 to 32768
[  184.024831][ T5783] loop0: detected capacity change from 0 to 32768
[pid  5786] mount("/dev/loop4", "./file1", "bcachefs", MS_I_VERSION, "data_checksum=crc64,journal_transaction_names,norecovery,fsck,journal_flush_disabled,btree_node_pref"... <unfinished ...>
[pid  5783] mount("/dev/loop0", "./file1", "bcachefs", MS_I_VERSION, "data_checksum=crc64,journal_transaction_names,norecovery,fsck,journal_flush_disabled,btree_node_pref"... <unfinished ...>
[pid  5780] <... mount resumed>)        = 0
[pid  5780] openat(AT_FDCWD, "./file1", O_RDONLY|O_DIRECTORY) = 4
[pid  5780] chdir("./file1")            = 0
[pid  5780] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = 5
[pid  5780] ioctl(5, LOOP_CLR_FD)       = 0
[pid  5780] close(5)                    = 0
[pid  5780] mkdir("./file0aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa", 004) = 0
[  184.200437][ T5780] bcachefs (loop3): done starting filesystem
[pid  5780] exit_group(0)               = ?
[pid  5780] +++ exited with 0 +++
[pid  5779] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5780, si_uid=0, si_status=0, si_utime=6 /* 0.06 s */, si_stime=93 /* 0.93 s */} ---
[pid  5779] restart_syscall(<... resuming interrupted clone ...>) = 0
[  184.293890][ T5784] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): filesystem UUID already open
[  184.303772][ T5784] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): shutdown complete
[pid  5779] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = 3
[pid  5779] ioctl(3, LOOP_CLR_FD)       = 0
[pid  5781] <... write resumed>)        = 16777216
[pid  5781] munmap(0x7f69d7a00000, 138412032) = 0
[pid  5779] close(3 <unfinished ...>
[pid  5781] openat(AT_FDCWD, "/dev/loop1", O_RDWR) = 5
[pid  5781] ioctl(5, LOOP_SET_FD, 4 <unfinished ...>
[pid  5779] <... close resumed>)        = 0
[pid  5779] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x5555667a7650) = 5820
./strace-static-x86_64: Process 5820 attached
[pid  5820] set_robust_list(0x5555667a7660, 24) = 0
[  184.434336][ T5786] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): filesystem UUID already open
[  184.444310][ T5786] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): shutdown complete
[  184.460092][ T5781] loop1: detected capacity change from 0 to 32768
[  184.481817][ T5783] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): filesystem UUID already open
[  184.491468][ T5783] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): shutdown complete
[  184.693095][ T5796] =====================================================
[  184.700452][ T5796] BUG: KMSAN: uninit-value in bch2_inode_v3_validate+0x481/0x5a0
[  184.708532][ T5796]  bch2_inode_v3_validate+0x481/0x5a0
[  184.714398][ T5796]  bch2_bkey_val_validate+0x2b5/0x440
[  184.719995][ T5796]  validate_bset_keys+0x1531/0x2080
[  184.725501][ T5796]  validate_bset_for_write+0x142/0x290
[  184.731182][ T5796]  __bch2_btree_node_write+0x53df/0x6830
[  184.737477][ T5796]  bch2_btree_node_write+0xa5/0x2e0
[pid  5820] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0
[pid  5781] <... ioctl resumed>)        = 0
[pid  5781] close(4)                    = 0
[pid  5781] close(5 <unfinished ...>
[pid  5820] setpgid(0, 0 <unfinished ...>
[pid  5786] <... mount resumed>)        = -1 EINVAL (Invalid argument)
[pid  5786] openat(AT_FDCWD, "/dev/loop4", O_RDWR) = 4
[pid  5786] ioctl(4, LOOP_CLR_FD)       = 0
[pid  5786] close(4)                    = 0
[pid  5786] mkdir("./file0aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa", 004) = 0
[pid  5786] exit_group(0)               = ?
[  184.742895][ T5796]  __btree_node_flush+0x606/0x680
[  184.745340][ T5786] bcachefs: bch2_fs_get_tree() error: EINVAL
[  184.748295][ T5796]  bch2_btree_node_flush1+0x38/0x60
[  184.759500][ T5796]  journal_flush_pins+0xce6/0x1780
[  184.764868][ T5796]  __bch2_journal_reclaim+0xda8/0x1670
[  184.771711][ T5796]  bch2_journal_reclaim_thread+0x18e/0x760
[  184.777906][ T5796]  kthread+0x3e2/0x540
[  184.782145][ T5796]  ret_from_fork+0x6d/0x90
[  184.786850][ T5796]  ret_from_fork_asm+0x1a/0x30
[pid  5786] +++ exited with 0 +++
[pid  5820] <... setpgid resumed>)      = 0
[pid  5782] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5786, si_uid=0, si_status=0, si_utime=7 /* 0.07 s */, si_stime=96 /* 0.96 s */} ---
[pid  5820] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5782] restart_syscall(<... resuming interrupted clone ...>) = 0
[pid  5820] <... openat resumed>)       = 3
[pid  5820] write(3, "1000", 4 <unfinished ...>
[pid  5782] openat(AT_FDCWD, "/dev/loop4", O_RDWR <unfinished ...>
[pid  5820] <... write resumed>)        = 4
[pid  5782] <... openat resumed>)       = 3
[pid  5820] close(3)                    = 0
executing program
[pid  5820] write(1, "executing program\n", 18 <unfinished ...>
[pid  5782] ioctl(3, LOOP_CLR_FD <unfinished ...>
[pid  5820] <... write resumed>)        = 18
[pid  5782] <... ioctl resumed>)        = 0
[  184.791857][ T5796] 
[  184.795112][ T5796] Uninit was stored to memory at:
[  184.800438][ T5796]  bch2_sort_keys_keep_unwritten_whiteouts+0x12d5/0x19d0
[  184.807857][ T5796]  __bch2_btree_node_write+0x3ae8/0x6830
[  184.814599][ T5796]  bch2_btree_node_write+0xa5/0x2e0
[  184.821609][ T5796]  __btree_node_flush+0x606/0x680
[  184.826905][ T5796]  bch2_btree_node_flush1+0x38/0x60
[  184.832961][ T5796]  journal_flush_pins+0xce6/0x1780
[  184.838419][ T5796]  __bch2_journal_reclaim+0xda8/0x1670
[  184.844143][ T5796]  bch2_journal_reclaim_thread+0x18e/0x760
[  184.850927][ T5796]  kthread+0x3e2/0x540
[  184.855307][ T5796]  ret_from_fork+0x6d/0x90
[  184.859919][ T5796]  ret_from_fork_asm+0x1a/0x30
[  184.864996][ T5796] 
[  184.867449][ T5796] Uninit was created at:
[  184.871961][ T5796]  ___kmalloc_large_node+0x22c/0x370
[  184.877546][ T5796]  __kmalloc_large_node_noprof+0x3f/0x1e0
[  184.883573][ T5796]  __kmalloc_node_noprof+0xc96/0x1250
[  184.889153][ T5796]  __kvmalloc_node_noprof+0xc0/0x2d0
[  184.894761][ T5796]  btree_node_sort+0x78a/0x1d30
[  184.899825][ T5796]  bch2_btree_post_write_cleanup+0x1b0/0xf20
[  184.906107][ T5796]  bch2_btree_node_write+0x21c/0x2e0
[  184.911596][ T5796]  __btree_node_flush+0x606/0x680
[  184.916892][ T5796]  bch2_btree_node_flush0+0x35/0x60
[  184.922278][ T5796]  journal_flush_pins+0xce6/0x1780
[  184.927644][ T5796]  __bch2_journal_reclaim+0xda8/0x1670
[  184.933354][ T5796]  bch2_journal_reclaim_thread+0x18e/0x760
[  184.939354][ T5796]  kthread+0x3e2/0x540
[  184.943659][ T5796]  ret_from_fork+0x6d/0x90
[  184.948251][ T5796]  ret_from_fork_asm+0x1a/0x30
[  184.953377][ T5796] 
[  184.955836][ T5796] CPU: 1 UID: 0 PID: 5796 Comm: bch-reclaim/loo Not tainted 6.13.0-rc4-syzkaller-00012-g9b2ffa6148b1 #0
[  184.967219][ T5796] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
[  184.977507][ T5796] =====================================================
[  184.984634][ T5796] Disabling lock debugging due to kernel taint
[  184.990921][ T5796] Kernel panic - not syncing: kmsan.panic set ...
[  184.997496][ T5796] CPU: 1 UID: 0 PID: 5796 Comm: bch-reclaim/loo Tainted: G    B              6.13.0-rc4-syzkaller-00012-g9b2ffa6148b1 #0
[  185.010334][ T5796] Tainted: [B]=BAD_PAGE
[  185.014612][ T5796] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
[  185.024840][ T5796] Call Trace:
[  185.028237][ T5796]  <TASK>
[  185.031273][ T5796]  dump_stack_lvl+0x216/0x2d0
[  185.036252][ T5796]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[pid  5820] bpf(BPF_MAP_CREATE, {map_type=BPF_MAP_TYPE_ARRAY, key_size=4, value_size=8, max_entries=1, map_flags=BPF_F_RDONLY_PROG, inner_map_fd=-1, map_name="", map_ifindex=0, btf_fd=-1, btf_key_type_id=0, btf_value_type_id=0, btf_vmlinux_value_type_id=0, map_extra=0}, 72 <unfinished ...>
[pid  5782] close(3 <unfinished ...>
[pid  5820] <... bpf resumed>)          = 3
[pid  5783] <... mount resumed>)        = -1 EINVAL (Invalid argument)
[  185.042252][ T5796]  dump_stack+0x1e/0x24
[  185.046605][ T5796]  panic+0x4e2/0xcf0
[  185.050707][ T5796]  ? kmsan_get_metadata+0x71/0x1c0
[  185.056018][ T5796]  kmsan_report+0x2c7/0x2d0
[  185.060692][ T5796]  ? kmsan_get_metadata+0x13e/0x1c0
[  185.066069][ T5796]  ? kmsan_get_metadata+0x13e/0x1c0
[  185.071712][ T5796]  ? __msan_warning+0x95/0x120
[  185.076694][ T5796]  ? bch2_inode_v3_validate+0x481/0x5a0
[  185.082540][ T5796]  ? bch2_bkey_val_validate+0x2b5/0x440
[  185.088324][ T5796]  ? validate_bset_keys+0x1531/0x2080
[  185.093907][ T5796]  ? validate_bset_for_write+0x142/0x290
[  185.099769][ T5796]  ? __bch2_btree_node_write+0x53df/0x6830
[  185.105806][ T5796]  ? bch2_btree_node_write+0xa5/0x2e0
[  185.111495][ T5796]  ? __btree_node_flush+0x606/0x680
[  185.116899][ T5796]  ? bch2_btree_node_flush1+0x38/0x60
[  185.122468][ T5796]  ? journal_flush_pins+0xce6/0x1780
[  185.127940][ T5796]  ? __bch2_journal_reclaim+0xda8/0x1670
[  185.133763][ T5796]  ? bch2_journal_reclaim_thread+0x18e/0x760
[  185.139938][ T5796]  ? kthread+0x3e2/0x540
[  185.144353][ T5796]  ? ret_from_fork+0x6d/0x90
[  185.149125][ T5796]  ? ret_from_fork_asm+0x1a/0x30
[  185.154283][ T5796]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  185.160276][ T5796]  ? kmsan_get_metadata+0x13e/0x1c0
[  185.165655][ T5796]  ? kmsan_internal_set_shadow_origin+0x69/0x100
[  185.172216][ T5796]  ? kmsan_get_metadata+0x13e/0x1c0
[  185.177610][ T5796]  ? kmsan_get_metadata+0x13e/0x1c0
[  185.182987][ T5796]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  185.188978][ T5796]  ? kmsan_get_metadata+0x13e/0x1c0
[  185.194352][ T5796]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  185.200346][ T5796]  ? kmsan_get_metadata+0x13e/0x1c0
[  185.205742][ T5796]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  185.211737][ T5796]  __msan_warning+0x95/0x120
[  185.216539][ T5796]  bch2_inode_v3_validate+0x481/0x5a0
[  185.222143][ T5796]  ? __pfx_bch2_inode_v3_validate+0x10/0x10
[  185.228254][ T5796]  bch2_bkey_val_validate+0x2b5/0x440
[  185.233876][ T5796]  validate_bset_keys+0x1531/0x2080
[  185.239351][ T5796]  validate_bset_for_write+0x142/0x290
[  185.245045][ T5796]  __bch2_btree_node_write+0x53df/0x6830
[  185.250913][ T5796]  ? __kmalloc_noprof+0xcd5/0x1230
[  185.256243][ T5796]  ? stack_depot_save_flags+0x2c/0x750
[  185.261912][ T5796]  ? kmsan_get_metadata+0x13e/0x1c0
[  185.267293][ T5796]  ? mempool_kmalloc+0x36/0x50
[  185.272297][ T5796]  ? kmsan_internal_set_shadow_origin+0x69/0x100
[  185.278906][ T5796]  bch2_btree_node_write+0xa5/0x2e0
[  185.284338][ T5796]  __btree_node_flush+0x606/0x680
[  185.289570][ T5796]  ? __btree_node_flush+0xd3/0x680
[  185.294903][ T5796]  ? __pfx_bch2_btree_node_flush1+0x10/0x10
[  185.301096][ T5796]  bch2_btree_node_flush1+0x38/0x60
[  185.306499][ T5796]  journal_flush_pins+0xce6/0x1780
[  185.311846][ T5796]  __bch2_journal_reclaim+0xda8/0x1670
[  185.317500][ T5796]  ? __try_to_del_timer_sync+0x3d3/0x420
[  185.323409][ T5796]  bch2_journal_reclaim_thread+0x18e/0x760
[  185.329455][ T5796]  kthread+0x3e2/0x540
[  185.333710][ T5796]  ? __pfx_bch2_journal_reclaim_thread+0x10/0x10
[  185.340247][ T5796]  ? __pfx_kthread+0x10/0x10
[  185.345031][ T5796]  ret_from_fork+0x6d/0x90
[  185.349808][ T5796]  ? __pfx_kthread+0x10/0x10
[  185.354619][ T5796]  ret_from_fork_asm+0x1a/0x30
[  185.359631][ T5796]  </TASK>
[  185.363058][ T5796] Kernel Offset: disabled
[  185.367479][ T5796] Rebooting in 86400 seconds..