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

<...>
Warning: Permanently added '10.128.0.48' (ED25519) to the list of known hosts.
execve("./syz-executor2884972746", ["./syz-executor2884972746"], 0x7ffdb0affde0 /* 10 vars */) = 0
brk(NULL)                               = 0x555563e7f000
brk(0x555563e7fd00)                     = 0x555563e7fd00
arch_prctl(ARCH_SET_FS, 0x555563e7f380) = 0
set_tid_address(0x555563e7f650)         = 5776
set_robust_list(0x555563e7f660, 24)     = 0
rseq(0x555563e7fca0, 0x20, 0, 0x53053053) = 0
prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
readlink("/proc/self/exe", "/root/syz-executor2884972746", 4096) = 28
getrandom("\x55\x64\x83\x09\xe5\xc0\xca\x67", 8, GRND_NONBLOCK) = 8
brk(NULL)                               = 0x555563e7fd00
brk(0x555563ea0d00)                     = 0x555563ea0d00
brk(0x555563ea1000)                     = 0x555563ea1000
mprotect(0x7fa7fe216000, 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./strace-static-x86_64: Process 5777 attached
, child_tidptr=0x555563e7f650) = 5777
[pid  5777] set_robust_list(0x555563e7f660, 24) = 0
[pid  5777] openat(AT_FDCWD, "/dev/loop0", O_RDWR <unfinished ...>
[pid  5776] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5777] <... openat resumed>)       = 3
[pid  5777] ioctl(3, LOOP_CLR_FD./strace-static-x86_64: Process 5778 attached
 <unfinished ...>
[pid  5776] <... clone resumed>, child_tidptr=0x555563e7f650) = 5778
[pid  5777] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5777] close(3 <unfinished ...>
[pid  5778] set_robust_list(0x555563e7f660, 24) = 0
[pid  5777] <... close resumed>)        = 0
[pid  5777] 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  5778] openat(AT_FDCWD, "/dev/loop1", O_RDWR <unfinished ...>
[pid  5777] <... clone resumed>, child_tidptr=0x555563e7f650) = 5779
./strace-static-x86_64: Process 5779 attached
[pid  5778] <... openat resumed>)       = 3
[pid  5779] set_robust_list(0x555563e7f660, 24) = 0
[pid  5776] <... clone resumed>, child_tidptr=0x555563e7f650) = 5780
[pid  5778] ioctl(3, LOOP_CLR_FD./strace-static-x86_64: Process 5780 attached
 <unfinished ...>
[pid  5779] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5776] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5780] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5778] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5779] <... prctl resumed>)        = 0
[pid  5780] <... set_robust_list resumed>) = 0
[pid  5780] openat(AT_FDCWD, "/dev/loop2", O_RDWR./strace-static-x86_64: Process 5781 attached
) = 3
[pid  5779] setpgid(0, 0 <unfinished ...>
[pid  5778] close(3 <unfinished ...>
[pid  5776] <... clone resumed>, child_tidptr=0x555563e7f650) = 5781
[pid  5779] <... setpgid resumed>)      = 0
[pid  5778] <... close resumed>)        = 0
[pid  5776] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5781] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5779] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5778] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5782 attached
 <unfinished ...>
[pid  5781] <... set_robust_list resumed>) = 0
[pid  5780] ioctl(3, LOOP_CLR_FD <unfinished ...>
[pid  5779] <... openat resumed>)       = 3
[pid  5781] openat(AT_FDCWD, "/dev/loop3", O_RDWR <unfinished ...>
[pid  5779] write(3, "1000", 4 <unfinished ...>
[pid  5780] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5782] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5779] <... write resumed>)        = 4
[pid  5782] <... set_robust_list resumed>) = 0
[pid  5781] <... openat resumed>)       = 3
[pid  5780] close(3 <unfinished ...>
[pid  5779] close(3)                    = 0
executing program
./strace-static-x86_64: Process 5783 attached
[pid  5776] <... clone resumed>, child_tidptr=0x555563e7f650) = 5782
[pid  5782] openat(AT_FDCWD, "/dev/loop4", O_RDWR <unfinished ...>
[pid  5780] <... close resumed>)        = 0
[pid  5779] write(1, "executing program\n", 18 <unfinished ...>
[pid  5778] <... clone resumed>, child_tidptr=0x555563e7f650) = 5783
[pid  5783] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5780] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5779] <... write resumed>)        = 18
[pid  5782] <... openat resumed>)       = 3
[pid  5779] memfd_create("syzkaller", 0) = 3
[pid  5779] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fa7f5c00000
[pid  5783] <... set_robust_list resumed>) = 0
[pid  5782] ioctl(3, LOOP_CLR_FD <unfinished ...>
[pid  5781] ioctl(3, LOOP_CLR_FD./strace-static-x86_64: Process 5784 attached
 <unfinished ...>
[pid  5782] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5781] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5782] close(3 <unfinished ...>
[pid  5780] <... clone resumed>, child_tidptr=0x555563e7f650) = 5784
[pid  5781] close(3 <unfinished ...>
[pid  5783] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5784] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5783] <... prctl resumed>)        = 0
[pid  5782] <... close resumed>)        = 0
[pid  5781] <... close resumed>)        = 0
[pid  5784] <... set_robust_list resumed>) = 0
[pid  5783] setpgid(0, 0 <unfinished ...>
[pid  5781] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5782] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5784] prctl(PR_SET_PDEATHSIG, SIGKILL./strace-static-x86_64: Process 5785 attached
./strace-static-x86_64: Process 5786 attached
) = 0
[pid  5786] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5785] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5783] <... setpgid resumed>)      = 0
[pid  5782] <... clone resumed>, child_tidptr=0x555563e7f650) = 5786
[pid  5781] <... clone resumed>, child_tidptr=0x555563e7f650) = 5785
[pid  5786] <... set_robust_list resumed>) = 0
[pid  5786] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5785] <... set_robust_list resumed>) = 0
[pid  5784] setpgid(0, 0 <unfinished ...>
[pid  5783] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5786] <... prctl resumed>)        = 0
[pid  5785] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5784] <... setpgid resumed>)      = 0
[pid  5783] <... openat resumed>)       = 3
[pid  5786] setpgid(0, 0 <unfinished ...>
[pid  5783] write(3, "1000", 4 <unfinished ...>
[pid  5785] <... prctl resumed>)        = 0
[pid  5783] <... write resumed>)        = 4
[pid  5786] <... setpgid resumed>)      = 0
[pid  5785] setpgid(0, 0 <unfinished ...>
[pid  5783] close(3 <unfinished ...>
[pid  5785] <... setpgid resumed>)      = 0
[pid  5783] <... close resumed>)        = 0
executing program
[pid  5786] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5785] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5784] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5783] write(1, "executing program\n", 18 <unfinished ...>
[pid  5785] <... openat resumed>)       = 3
[pid  5784] <... openat resumed>)       = 3
[pid  5783] <... write resumed>)        = 18
[pid  5786] <... openat resumed>)       = 3
[pid  5784] write(3, "1000", 4 <unfinished ...>
[pid  5786] write(3, "1000", 4 <unfinished ...>
[pid  5785] write(3, "1000", 4 <unfinished ...>
[pid  5784] <... write resumed>)        = 4
[pid  5783] memfd_create("syzkaller", 0) = 3
[pid  5786] <... write resumed>)        = 4
[pid  5785] <... write resumed>)        = 4
[pid  5786] close(3 <unfinished ...>
[pid  5785] close(3 <unfinished ...>
[pid  5783] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid  5786] <... close resumed>)        = 0
executing program
executing program
executing program
[pid  5786] write(1, "executing program\n", 18 <unfinished ...>
[pid  5785] <... close resumed>)        = 0
[pid  5784] close(3 <unfinished ...>
[pid  5783] <... mmap resumed>)         = 0x7fa7f5c00000
[pid  5786] <... write resumed>)        = 18
[pid  5785] write(1, "executing program\n", 18 <unfinished ...>
[pid  5784] <... close resumed>)        = 0
[pid  5786] memfd_create("syzkaller", 0 <unfinished ...>
[pid  5784] write(1, "executing program\n", 18 <unfinished ...>
[pid  5785] <... write resumed>)        = 18
[pid  5786] <... memfd_create resumed>) = 3
[pid  5785] memfd_create("syzkaller", 0 <unfinished ...>
[pid  5784] <... write resumed>)        = 18
[pid  5786] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid  5784] memfd_create("syzkaller", 0 <unfinished ...>
[pid  5786] <... mmap resumed>)         = 0x7fa7f5c00000
[pid  5785] <... memfd_create resumed>) = 3
[pid  5784] <... memfd_create resumed>) = 3
[pid  5785] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid  5784] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fa7f5c00000
[pid  5785] <... mmap resumed>)         = 0x7fa7f5c00000
[pid  5779] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\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] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\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  5785] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\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] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\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  5784] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\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  5779] <... write resumed>)        = 16777216
[pid  5779] munmap(0x7fa7f5c00000, 138412032) = 0
[pid  5779] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4
[pid  5779] ioctl(4, LOOP_SET_FD, 3)    = 0
[  214.910774][ T5779] loop0: detected capacity change from 0 to 32768
[pid  5779] close(3)                    = 0
[pid  5779] close(4)                    = 0
[pid  5779] mkdir("./file0", 0777)      = 0
[pid  5779] mount("/dev/loop0", "./file0", "bcachefs", MS_STRICTATIME, "norecovery,errors=continue,sma*,au}-,euid=" <unfinished ...>
[pid  5784] <... write resumed>)        = 16777216
[pid  5784] munmap(0x7fa7f5c00000, 138412032 <unfinished ...>
[pid  5786] <... write resumed>)        = 16777216
[pid  5786] munmap(0x7fa7f5c00000, 138412032 <unfinished ...>
[pid  5784] <... munmap resumed>)       = 0
[pid  5784] openat(AT_FDCWD, "/dev/loop2", O_RDWR) = 4
[  215.013601][ T5779] bcachefs (/dev/loop0): error reading default superblock: checksum error, type none: got  should be 
[pid  5784] ioctl(4, LOOP_SET_FD, 3)    = 0
[pid  5786] <... munmap resumed>)       = 0
[pid  5786] openat(AT_FDCWD, "/dev/loop4", O_RDWR) = 4
[pid  5784] close(3)                    = 0
[pid  5786] ioctl(4, LOOP_SET_FD, 3 <unfinished ...>
[pid  5783] <... write resumed>)        = 16777216
[pid  5785] <... write resumed>)        = 16777216
[pid  5784] close(4 <unfinished ...>
[pid  5785] munmap(0x7fa7f5c00000, 138412032 <unfinished ...>
[pid  5783] munmap(0x7fa7f5c00000, 138412032 <unfinished ...>
[pid  5784] <... close resumed>)        = 0
[  215.060171][ T5784] loop2: detected capacity change from 0 to 32768
[pid  5784] mkdir("./file0", 0777)      = -1 EEXIST (File exists)
[pid  5784] mount("/dev/loop2", "./file0", "bcachefs", MS_STRICTATIME, "norecovery,errors=continue,sma*,au}-,euid=" <unfinished ...>
[pid  5786] <... ioctl resumed>)        = 0
[pid  5785] <... munmap resumed>)       = 0
[pid  5783] <... munmap resumed>)       = 0
[pid  5786] close(3)                    = 0
[pid  5785] openat(AT_FDCWD, "/dev/loop3", O_RDWR <unfinished ...>
[pid  5783] openat(AT_FDCWD, "/dev/loop1", O_RDWR <unfinished ...>
[pid  5786] close(4 <unfinished ...>
[pid  5785] <... openat resumed>)       = 4
[pid  5783] <... openat resumed>)       = 4
[pid  5786] <... close resumed>)        = 0
[pid  5783] ioctl(4, LOOP_SET_FD, 3 <unfinished ...>
[pid  5786] mkdir("./file0", 0777)      = -1 EEXIST (File exists)
[pid  5786] mount("/dev/loop4", "./file0", "bcachefs", MS_STRICTATIME, "norecovery,errors=continue,sma*,au}-,euid=" <unfinished ...>
[  215.125477][ T5786] loop4: detected capacity change from 0 to 32768
[  215.142689][ T5784] bcachefs (/dev/loop2): error reading default superblock: checksum error, type none: got  should be 
[pid  5785] ioctl(4, LOOP_SET_FD, 3 <unfinished ...>
[pid  5783] <... ioctl resumed>)        = 0
[  215.195258][ T5786] bcachefs (/dev/loop4): error reading default superblock: checksum error, type none: got  should be 
[  215.225768][ T5783] loop1: detected capacity change from 0 to 32768
[pid  5783] close(3 <unfinished ...>
[pid  5785] <... ioctl resumed>)        = 0
[pid  5783] <... close resumed>)        = 0
[pid  5785] close(3 <unfinished ...>
[pid  5783] close(4)                    = 0
[pid  5785] <... close resumed>)        = 0
[pid  5783] mkdir("./file0", 0777)      = -1 EEXIST (File exists)
[pid  5783] mount("/dev/loop1", "./file0", "bcachefs", MS_STRICTATIME, "norecovery,errors=continue,sma*,au}-,euid=" <unfinished ...>
[pid  5785] close(4)                    = 0
[  215.250667][ T5785] loop3: detected capacity change from 0 to 32768
[pid  5785] mkdir("./file0", 0777)      = -1 EEXIST (File exists)
[  215.288315][ T5783] bcachefs (/dev/loop1): error reading default superblock: checksum error, type none: got  should be 
[  215.325421][ T5785] bcachefs (/dev/loop3): error reading default superblock: checksum error, type none: got  should be 
[  215.403785][ T5779] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=errors=continue,metadata_checksum=none,data_checksum=none,compression=lz4,norecovery,nojournal_transaction_names
[  215.422445][ T5779] bcachefs (loop0): recovering from clean shutdown, journal seq 10
[  215.431879][ T5779] bcachefs (loop0): Doing compatible version upgrade from 1.7: mi_btree_bitmap to 1.13: inode_has_child_snapshots
[  215.431879][ T5779]   running recovery passes: check_allocations,check_inodes
[  215.618202][ T5784] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): filesystem UUID already open
[  215.628410][ T5784] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): shutdown complete
[  215.649696][ T5779] bcachefs (loop0): error validating btree node at btree alloc level 0/0
[  215.649811][ T5779]   u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq ac62141f8dc7e261 written 24 min_key POS_MIN durability: 1 ptr: 0:26:0 gen 0  
[  215.649930][ T5779]   node offset 8/24 bset u64s 375 bset byte offset 328: keys out of order: u64s 11 type alloc_v4 0:4398046511107:0 len 0 ver 0 > u64s 11 type alloc_v4 0:4:0 len 0 ver 0, fixing
[  215.678876][ T5786] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): filesystem UUID already open
[  215.679278][ T5786] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): shutdown complete
[  215.786655][ T5783] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): filesystem UUID already open
[  215.787020][ T5783] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): shutdown complete
[  215.822860][ T5785] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): filesystem UUID already open
[  215.823243][ T5785] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): shutdown complete
[  215.982674][ T5779] bcachefs (loop0): btree_node_read_work: rewriting btree node at btree=alloc level=0 SPOS_MAX due to error
[  216.076038][ T5779] bcachefs (loop0): error validating btree node on loop0 at btree freespace level 0/0
[  216.076206][ T5779]   u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq e81e1ed936acf3df written 21 min_key POS_MIN durability: 1 ptr: 0:29:0 gen 0  
[  216.076325][ T5779]   node offset 16/21 bset u64s 13: bset past end of btree node (offset 16 len 8 but written 21), fixing
[  216.206143][ T5779] bcachefs (loop0): error validating btree node on loop0 at btree freespace level 0/0
[  216.206258][ T5779]   u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq e81e1ed936acf3df written 21 min_key POS_MIN durability: 1 ptr: 0:29:0 gen 0  
[  216.206371][ T5779]   node offset 16/21 bset u64s 0: empty bset, fixing
[  216.305610][ T5779] bcachefs (loop0): btree_node_read_work: rewriting btree node at btree=freespace level=0 SPOS_MAX due to error
[  216.367338][ T5779] bcachefs (loop0): accounting_read... done
[  216.384505][ T5779] bcachefs (loop0): alloc_read... done
[  216.410629][ T5779] bcachefs (loop0): stripes_read... done
[  216.417124][ T5779] bcachefs (loop0): snapshots_read... done
[  216.441244][ T5779] bcachefs (loop0): check_allocations...
[  216.448771][ T5779] bcachefs (loop0): pointer to nonexistent bucket 0:4398046511107
[  216.463040][ T5779] bcachefs (loop0): bch2_gc_mark_key(): error EIO
[  216.469957][ T5779] bcachefs (loop0): bch2_gc_btree(): error EIO
[  216.476826][ T5779] btree node read error for alloc, fixing
[  216.562677][ T5779] bcachefs (loop0): running explicit recovery pass check_topology (2), currently at check_allocations (8)
[  216.562868][ T5786] bcachefs: bch2_fs_get_tree() error: EINVAL
[  216.574572][ T5779] bcachefs (loop0): bch2_gc_btrees(): error restart_recovery
[pid  5785] mount("/dev/loop3", "./file0", "bcachefs", MS_STRICTATIME, "norecovery,errors=continue,sma*,au}-,euid=" <unfinished ...>
[pid  5786] <... mount resumed>)        = -1 EINVAL (Invalid argument)
[pid  5786] openat(AT_FDCWD, "/dev/loop4", O_RDWR) = 3
[pid  5786] ioctl(3, LOOP_CLR_FD)       = 0
[  216.630846][ T5779] bcachefs (loop0): bch2_check_allocations(): error restart_recovery
[  216.639664][ T5779] bcachefs (loop0): check_topology... done
[  216.646202][ T5779] bcachefs (loop0): accounting_read... done
[  216.691747][ T5779] bcachefs (loop0): alloc_read... done
[  216.697932][ T5779] bcachefs (loop0): stripes_read... done
[pid  5786] close(3 <unfinished ...>
[pid  5783] <... mount resumed>)        = -1 EINVAL (Invalid argument)
[  216.731141][ T5779] bcachefs (loop0): snapshots_read...
[  216.739812][ T5783] bcachefs: bch2_fs_get_tree() error: EINVAL
[  216.763491][ T5784] bcachefs: bch2_fs_get_tree() error: EINVAL
[  216.768227][ T5779]  done
[pid  5783] openat(AT_FDCWD, "/dev/loop1", O_RDWR) = 3
[  216.772790][ T5779] bcachefs (loop0): check_allocations...
[  216.806458][ T5779] bcachefs (loop0): pointer to nonexistent bucket 0:4398046511107
[  216.808156][ T5785] bcachefs: bch2_fs_get_tree() error: EINVAL
[  216.812313][ T5779] bcachefs (loop0): bch2_gc_mark_key(): error EIO
[  216.812402][ T5779] bcachefs (loop0): bch2_gc_btree(): error EIO
[pid  5783] ioctl(3, LOOP_CLR_FD)       = 0
[pid  5785] <... mount resumed>)        = -1 EINVAL (Invalid argument)
[pid  5783] close(3 <unfinished ...>
[pid  5785] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = 3
[  216.852670][ T5779] btree node read error for alloc, fixing
[pid  5785] ioctl(3, LOOP_CLR_FD)       = 0
[pid  5784] <... mount resumed>)        = -1 EINVAL (Invalid argument)
[pid  5784] openat(AT_FDCWD, "/dev/loop2", O_RDWR <unfinished ...>
[pid  5785] close(3 <unfinished ...>
[pid  5784] <... openat resumed>)       = 3
[pid  5784] ioctl(3, LOOP_CLR_FD)       = 0
[  216.954388][ T5779] btree ptr not marked in member info btree allocated bitmap
[  216.954497][ T5779]   u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 7589ab5e0c11cc7a written 24 min_key POS_MIN durability: 1 ptr: 0:38:0 gen 0, fixing
[  217.088695][ T5779] bucket 0:38 data type btree ptr gen 0 missing in alloc btree
[  217.088805][ T5779] while marking u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 7589ab5e0c11cc7a written 24 min_key POS_MIN durability: 1 ptr: 0:38:0 gen 0, fixing
[  217.203685][ T5779] btree ptr not marked in member info btree allocated bitmap
[  217.203791][ T5779]   u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 9aa2895aefce4bdf written 24 min_key POS_MIN durability: 1 ptr: 0:41:0 gen 0, fixing
[pid  5784] close(3 <unfinished ...>
[pid  5777] kill(-5779, SIGKILL)        = 0
[pid  5777] kill(5779, SIGKILL)         = 0
[  217.372531][ T5779] bucket 0:41 data type btree ptr gen 0 missing in alloc btree
[pid  5778] kill(-5783, SIGKILL)        = 0
[pid  5778] kill(5783, SIGKILL)         = 0
[pid  5780] kill(-5784, SIGKILL)        = 0
[pid  5780] kill(5784, SIGKILL)         = 0
[  217.372642][ T5779] while marking u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 9aa2895aefce4bdf written 24 min_key POS_MIN durability: 1 ptr: 0:41:0 gen 0, fixing
[pid  5782] kill(-5786, SIGKILL <unfinished ...>
[pid  5781] kill(-5785, SIGKILL <unfinished ...>
[pid  5782] <... kill resumed>)         = 0
[pid  5782] kill(5786, SIGKILL)         = 0
[pid  5781] <... kill resumed>)         = 0
[pid  5781] kill(5785, SIGKILL)         = 0
[  217.529105][ T5779] btree ptr not marked in member info btree allocated bitmap
[  217.529212][ T5779]   u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c0bef60d07ceb940 written 16 min_key POS_MIN durability: 1 ptr: 0:35:0 gen 0, fixing
[  217.652693][ T5779] bucket 0:35 data type btree ptr gen 0 missing in alloc btree
[  217.652803][ T5779] while marking u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c0bef60d07ceb940 written 16 min_key POS_MIN durability: 1 ptr: 0:35:0 gen 0, fixing
[  217.779075][ T5779] btree ptr not marked in member info btree allocated bitmap
[  217.779184][ T5779]   u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq ebb8d5a9e3463bdb written 16 min_key POS_MIN durability: 1 ptr: 0:32:0 gen 0, fixing
[  217.913218][ T5779] bucket 0:32 data type btree ptr gen 0 missing in alloc btree
[  217.913336][ T5779] while marking u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq ebb8d5a9e3463bdb written 16 min_key POS_MIN durability: 1 ptr: 0:32:0 gen 0, fixing
[  218.058048][ T5779] btree ptr not marked in member info btree allocated bitmap
[  218.058154][ T5779]   u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq e81e1ed936acf3df written 21 min_key POS_MIN durability: 1 ptr: 0:29:0 gen 0, fixing
[  218.160684][ T5779] bucket 0:29 data type btree ptr gen 0 missing in alloc btree
[pid  5783] <... close resumed>)        = ?
[  218.160796][ T5779] while marking u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq e81e1ed936acf3df written 21 min_key POS_MIN durability: 1 ptr: 0:29:0 gen 0, fixing
[pid  5783] +++ killed by SIGKILL +++
[pid  5778] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_KILLED, si_pid=5783, si_uid=0, si_status=SIGKILL, si_utime=4 /* 0.04 s */, si_stime=156 /* 1.56 s */} ---
[pid  5778] openat(AT_FDCWD, "/dev/loop1", O_RDWR <unfinished ...>
[pid  5785] <... close resumed>)        = ?
[pid  5778] <... openat resumed>)       = 3
[pid  5778] ioctl(3, LOOP_CLR_FD)       = -1 ENXIO (No such device or address)
[pid  5778] close(3)                    = 0
[  218.290924][ T5779] bucket 0:3 gen 0 has wrong data_type: got free, should be sb, fixing
[pid  5785] +++ killed by SIGKILL +++
[pid  5778] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5826 attached
 <unfinished ...>
[pid  5786] <... close resumed>)        = ?
[pid  5826] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5781] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_KILLED, si_pid=5785, si_uid=0, si_status=SIGKILL, si_utime=4 /* 0.04 s */, si_stime=166 /* 1.66 s */} ---
[pid  5778] <... clone resumed>, child_tidptr=0x555563e7f650) = 5826
[pid  5826] <... set_robust_list resumed>) = 0
[pid  5826] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5781] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = 3
[pid  5826] <... prctl resumed>)        = 0
[pid  5781] ioctl(3, LOOP_CLR_FD <unfinished ...>
[pid  5826] setpgid(0, 0 <unfinished ...>
[pid  5781] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5826] <... setpgid resumed>)      = 0
[pid  5826] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[  218.333909][ T5779] bucket 0:3 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  218.352558][ T5779] bucket 0:4 gen 0 has wrong data_type: got free, should be sb, fixing
[pid  5781] close(3 <unfinished ...>
[pid  5826] <... openat resumed>)       = 3
[pid  5786] +++ killed by SIGKILL +++
[pid  5781] <... close resumed>)        = 0
[pid  5782] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_KILLED, si_pid=5786, si_uid=0, si_status=SIGKILL, si_utime=4 /* 0.04 s */, si_stime=165 /* 1.65 s */} ---
[pid  5781] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5782] openat(AT_FDCWD, "/dev/loop4", O_RDWR) = 3
executing program
[pid  5826] write(3, "1000", 4 <unfinished ...>
[pid  5782] ioctl(3, LOOP_CLR_FD <unfinished ...>
[pid  5826] <... write resumed>)        = 4
[pid  5782] <... ioctl resumed>)        = -1 ENXIO (No such device or address)
[pid  5781] <... clone resumed>, child_tidptr=0x555563e7f650) = 5827
[pid  5826] close(3 <unfinished ...>
[pid  5782] close(3 <unfinished ...>
[pid  5826] <... close resumed>)        = 0
[pid  5826] write(1, "executing program\n", 18./strace-static-x86_64: Process 5827 attached
) = 18
[pid  5782] <... close resumed>)        = 0
[pid  5827] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5782] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD <unfinished ...>
[pid  5826] memfd_create("syzkaller", 0./strace-static-x86_64: Process 5828 attached
 <unfinished ...>
[pid  5827] <... set_robust_list resumed>) = 0
[pid  5826] <... memfd_create resumed>) = 3
[pid  5828] set_robust_list(0x555563e7f660, 24 <unfinished ...>
[pid  5827] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0
[pid  5828] <... set_robust_list resumed>) = 0
[pid  5826] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid  5782] <... clone resumed>, child_tidptr=0x555563e7f650) = 5828
[pid  5826] <... mmap resumed>)         = 0x7fa7f5c00000
[pid  5828] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5827] setpgid(0, 0)               = 0
[pid  5827] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5828] <... prctl resumed>)        = 0
[pid  5827] <... openat resumed>)       = 3
[pid  5828] setpgid(0, 0 <unfinished ...>
[pid  5827] write(3, "1000", 4 <unfinished ...>
[pid  5828] <... setpgid resumed>)      = 0
[  218.385427][ T5779] bucket 0:4 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  218.421498][ T5779] bucket 0:5 gen 0 has wrong data_type: got free, should be sb, fixing
[pid  5827] <... write resumed>)        = 4
[pid  5828] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC <unfinished ...>
[pid  5827] close(3executing program
)                    = 0
[pid  5827] write(1, "executing program\n", 18 <unfinished ...>
[pid  5828] <... openat resumed>)       = 3
[pid  5827] <... write resumed>)        = 18
[pid  5827] memfd_create("syzkaller", 0 <unfinished ...>
[pid  5828] write(3, "1000", 4 <unfinished ...>
[pid  5827] <... memfd_create resumed>) = 3
[pid  5828] <... write resumed>)        = 4
[pid  5827] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid  5828] close(3 <unfinished ...>
[pid  5827] <... mmap resumed>)         = 0x7fa7f5c00000
[pid  5828] <... close resumed>)        = 0
[pid  5828] write(1, "executing program\n", 18executing program
) = 18
[  218.466224][ T5779] bucket 0:5 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[pid  5828] memfd_create("syzkaller", 0) = 3
[pid  5828] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fa7f5c00000
[  218.525744][ T5779] bucket 0:6 gen 0 has wrong data_type: got free, should be sb, fixing
[  218.551313][ T5779] bucket 0:6 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  218.588788][ T5779] bucket 0:7 gen 0 has wrong data_type: got free, should be sb, fixing
[  218.634818][ T5779] bucket 0:7 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  218.663797][ T5779] bucket 0:8 gen 0 has wrong data_type: got free, should be sb, fixing
[pid  5784] <... close resumed>)        = ?
[pid  5784] +++ killed by SIGKILL +++
[pid  5780] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_KILLED, si_pid=5784, si_uid=0, si_status=SIGKILL, si_utime=3 /* 0.03 s */, si_stime=181 /* 1.81 s */} ---
[  218.706999][ T5779] bucket 0:8 gen 0 data type sb has wrong dirty_sectors: got 0, should be 8, fixing
[  218.742904][ T5779] bucket 0:9 gen 0 has wrong data_type: got free, should be journal, fixing
[pid  5780] openat(AT_FDCWD, "/dev/loop2", O_RDWR) = 3
[pid  5780] ioctl(3, LOOP_CLR_FD)       = -1 ENXIO (No such device or address)
[pid  5780] close(3)                    = 0
[pid  5780] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5829 attached
 <unfinished ...>
[  218.789861][ T5779] bucket 0:9 gen 0 data type journal has wrong dirty_sectors: got 0, should be 256, fixing
[pid  5829] set_robust_list(0x555563e7f660, 24) = 0
[pid  5829] prctl(PR_SET_PDEATHSIG, SIGKILL <unfinished ...>
[pid  5780] <... clone resumed>, child_tidptr=0x555563e7f650) = 5829
[pid  5829] <... prctl resumed>)        = 0
[pid  5829] setpgid(0, 0)               = 0
[  218.849187][ T5779] bucket 0:10 gen 0 has wrong data_type: got free, should be journal, fixing
[pid  5829] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3
[pid  5829] write(3, "1000", 4)         = 4
[pid  5829] close(3)                    = 0
[  218.895792][ T5779] bucket 0:10 gen 0 data type journal has wrong dirty_sectors: got 0, should be 256, fixing
[  218.924722][ T5779] bucket 0:11 gen 0 has wrong data_type: got free, should be journal, fixing
[pid  5829] write(1, "executing program\n", 18executing program
) = 18
[pid  5829] memfd_create("syzkaller", 0) = 3
[pid  5829] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fa7f5c00000
[  218.939808][ T5779] bucket 0:11 gen 0 data type journal has wrong dirty_sectors: got 0, should be 256, fixing
[  218.996047][ T5779] bucket 0:12 gen 0 has wrong data_type: got free, should be journal, fixing
[  219.035218][ T5779] bucket 0:12 gen 0 data type journal has wrong dirty_sectors: got 0, should be 256, fixing
[  219.075615][ T5779] bucket 0:13 gen 0 has wrong data_type: got free, should be journal, fixing
[  219.104906][ T5779] bucket 0:13 gen 0 data type journal has wrong dirty_sectors: got 0, should be 256, fixing
[pid  5777] openat(AT_FDCWD, "/sys/fs/fuse/connections", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3
[  219.139974][ T5779] bucket 0:14 gen 0 has wrong data_type: got free, should be journal, fixing
[  219.174816][ T5779] bucket 0:14 gen 0 data type journal has wrong dirty_sectors: got 0, should be 256, fixing
[pid  5777] newfstatat(3, "",  <unfinished ...>
[pid  5826] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\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  5777] <... newfstatat resumed>{st_mode=S_IFDIR|0755, st_size=0, ...}, AT_EMPTY_PATH) = 0
[pid  5777] getdents64(3, 0x555563e806f0 /* 2 entries */, 32768) = 48
[pid  5777] getdents64(3, 0x555563e806f0 /* 0 entries */, 32768) = 0
[pid  5777] close(3)                    = 0
[  219.205735][ T5779] bucket 0:15 gen 0 has wrong data_type: got free, should be journal, fixing
[  219.231402][ T5779] bucket 0:15 gen 0 data type journal has wrong dirty_sectors: got 0, should be 256, fixing
[pid  5827] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\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 ...>
[  219.281655][ T5779] bucket 0:16 gen 0 has wrong data_type: got free, should be sb, fixing
[  219.315062][ T5779] bucket 0:16 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  219.337317][ T5779] bucket 0:17 gen 0 has wrong data_type: got free, should be sb, fixing
[  219.374846][ T5779] bucket 0:17 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  219.405737][ T5779] bucket 0:18 gen 0 has wrong data_type: got free, should be sb, fixing
[  219.436951][ T5779] bucket 0:18 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  219.474412][ T5779] bucket 0:19 gen 0 has wrong data_type: got free, should be sb, fixing
[  219.483587][ T5779] bucket 0:19 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  219.516098][ T5779] bucket 0:20 gen 0 has wrong data_type: got free, should be sb, fixing
[  219.545072][ T5779] bucket 0:20 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  219.579374][ T5779] bucket 0:21 gen 0 has wrong data_type: got free, should be sb, fixing
[pid  5828] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\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 ...>
[  219.624794][ T5779] bucket 0:21 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  219.645747][ T5779] bucket 0:22 gen 0 has wrong data_type: got free, should be sb, fixing
[  219.684810][ T5779] bucket 0:22 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  219.788439][ T5779] bucket 0:23 gen 0 has wrong data_type: got free, should be sb, fixing
[  219.844086][ T5779] bucket 0:23 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  219.910119][ T5779] bucket 0:24 gen 0 has wrong data_type: got free, should be journal, fixing
[  219.945837][ T5779] bucket 0:24 gen 0 data type journal has wrong dirty_sectors: got 0, should be 256, fixing
[  219.985988][ T5779] bucket 0:29 gen 0 has wrong data_type: got free, should be btree, fixing
[  220.018106][ T5779] bucket 0:29 gen 0 data type btree has wrong dirty_sectors: got 0, should be 256, fixing
[  220.054896][ T5779] bucket 0:32 gen 0 has wrong data_type: got free, should be btree, fixing
[  220.082288][ T5779] bucket 0:32 gen 0 data type btree has wrong dirty_sectors: got 0, should be 256, fixing
[  220.110714][ T5779] bucket 0:35 gen 0 has wrong data_type: got free, should be btree, fixing
[  220.136089][ T5779] bucket 0:35 gen 0 data type btree has wrong dirty_sectors: got 0, should be 256, fixing
[  220.175026][ T5779] bucket 0:38 gen 0 has wrong data_type: got free, should be btree, fixing
[  220.185001][ T5779] bucket 0:38 gen 0 data type btree has wrong dirty_sectors: got 0, should be 256, fixing
[  220.207051][ T5779] bucket 0:41 gen 0 has wrong data_type: got free, should be btree, fixing
[  220.231577][ T5779] bucket 0:41 gen 0 data type btree has wrong dirty_sectors: got 0, should be 256, fixing
[  220.273085][ T5779] bucket 0:120 gen 0 has wrong data_type: got free, should be sb, fixing
[  220.304769][ T5779] bucket 0:120 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  220.337322][ T5779] bucket 0:121 gen 0 has wrong data_type: got free, should be sb, fixing
[  220.361360][ T5779] bucket 0:121 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  220.387178][ T5779] bucket 0:122 gen 0 has wrong data_type: got free, should be sb, fixing
[  220.414809][ T5779] bucket 0:122 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  220.443195][ T5779] bucket 0:123 gen 0 has wrong data_type: got free, should be sb, fixing
[  220.473518][ T5779] bucket 0:123 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  220.495811][ T5779] bucket 0:124 gen 0 has wrong data_type: got free, should be sb, fixing
[  220.531135][ T5779] bucket 0:124 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  220.566504][ T5779] bucket 0:125 gen 0 has wrong data_type: got free, should be sb, fixing
[  220.615203][ T5779] bucket 0:125 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  220.648534][ T5779] bucket 0:126 gen 0 has wrong data_type: got free, should be sb, fixing
[pid  5829] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\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  5826] <... write resumed>)        = 16777216
[  220.685315][ T5779] bucket 0:126 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  220.711915][ T5779] bucket 0:127 gen 0 has wrong data_type: got free, should be sb, fixing
[pid  5826] munmap(0x7fa7f5c00000, 138412032 <unfinished ...>
[pid  5827] <... write resumed>)        = 16777216
[pid  5827] munmap(0x7fa7f5c00000, 138412032 <unfinished ...>
[pid  5826] <... munmap resumed>)       = 0
[  220.751558][ T5779] bucket 0:127 gen 0 data type sb has wrong dirty_sectors: got 0, should be 256, fixing
[  220.791372][ T5779]  done
[pid  5826] openat(AT_FDCWD, "/dev/loop1", O_RDWR) = 4
[pid  5826] ioctl(4, LOOP_SET_FD, 3 <unfinished ...>
[pid  5827] <... munmap resumed>)       = 0
[pid  5826] <... ioctl resumed>)        = 0
[pid  5826] close(3)                    = 0
[pid  5827] openat(AT_FDCWD, "/dev/loop3", O_RDWR <unfinished ...>
[pid  5826] close(4)                    = 0
[pid  5827] <... openat resumed>)       = 4
[pid  5826] mkdir("./file0", 0777 <unfinished ...>
[  220.818140][ T5826] loop1: detected capacity change from 0 to 32768
[  220.841783][ T5779] bcachefs (loop0): going read-write
[pid  5827] ioctl(4, LOOP_SET_FD, 3 <unfinished ...>
[pid  5826] <... mkdir resumed>)        = -1 EEXIST (File exists)
[pid  5826] mount("/dev/loop1", "./file0", "bcachefs", MS_STRICTATIME, "norecovery,errors=continue,sma*,au}-,euid=" <unfinished ...>
[pid  5827] <... ioctl resumed>)        = 0
[pid  5827] close(3)                    = 0
[pid  5827] close(4)                    = 0
[pid  5827] mkdir("./file0", 0777)      = -1 EEXIST (File exists)
[  220.871563][ T5827] loop3: detected capacity change from 0 to 32768
[  220.886322][ T5826] bcachefs (/dev/loop1): error reading default superblock: checksum error, type none: got  should be 
[  220.948969][ T5779] bcachefs (loop0): bch2_copygc_start(): error creating copygc thread EINTR
[  220.958333][ T5779] bcachefs (loop0): error starting copygc thread
[  220.965104][ T5779] bcachefs (loop0): bch2_fs_start(): error starting filesystem EINTR
[  220.975700][ T5779] bcachefs (loop0): shutting down
[  220.980972][ T5779] bcachefs (loop0): going read-only
[  220.987388][   T10] bcachefs (loop0): bch2_delete_dead_snapshots(): error deleting redundant snapshots erofs_trans_commit
[  220.999074][   T10] bcachefs (loop0): bch2_delete_dead_snapshots(): error erofs_trans_commit
[  221.011616][ T5827] bcachefs (/dev/loop3): error reading default superblock: checksum error, type none: got  should be 
[  221.101820][ T3616] bucket 0:26 gen 0 data type btree sector count overflow: 0 + -256 > U32_MAX
[  221.101944][ T3616] while marking u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq ac62141f8dc7e261 written 24 min_key POS_MIN durability: 1 ptr: 0:26:0 gen 0, continuing
[  221.159827][ T5779] bcachefs (loop0): finished waiting for writes to stop
[  221.160003][ T5779] bcachefs (loop0): flushing journal and stopping allocators, journal seq 12
[  221.219527][ T5827] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): filesystem UUID already open
[  221.219886][ T5827] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): shutdown complete
[  221.230154][ T5826] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): filesystem UUID already open
[  221.267258][ T5826] bcachefs (da441363-bb6a-4ab9-999b-c1f40db4fee2): shutdown complete
[  221.305517][ T5815] =====================================================
[  221.312821][ T5815] BUG: KMSAN: uninit-value in bch2_backpointer_validate+0x63a/0x8f0
[  221.322755][ T5815]  bch2_backpointer_validate+0x63a/0x8f0
[  221.329191][ T5815]  bch2_bkey_val_validate+0x2b5/0x440
[  221.334999][ T5815]  validate_bset_keys+0x1531/0x2080
[  221.340491][ T5815]  validate_bset_for_write+0x142/0x290
[  221.346439][ T5815]  __bch2_btree_node_write+0x53df/0x6830
[pid  5827] mount("/dev/loop3", "./file0", "bcachefs", MS_STRICTATIME, "norecovery,errors=continue,sma*,au}-,euid=" <unfinished ...>
[pid  5829] <... write resumed>)        = 16777216
[  221.352378][ T5815]  bch2_btree_node_write+0xa5/0x2e0
[  221.358158][ T5815]  __btree_node_flush+0x606/0x680
[  221.363444][ T5815]  bch2_btree_node_flush1+0x38/0x60
[  221.369115][ T5815]  journal_flush_pins+0xce6/0x1780
[  221.374703][ T5815]  __bch2_journal_reclaim+0xda8/0x1670
[  221.380421][ T5815]  bch2_journal_reclaim_thread+0x18e/0x760
[  221.386655][ T5815]  kthread+0x3e2/0x540
[  221.390968][ T5815]  ret_from_fork+0x6d/0x90
[  221.398385][ T5815]  ret_from_fork_asm+0x1a/0x30
[  221.405304][ T5815] 
[  221.409603][ T5815] Uninit was stored to memory at:
[  221.417033][ T5815]  bch2_sort_keys_keep_unwritten_whiteouts+0x12d5/0x19d0
[  221.424510][ T5815]  __bch2_btree_node_write+0x3ae8/0x6830
[  221.430435][ T5815]  bch2_btree_node_write+0xa5/0x2e0
[  221.436169][ T5815]  __btree_node_flush+0x606/0x680
[  221.441455][ T5815]  bch2_btree_node_flush1+0x38/0x60
[  221.448147][ T5815]  journal_flush_pins+0xce6/0x1780
[  221.453520][ T5815]  __bch2_journal_reclaim+0xda8/0x1670
[  221.459388][ T5815]  bch2_journal_reclaim_thread+0x18e/0x760
[  221.465630][ T5815]  kthread+0x3e2/0x540
[  221.469946][ T5815]  ret_from_fork+0x6d/0x90
[  221.474923][ T5815]  ret_from_fork_asm+0x1a/0x30
[  221.479980][ T5815] 
[  221.482440][ T5815] Uninit was created at:
[  221.487189][ T5815]  ___kmalloc_large_node+0x22c/0x370
[  221.492750][ T5815]  __kmalloc_large_node_noprof+0x3f/0x1e0
[  221.499038][ T5815]  __kmalloc_node_noprof+0xc96/0x1250
[  221.504888][ T5815]  __kvmalloc_node_noprof+0xc0/0x2d0
[  221.510420][ T5815]  btree_node_sort+0x78a/0x1d30
[  221.515816][ T5815]  bch2_btree_post_write_cleanup+0x1b0/0xf20
[  221.522101][ T5815]  bch2_btree_node_write+0x21c/0x2e0
[  221.527861][ T5815]  __btree_node_flush+0x606/0x680
[  221.533121][ T5815]  bch2_btree_node_flush0+0x35/0x60
[  221.538946][ T5815]  journal_flush_pins+0xce6/0x1780
[  221.544447][ T5815]  __bch2_journal_reclaim+0xda8/0x1670
[  221.550183][ T5815]  bch2_journal_reclaim_thread+0x18e/0x760
[  221.556388][ T5815]  kthread+0x3e2/0x540
[  221.560681][ T5815]  ret_from_fork+0x6d/0x90
[  221.565602][ T5815]  ret_from_fork_asm+0x1a/0x30
[  221.570667][ T5815] 
[  221.573168][ T5815] CPU: 0 UID: 0 PID: 5815 Comm: bch-reclaim/loo Not tainted 6.13.0-rc1-syzkaller-00025-gfeffde684ac2 #0
[  221.584758][ T5815] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
[  221.595856][ T5815] =====================================================
[  221.602961][ T5815] Disabling lock debugging due to kernel taint
[  221.609478][ T5815] Kernel panic - not syncing: kmsan.panic set ...
[  221.616090][ T5815] CPU: 0 UID: 0 PID: 5815 Comm: bch-reclaim/loo Tainted: G    B              6.13.0-rc1-syzkaller-00025-gfeffde684ac2 #0
[  221.628980][ T5815] Tainted: [B]=BAD_PAGE
[  221.633301][ T5815] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
[  221.643556][ T5815] Call Trace:
[  221.647006][ T5815]  <TASK>
[  221.650066][ T5815]  dump_stack_lvl+0x216/0x2d0
[  221.654984][ T5815]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  221.661000][ T5815]  dump_stack+0x1e/0x24
[  221.665395][ T5815]  panic+0x4e2/0xcf0
[  221.669531][ T5815]  ? kmsan_get_metadata+0xd1/0x1c0
[  221.674860][ T5815]  kmsan_report+0x2c7/0x2d0
[  221.679573][ T5815]  ? kmsan_get_metadata+0x13e/0x1c0
[  221.684969][ T5815]  ? __msan_warning+0x95/0x120
[  221.689973][ T5815]  ? bch2_backpointer_validate+0x63a/0x8f0
[  221.696002][ T5815]  ? bch2_bkey_val_validate+0x2b5/0x440
[  221.701791][ T5815]  ? validate_bset_keys+0x1531/0x2080
[  221.707411][ T5815]  ? validate_bset_for_write+0x142/0x290
[  221.713289][ T5815]  ? __bch2_btree_node_write+0x53df/0x6830
[  221.719361][ T5815]  ? bch2_btree_node_write+0xa5/0x2e0
[  221.724994][ T5815]  ? __btree_node_flush+0x606/0x680
[  221.730405][ T5815]  ? bch2_btree_node_flush1+0x38/0x60
[  221.735994][ T5815]  ? journal_flush_pins+0xce6/0x1780
[  221.741478][ T5815]  ? __bch2_journal_reclaim+0xda8/0x1670
[  221.747332][ T5815]  ? bch2_journal_reclaim_thread+0x18e/0x760
[  221.753553][ T5815]  ? kthread+0x3e2/0x540
[  221.758006][ T5815]  ? ret_from_fork+0x6d/0x90
[  221.762827][ T5815]  ? ret_from_fork_asm+0x1a/0x30
[  221.768048][ T5815]  ? is_last_task_frame+0x5d/0x450
[  221.773394][ T5815]  ? kmsan_get_metadata+0x13e/0x1c0
[  221.778792][ T5815]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  221.784812][ T5815]  ? kmsan_get_metadata+0x13e/0x1c0
[  221.790227][ T5815]  ? kmsan_get_metadata+0x13e/0x1c0
[  221.795623][ T5815]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  221.801636][ T5815]  ? kmsan_get_metadata+0x13e/0x1c0
[  221.807060][ T5815]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  221.813072][ T5815]  ? kmsan_get_metadata+0x13e/0x1c0
[  221.818467][ T5815]  ? kmsan_get_metadata+0x13e/0x1c0
[  221.823929][ T5815]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  221.829954][ T5815]  __msan_warning+0x95/0x120
[  221.834783][ T5815]  bch2_backpointer_validate+0x63a/0x8f0
[  221.840650][ T5815]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  221.846661][ T5815]  ? validate_bset_keys+0x8b0/0x2080
[  221.852196][ T5815]  ? kmsan_get_metadata+0x13e/0x1c0
[  221.857601][ T5815]  ? kmsan_get_shadow_origin_ptr+0x4d/0xb0
[  221.863619][ T5815]  ? __pfx_bch2_backpointer_validate+0x10/0x10
[  221.869997][ T5815]  bch2_bkey_val_validate+0x2b5/0x440
[  221.875716][ T5815]  validate_bset_keys+0x1531/0x2080
[  221.881213][ T5815]  validate_bset_for_write+0x142/0x290
[  221.886943][ T5815]  __bch2_btree_node_write+0x53df/0x6830
[  221.892838][ T5815]  ? __kmalloc_noprof+0xcd5/0x1230
[  221.898193][ T5815]  ? kmsan_get_metadata+0x13e/0x1c0
[  221.903594][ T5815]  ? mempool_kmalloc+0x36/0x50
[  221.908539][ T5815]  ? kmsan_internal_set_shadow_origin+0x69/0x100
[  221.915174][ T5815]  bch2_btree_node_write+0xa5/0x2e0
[  221.920618][ T5815]  __btree_node_flush+0x606/0x680
[  221.926026][ T5815]  ? __btree_node_flush+0xd3/0x680
[  221.931357][ T5815]  ? __pfx_bch2_btree_node_flush1+0x10/0x10
[  221.937471][ T5815]  bch2_btree_node_flush1+0x38/0x60
[  221.942893][ T5815]  journal_flush_pins+0xce6/0x1780
[  221.948259][ T5815]  __bch2_journal_reclaim+0xda8/0x1670
[  221.953942][ T5815]  ? kmsan_internal_set_shadow_origin+0x69/0x100
[  221.960658][ T5815]  bch2_journal_reclaim_thread+0x18e/0x760
[  221.966744][ T5815]  kthread+0x3e2/0x540
[  221.971015][ T5815]  ? __pfx_bch2_journal_reclaim_thread+0x10/0x10
[  221.977573][ T5815]  ? __pfx_kthread+0x10/0x10
[  221.982371][ T5815]  ret_from_fork+0x6d/0x90
[  221.986997][ T5815]  ? __pfx_kthread+0x10/0x10
[  221.991780][ T5815]  ret_from_fork_asm+0x1a/0x30
[  221.996806][ T5815]  </TASK>
[  222.000260][ T5815] Kernel Offset: disabled
[  222.004708][ T5815] Rebooting in 86400 seconds..