./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor3990180474 <...> Warning: Permanently added '10.128.0.240' (ED25519) to the list of known hosts. execve("./syz-executor3990180474", ["./syz-executor3990180474"], 0x7ffdfcbc6e00 /* 10 vars */) = 0 brk(NULL) = 0x55557342e000 brk(0x55557342ed00) = 0x55557342ed00 arch_prctl(ARCH_SET_FS, 0x55557342e380) = 0 set_tid_address(0x55557342e650) = 5856 set_robust_list(0x55557342e660, 24) = 0 rseq(0x55557342eca0, 0x20, 0, 0x53053053) = 0 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 readlink("/proc/self/exe", "/root/syz-executor3990180474", 4096) = 28 getrandom("\xf0\x86\x2f\xd1\x79\x97\x90\x90", 8, GRND_NONBLOCK) = 8 brk(NULL) = 0x55557342ed00 brk(0x55557344fd00) = 0x55557344fd00 brk(0x555573450000) = 0x555573450000 mprotect(0x7fe352ba8000, 16384, PROT_READ) = 0 mmap(0x1ffffffff000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x1ffffffff000 mmap(0x200000000000, 16777216, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x200000000000 mmap(0x200001000000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x200001000000 clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5857 attached [pid 5857] set_robust_list(0x55557342e660, 24 [pid 5856] <... clone resumed>, child_tidptr=0x55557342e650) = 5857 [pid 5857] <... set_robust_list resumed>) = 0 [pid 5857] mkdir("./syzkaller.Zmf0Ak", 0700 [pid 5856] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5857] <... mkdir resumed>) = 0 ./strace-static-x86_64: Process 5858 attached [pid 5856] <... clone resumed>, child_tidptr=0x55557342e650) = 5858 [pid 5858] set_robust_list(0x55557342e660, 24 [pid 5857] chmod("./syzkaller.Zmf0Ak", 0777 [pid 5856] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5858] <... set_robust_list resumed>) = 0 [pid 5857] <... chmod resumed>) = 0 [pid 5858] mkdir("./syzkaller.J8wdTi", 0700./strace-static-x86_64: Process 5859 attached [pid 5857] chdir("./syzkaller.Zmf0Ak" [pid 5859] set_robust_list(0x55557342e660, 24 [pid 5858] <... mkdir resumed>) = 0 [pid 5857] <... chdir resumed>) = 0 [pid 5856] <... clone resumed>, child_tidptr=0x55557342e650) = 5859 [pid 5858] chmod("./syzkaller.J8wdTi", 0777 [pid 5859] <... set_robust_list resumed>) = 0 [pid 5856] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5858] <... chmod resumed>) = 0 [pid 5858] chdir("./syzkaller.J8wdTi"./strace-static-x86_64: Process 5860 attached [pid 5859] mkdir("./syzkaller.1xG9KK", 0700 [pid 5857] mkdir("./0", 0777 [pid 5856] <... clone resumed>, child_tidptr=0x55557342e650) = 5860 [pid 5860] set_robust_list(0x55557342e660, 24 [pid 5858] <... chdir resumed>) = 0 [pid 5859] <... mkdir resumed>) = 0 [pid 5858] mkdir("./0", 0777 [pid 5856] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5860] <... set_robust_list resumed>) = 0 [pid 5858] <... mkdir resumed>) = 0 [pid 5859] chmod("./syzkaller.1xG9KK", 0777 [pid 5857] <... mkdir resumed>) = 0 ./strace-static-x86_64: Process 5861 attached [pid 5860] mkdir("./syzkaller.oR5iqB", 0700 [pid 5858] openat(AT_FDCWD, "/dev/loop1", O_RDWR [pid 5856] <... clone resumed>, child_tidptr=0x55557342e650) = 5861 [pid 5861] set_robust_list(0x55557342e660, 24 [pid 5859] <... chmod resumed>) = 0 [pid 5857] openat(AT_FDCWD, "/dev/loop0", O_RDWR [pid 5861] <... set_robust_list resumed>) = 0 [pid 5860] <... mkdir resumed>) = 0 [pid 5859] chdir("./syzkaller.1xG9KK" [pid 5858] <... openat resumed>) = 3 [pid 5857] <... openat resumed>) = 3 [pid 5857] ioctl(3, LOOP_CLR_FD [pid 5861] mkdir("./syzkaller.8MAv7A", 0700 [pid 5860] chmod("./syzkaller.oR5iqB", 0777 [pid 5859] <... chdir resumed>) = 0 [pid 5858] ioctl(3, LOOP_CLR_FD [pid 5860] <... chmod resumed>) = 0 [pid 5859] mkdir("./0", 0777 [pid 5858] <... ioctl resumed>) = -1 ENXIO (No such device or address) [pid 5857] <... ioctl resumed>) = -1 ENXIO (No such device or address) [pid 5861] <... mkdir resumed>) = 0 [pid 5860] chdir("./syzkaller.oR5iqB" [pid 5861] chmod("./syzkaller.8MAv7A", 0777) = 0 [pid 5860] <... chdir resumed>) = 0 [pid 5858] close(3 [pid 5857] close(3 [pid 5858] <... close resumed>) = 0 [pid 5861] chdir("./syzkaller.8MAv7A" [pid 5860] mkdir("./0", 0777 [pid 5861] <... chdir resumed>) = 0 [pid 5859] <... mkdir resumed>) = 0 [pid 5858] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5857] <... close resumed>) = 0 [pid 5861] mkdir("./0", 0777 [pid 5857] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5860] <... mkdir resumed>) = 0 ./strace-static-x86_64: Process 5862 attached ./strace-static-x86_64: Process 5863 attached [pid 5862] set_robust_list(0x55557342e660, 24 [pid 5861] <... mkdir resumed>) = 0 [pid 5860] openat(AT_FDCWD, "/dev/loop3", O_RDWR [pid 5859] openat(AT_FDCWD, "/dev/loop2", O_RDWR [pid 5861] openat(AT_FDCWD, "/dev/loop4", O_RDWR [pid 5863] set_robust_list(0x55557342e660, 24 [pid 5862] <... set_robust_list resumed>) = 0 [pid 5860] <... openat resumed>) = 3 [pid 5859] <... openat resumed>) = 3 [pid 5858] <... clone resumed>, child_tidptr=0x55557342e650) = 5863 [pid 5857] <... clone resumed>, child_tidptr=0x55557342e650) = 5862 [pid 5863] <... set_robust_list resumed>) = 0 [pid 5862] chdir("./0" [pid 5861] <... openat resumed>) = 3 [pid 5863] chdir("./0" [pid 5862] <... chdir resumed>) = 0 [pid 5860] ioctl(3, LOOP_CLR_FD [pid 5859] ioctl(3, LOOP_CLR_FD [pid 5863] <... chdir resumed>) = 0 [pid 5862] prctl(PR_SET_PDEATHSIG, SIGKILL [pid 5860] <... ioctl resumed>) = -1 ENXIO (No such device or address) [pid 5859] <... ioctl resumed>) = -1 ENXIO (No such device or address) [pid 5863] prctl(PR_SET_PDEATHSIG, SIGKILL [pid 5862] <... prctl resumed>) = 0 [pid 5862] setpgid(0, 0 [pid 5860] close(3 [pid 5863] <... prctl resumed>) = 0 [pid 5862] <... setpgid resumed>) = 0 [pid 5860] <... close resumed>) = 0 [pid 5859] close(3 [pid 5863] setpgid(0, 0 [pid 5862] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC [pid 5863] <... setpgid resumed>) = 0 [pid 5860] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5859] <... close resumed>) = 0 ./strace-static-x86_64: Process 5864 attached [pid 5863] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC [pid 5862] <... openat resumed>) = 3 [pid 5861] ioctl(3, LOOP_CLR_FD [pid 5859] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5863] <... openat resumed>) = 3 [pid 5861] <... ioctl resumed>) = -1 ENXIO (No such device or address) [pid 5864] set_robust_list(0x55557342e660, 24 [pid 5863] write(3, "1000", 4 [pid 5862] write(3, "1000", 4 [pid 5861] close(3 [pid 5863] <... write resumed>) = 4 [pid 5862] <... write resumed>) = 4 [pid 5863] close(3 [pid 5862] close(3 [pid 5863] <... close resumed>) = 0 [pid 5862] <... close resumed>) = 0 [pid 5862] symlink("/dev/binderfs", "./binderfs" [pid 5863] symlink("/dev/binderfs", "./binderfs" [pid 5862] <... symlink resumed>) = 0 [pid 5863] <... symlink resumed>) = 0 [pid 5860] <... clone resumed>, child_tidptr=0x55557342e650) = 5864 executing program [pid 5863] write(1, "executing program\n", 18 [pid 5859] <... clone resumed>, child_tidptr=0x55557342e650) = 5865 [pid 5863] <... write resumed>) = 18 [pid 5861] <... close resumed>) = 0 executing program ./strace-static-x86_64: Process 5865 attached [pid 5864] <... set_robust_list resumed>) = 0 [pid 5863] memfd_create("syzkaller", 0 [pid 5861] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5862] write(1, "executing program\n", 18) = 18 [pid 5862] memfd_create("syzkaller", 0 [pid 5863] <... memfd_create resumed>) = 3 ./strace-static-x86_64: Process 5866 attached [pid 5865] set_robust_list(0x55557342e660, 24 [pid 5864] chdir("./0" [pid 5862] <... memfd_create resumed>) = 3 [pid 5865] <... set_robust_list resumed>) = 0 [pid 5864] <... chdir resumed>) = 0 [pid 5861] <... clone resumed>, child_tidptr=0x55557342e650) = 5866 [pid 5865] chdir("./0" [pid 5864] prctl(PR_SET_PDEATHSIG, SIGKILL [pid 5865] <... chdir resumed>) = 0 [pid 5864] <... prctl resumed>) = 0 [pid 5865] prctl(PR_SET_PDEATHSIG, SIGKILL [pid 5864] setpgid(0, 0 [pid 5865] <... prctl resumed>) = 0 [pid 5864] <... setpgid resumed>) = 0 [pid 5865] setpgid(0, 0 [pid 5864] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC [pid 5865] <... setpgid resumed>) = 0 [pid 5864] <... openat resumed>) = 3 [pid 5865] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC [pid 5864] write(3, "1000", 4) = 4 [pid 5864] close(3) = 0 [pid 5866] set_robust_list(0x55557342e660, 24 [pid 5865] <... openat resumed>) = 3 [pid 5863] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 [pid 5862] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 [pid 5866] <... set_robust_list resumed>) = 0 [pid 5862] <... mmap resumed>) = 0x7fe34a600000 [pid 5863] <... mmap resumed>) = 0x7fe34a600000 [pid 5866] chdir("./0"executing program [pid 5865] write(3, "1000", 4 [pid 5864] symlink("/dev/binderfs", "./binderfs" [pid 5865] <... write resumed>) = 4 [pid 5864] <... symlink resumed>) = 0 [pid 5865] close(3) = 0 [pid 5865] symlink("/dev/binderfs", "./binderfs" [pid 5864] write(1, "executing program\n", 18 [pid 5865] <... symlink resumed>) = 0 [pid 5864] <... write resumed>) = 18 [pid 5864] memfd_create("syzkaller", 0) = 3 executing program [pid 5865] write(1, "executing program\n", 18 [pid 5864] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 [pid 5865] <... write resumed>) = 18 [pid 5864] <... mmap resumed>) = 0x7fe34a600000 executing program [pid 5866] <... chdir resumed>) = 0 [pid 5865] memfd_create("syzkaller", 0 [pid 5866] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5866] setpgid(0, 0) = 0 [pid 5866] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5866] write(3, "1000", 4) = 4 [pid 5866] close(3) = 0 [pid 5866] symlink("/dev/binderfs", "./binderfs") = 0 [pid 5866] write(1, "executing program\n", 18) = 18 [pid 5866] memfd_create("syzkaller", 0) = 3 [pid 5866] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe34a600000 [pid 5865] <... memfd_create resumed>) = 3 [pid 5865] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fe34a600000 [pid 5864] 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 [pid 5865] 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 [pid 5863] 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 [pid 5862] 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 [pid 5866] 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 [pid 5864] <... write resumed>) = 16777216 [pid 5865] <... write resumed>) = 16777216 [pid 5864] munmap(0x7fe34a600000, 138412032 [pid 5866] <... write resumed>) = 16777216 [pid 5865] munmap(0x7fe34a600000, 138412032 [pid 5862] <... write resumed>) = 16777216 [pid 5866] munmap(0x7fe34a600000, 138412032 [pid 5865] <... munmap resumed>) = 0 [pid 5864] <... munmap resumed>) = 0 [pid 5862] munmap(0x7fe34a600000, 138412032 [pid 5864] openat(AT_FDCWD, "/dev/loop3", O_RDWR [pid 5863] <... write resumed>) = 16777216 [pid 5866] <... munmap resumed>) = 0 [pid 5864] <... openat resumed>) = 4 [pid 5863] munmap(0x7fe34a600000, 138412032 [pid 5866] openat(AT_FDCWD, "/dev/loop4", O_RDWR [pid 5864] ioctl(4, LOOP_SET_FD, 3 [pid 5866] <... openat resumed>) = 4 [pid 5866] ioctl(4, LOOP_SET_FD, 3 [pid 5865] openat(AT_FDCWD, "/dev/loop2", O_RDWR [pid 5862] <... munmap resumed>) = 0 [pid 5866] <... ioctl resumed>) = 0 [pid 5865] <... openat resumed>) = 4 [pid 5862] openat(AT_FDCWD, "/dev/loop0", O_RDWR [pid 5865] ioctl(4, LOOP_SET_FD, 3 [pid 5866] close(3 [pid 5864] <... ioctl resumed>) = 0 [pid 5863] <... munmap resumed>) = 0 [pid 5862] <... openat resumed>) = 4 [pid 5866] <... close resumed>) = 0 [pid 5864] close(3 [pid 5862] ioctl(4, LOOP_SET_FD, 3 [pid 5866] close(4 [pid 5864] <... close resumed>) = 0 [pid 5862] <... ioctl resumed>) = 0 [pid 5866] <... close resumed>) = 0 [pid 5864] close(4 [pid 5863] openat(AT_FDCWD, "/dev/loop1", O_RDWR [pid 5864] <... close resumed>) = 0 [pid 5863] <... openat resumed>) = 4 [pid 5864] mkdir("./file0", 0777) = 0 [pid 5863] ioctl(4, LOOP_SET_FD, 3 [pid 5866] mkdir("./file0", 0777) = 0 [pid 5866] mount("/dev/loop4", "./file0", "bcachefs", 0, "\x05\x4a\x6c\xc3\x21\x83\xaa\x1b\xad\x40\x58\xbd\xcf\xc2\x0b\x37\x0e\xe3\x33\x31\x9a\x17\x95\xd8\xf0\x83\x81\x7d\xa0\xba\xf4\xca\x40\xa2\x88\x52\x5b\x06\x45\xfa\xfd\x40\x33\x03\xbe\xd0\x2b\x3d\x34\x68\x32\xdc\xe3\x2f\x06\x13\x63\xec\xd2\x5e\xd5\x93" [pid 5862] close(3 [pid 5865] <... ioctl resumed>) = 0 [pid 5862] <... close resumed>) = 0 [pid 5862] close(4) = 0 [pid 5862] mkdir("./file0", 0777 [pid 5865] close(3 [pid 5862] <... mkdir resumed>) = 0 [pid 5865] <... close resumed>) = 0 [pid 5864] mount("/dev/loop3", "./file0", "bcachefs", 0, "\x05\x4a\x6c\xc3\x21\x83\xaa\x1b\xad\x40\x58\xbd\xcf\xc2\x0b\x37\x0e\xe3\x33\x31\x9a\x17\x95\xd8\xf0\x83\x81\x7d\xa0\xba\xf4\xca\x40\xa2\x88\x52\x5b\x06\x45\xfa\xfd\x40\x33\x03\xbe\xd0\x2b\x3d\x34\x68\x32\xdc\xe3\x2f\x06\x13\x63\xec\xd2\x5e\xd5\x93" [pid 5865] close(4 [pid 5862] mount("/dev/loop0", "./file0", "bcachefs", 0, "\x05\x4a\x6c\xc3\x21\x83\xaa\x1b\xad\x40\x58\xbd\xcf\xc2\x0b\x37\x0e\xe3\x33\x31\x9a\x17\x95\xd8\xf0\x83\x81\x7d\xa0\xba\xf4\xca\x40\xa2\x88\x52\x5b\x06\x45\xfa\xfd\x40\x33\x03\xbe\xd0\x2b\x3d\x34\x68\x32\xdc\xe3\x2f\x06\x13\x63\xec\xd2\x5e\xd5\x93" [pid 5865] <... close resumed>) = 0 [pid 5865] mkdir("./file0", 0777 [pid 5863] <... ioctl resumed>) = 0 [pid 5865] <... mkdir resumed>) = 0 [pid 5865] mount("/dev/loop2", "./file0", "bcachefs", 0, "\x05\x4a\x6c\xc3\x21\x83\xaa\x1b\xad\x40\x58\xbd\xcf\xc2\x0b\x37\x0e\xe3\x33\x31\x9a\x17\x95\xd8\xf0\x83\x81\x7d\xa0\xba\xf4\xca\x40\xa2\x88\x52\x5b\x06\x45\xfa\xfd\x40\x33\x03\xbe\xd0\x2b\x3d\x34\x68\x32\xdc\xe3\x2f\x06\x13\x63\xec\xd2\x5e\xd5\x93" [pid 5863] close(3) = 0 [pid 5863] close(4) = 0 [ 126.632650][ T5864] loop3: detected capacity change from 0 to 32768 [ 126.641410][ T5866] loop4: detected capacity change from 0 to 32768 [ 126.651677][ T5865] loop2: detected capacity change from 0 to 32768 [ 126.658098][ T5862] loop0: detected capacity change from 0 to 32768 [ 126.674888][ T5863] loop1: detected capacity change from 0 to 32768 [pid 5863] mkdir("./file0", 0777) = 0 [ 127.034131][ T5864] bcachefs (loop3): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,nojournal_transaction_names [ 127.034152][ T5864] allowing incompatible features above 0.0: (unknown version) [ 127.034164][ T5864] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes [ 127.077579][ T5864] bcachefs (loop3): Using encoding defined by superblock: utf8-12.1.0 [ 127.086086][ T5864] bcachefs (loop3): recovering from clean shutdown, journal seq 10 [ 127.094669][ T5864] bcachefs (loop3): Version upgrade from 1.19: autofix_errors to 1.7: mi_btree_bitmap incomplete [ 127.094669][ T5864] Doing compatible version upgrade from 1.19: autofix_errors to 1.28: inode_has_case_insensitive [ 127.094669][ T5864] running recovery passes: check_extents_to_backpointers,check_inodes [ 127.112848][ T5863] bcachefs (loop1): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,nojournal_transaction_names [ 127.163509][ T5864] bcachefs (loop3): accounting_read... [ 127.164249][ T5863] allowing incompatible features above 0.0: (unknown version) [ 127.173211][ T5865] bcachefs (loop2): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,nojournal_transaction_names [ 127.181203][ T5864] done [ 127.195522][ T5863] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes [ 127.219221][ T5865] allowing incompatible features above 0.0: (unknown version) [ 127.222232][ T5864] bcachefs (loop3): alloc_read... done [ 127.235743][ T5864] bcachefs (loop3): snapshots_read... [ 127.236596][ T5866] bcachefs (loop4): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,nojournal_transaction_names [ 127.236616][ T5866] allowing incompatible features above 0.0: (unknown version) [ 127.236627][ T5866] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes [ 127.246821][ T5865] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes [ 127.246850][ T5865] bcachefs (loop2): Using encoding defined by superblock: utf8-12.1.0 [ 127.246955][ T5865] bcachefs (loop2): recovering from clean shutdown, journal seq 10 [ 127.247092][ T5865] bcachefs (loop2): Version upgrade from 1.19: autofix_errors to 1.7: mi_btree_bitmap incomplete [ 127.247092][ T5865] Doing compatible version upgrade from 1.19: autofix_errors to 1.28: inode_has_case_insensitive [ 127.247092][ T5865] running recovery passes: check_extents_to_backpointers,check_inodes [ 127.247426][ T5864] done [ 127.251924][ T5864] bcachefs (loop3): going read-write [ 127.253513][ T5863] bcachefs (loop1): Using encoding defined by superblock: utf8-12.1.0 [ 127.253615][ T5863] bcachefs (loop1): recovering from clean shutdown, journal seq 10 [ 127.253745][ T5863] bcachefs (loop1): Version upgrade from 1.19: autofix_errors to 1.7: mi_btree_bitmap incomplete [ 127.253745][ T5863] Doing compatible version upgrade from 1.19: autofix_errors to 1.28: inode_has_case_insensitive [ 127.253745][ T5863] running recovery passes: check_extents_to_backpointers,check_inodes [ 127.266894][ T5862] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,nojournal_transaction_names [ 127.285305][ T5865] bcachefs (loop2): accounting_read... [ 127.293109][ T5862] allowing incompatible features above 0.0: (unknown version) [ 127.306075][ T5866] bcachefs (loop4): Using encoding defined by superblock: utf8-12.1.0 [ 127.315998][ T5862] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes [ 127.321983][ T5866] bcachefs (loop4): recovering from clean shutdown, journal seq 10 [ 127.354614][ T5862] bcachefs (loop0): Using encoding defined by superblock: utf8-12.1.0 [ 127.404470][ T5866] bcachefs (loop4): Version upgrade from 1.19: autofix_errors to 1.7: mi_btree_bitmap incomplete [ 127.404470][ T5866] Doing compatible version upgrade from 1.19: autofix_errors to 1.28: inode_has_case_insensitive [ 127.404470][ T5866] running recovery passes: check_extents_to_backpointers,check_inodes [ 127.427892][ T5866] bcachefs (loop4): accounting_read... [ 127.442370][ T5862] bcachefs (loop0): recovering from clean shutdown, journal seq 10 [ 127.459041][ T5865] done [ 127.481543][ T5863] bcachefs (loop1): accounting_read... [ 127.503109][ T5866] done [ 127.515142][ T5864] bcachefs (loop3): journal_replay... [ 127.517375][ T5866] bcachefs (loop4): alloc_read... [ 127.523057][ T5862] bcachefs (loop0): Version upgrade from 1.19: autofix_errors to 1.7: mi_btree_bitmap incomplete [ 127.523057][ T5862] Doing compatible version upgrade from 1.19: autofix_errors to 1.28: inode_has_case_insensitive [ 127.523057][ T5862] running recovery passes: check_extents_to_backpointers,check_inodes [ 127.526686][ T5866] done [ 127.532544][ T5865] bcachefs (loop2): alloc_read... [ 127.536254][ T5866] bcachefs (loop4): snapshots_read... [ 127.573070][ T5864] done [ 127.575040][ T5866] done [ 127.581732][ T5864] bcachefs (loop3): check_extents_to_backpointers... [ 127.587492][ T5864] bcachefs (loop3): scanning for missing backpointers in 7/128 buckets [ 127.589038][ T5866] bcachefs (loop4): going read-write [ 127.599347][ T5863] done [ 127.607231][ T5866] bcachefs (loop4): journal_replay... [ 127.608079][ T5864] done [ 127.611710][ T5866] done [ 127.616696][ T5863] bcachefs (loop1): alloc_read... [ 127.619030][ T5866] bcachefs (loop4): check_extents_to_backpointers... [ 127.628610][ T5865] done [ 127.632277][ T5866] bcachefs (loop4): scanning for missing backpointers in 7/128 buckets [ 127.636267][ T5865] bcachefs (loop2): snapshots_read... [ 127.640630][ T5866] done [ 127.648141][ T5863] done [ 127.653932][ T5865] done [ 127.656250][ T5863] bcachefs (loop1): snapshots_read... [ 127.658833][ T5866] bcachefs (loop4): check_inodes... [ 127.661988][ T5864] bcachefs (loop3): check_inodes... [ 127.672896][ T5865] bcachefs (loop2): going read-write [ 127.674823][ T5866] done [ 127.680669][ T5862] bcachefs (loop0): accounting_read... [ 127.687568][ T5863] done [ 127.690595][ T5866] bcachefs (loop4): resume_logged_ops... [ 127.694580][ T5865] bcachefs (loop2): journal_replay... [ 127.703478][ T5862] done [ 127.703483][ T5864] done [ 127.704815][ T5865] done [ 127.709167][ T5862] bcachefs (loop0): alloc_read... [ 127.716306][ T5863] bcachefs (loop1): going read-write [ 127.717702][ T5866] done [ 127.723706][ T5864] bcachefs (loop3): resume_logged_ops... [ 127.730808][ T5865] bcachefs (loop2): check_extents_to_backpointers... [ 127.737819][ T5862] done [ 127.743281][ T5865] bcachefs (loop2): scanning for missing backpointers in 7/128 buckets [ 127.745034][ T5866] bcachefs (loop4): delete_dead_inodes... [ 127.749118][ T5864] done [ 127.756077][ T5862] bcachefs (loop0): snapshots_read... [ 127.766006][ T5863] bcachefs (loop1): journal_replay... [ 127.766097][ T5865] done [ 127.773117][ T5866] done [ 127.777945][ T5864] bcachefs (loop3): delete_dead_inodes... [ 127.780275][ T5863] done [ 127.785675][ T5864] done [ 127.792232][ T5866] bcachefs (loop4): done starting filesystem [ 127.797233][ T5865] bcachefs (loop2): check_inodes... [ 127.799990][ T5862] done [ 127.802754][ T5865] done [ 127.808748][ T5863] bcachefs (loop1): check_extents_to_backpointers... [pid 5863] mount("/dev/loop1", "./file0", "bcachefs", 0, "\x05\x4a\x6c\xc3\x21\x83\xaa\x1b\xad\x40\x58\xbd\xcf\xc2\x0b\x37\x0e\xe3\x33\x31\x9a\x17\x95\xd8\xf0\x83\x81\x7d\xa0\xba\xf4\xca\x40\xa2\x88\x52\x5b\x06\x45\xfa\xfd\x40\x33\x03\xbe\xd0\x2b\x3d\x34\x68\x32\xdc\xe3\x2f\x06\x13\x63\xec\xd2\x5e\xd5\x93" [pid 5866] <... mount resumed>) = 0 [pid 5864] <... mount resumed>) = 0 [pid 5866] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY) = 3 [pid 5866] chdir("./file0") = 0 [pid 5866] openat(AT_FDCWD, "/dev/loop4", O_RDWR) = 4 [pid 5866] ioctl(4, LOOP_CLR_FD) = 0 [pid 5866] close(4) = 0 [pid 5864] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY [pid 5866] exit_group(0) = ? [pid 5864] <... openat resumed>) = 3 [pid 5866] +++ exited with 0 +++ [pid 5864] chdir("./file0") = 0 [pid 5861] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5866, si_uid=0, si_status=0, si_utime=2 /* 0.02 s */, si_stime=55 /* 0.55 s */} --- [pid 5864] openat(AT_FDCWD, "/dev/loop3", O_RDWR [pid 5861] restart_syscall(<... resuming interrupted clone ...> [pid 5864] <... openat resumed>) = 4 [pid 5861] <... restart_syscall resumed>) = 0 [pid 5864] ioctl(4, LOOP_CLR_FD) = 0 [ 127.813222][ T5864] bcachefs (loop3): done starting filesystem [ 127.822934][ T5863] bcachefs (loop1): scanning for missing backpointers in 7/128 buckets [ 127.830343][ T5865] bcachefs (loop2): resume_logged_ops... done [ 127.842743][ T5865] bcachefs (loop2): delete_dead_inodes... [ 127.843835][ T5862] bcachefs (loop0): going read-write [ 127.844115][ T5865] done [ 127.863446][ T5863] done [pid 5864] close(4) = 0 [pid 5864] exit_group(0) = ? [pid 5864] +++ exited with 0 +++ [pid 5861] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5860] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5864, si_uid=0, si_status=0, si_utime=1 /* 0.01 s */, si_stime=43 /* 0.43 s */} --- [pid 5861] <... umount2 resumed>) = -1 EINVAL (Invalid argument) [pid 5860] restart_syscall(<... resuming interrupted clone ...> [pid 5861] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [ 127.873140][ T5865] bcachefs (loop2): done starting filesystem [pid 5861] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5860] <... restart_syscall resumed>) = 0 [pid 5861] getdents64(3, 0x55557342f6f0 /* 4 entries */, 32768) = 112 [pid 5865] <... mount resumed>) = 0 [pid 5861] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5860] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5865] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY [pid 5860] <... umount2 resumed>) = -1 EINVAL (Invalid argument) [pid 5865] <... openat resumed>) = 3 [pid 5860] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY [pid 5865] chdir("./file0" [pid 5860] <... openat resumed>) = 3 [pid 5865] <... chdir resumed>) = 0 [pid 5860] newfstatat(3, "", [pid 5865] openat(AT_FDCWD, "/dev/loop2", O_RDWR [pid 5860] <... newfstatat resumed>{st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5865] <... openat resumed>) = 4 [pid 5860] getdents64(3, [pid 5865] ioctl(4, LOOP_CLR_FD) = 0 [pid 5860] <... getdents64 resumed>0x55557342f6f0 /* 4 entries */, 32768) = 112 [ 127.892464][ T5862] bcachefs (loop0): journal_replay... done [ 127.897892][ T5863] bcachefs (loop1): check_inodes... [ 127.904145][ T5862] bcachefs (loop0): check_extents_to_backpointers... [ 127.906027][ T5863] done [ 127.924320][ T5861] bcachefs (loop4): shutting down [pid 5865] close(4 [pid 5860] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5865] <... close resumed>) = 0 [pid 5865] exit_group(0) = ? [pid 5865] +++ exited with 0 +++ [ 127.929481][ T5861] bcachefs (loop4): going read-only [ 127.939572][ T5863] bcachefs (loop1): resume_logged_ops... [ 127.944973][ T5861] bcachefs (loop4): finished waiting for writes to stop [ 127.948301][ T5863] done [ 127.954070][ T5862] bcachefs (loop0): scanning for missing backpointers in 7/128 buckets [ 127.969891][ T5863] bcachefs (loop1): delete_dead_inodes... done [ 127.978930][ T5863] bcachefs (loop1): done starting filesystem [ 127.985635][ T5860] bcachefs (loop3): shutting down [pid 5859] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5865, si_uid=0, si_status=0, si_utime=3 /* 0.03 s */, si_stime=46 /* 0.46 s */} --- [pid 5859] restart_syscall(<... resuming interrupted clone ...>) = 0 [pid 5859] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5859] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY [pid 5863] <... mount resumed>) = 0 [pid 5859] <... openat resumed>) = 3 [pid 5863] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY) = 3 [pid 5859] newfstatat(3, "", [pid 5863] chdir("./file0" [pid 5859] <... newfstatat resumed>{st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5863] <... chdir resumed>) = 0 [pid 5859] getdents64(3, [pid 5863] openat(AT_FDCWD, "/dev/loop1", O_RDWR [pid 5859] <... getdents64 resumed>0x55557342f6f0 /* 4 entries */, 32768) = 112 [pid 5863] <... openat resumed>) = 4 [pid 5859] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5863] ioctl(4, LOOP_CLR_FD) = 0 [pid 5863] close(4) = 0 [ 127.997151][ T5860] bcachefs (loop3): going read-only [ 128.002968][ T5862] done [ 128.007363][ T5860] bcachefs (loop3): finished waiting for writes to stop [ 128.008849][ T5862] bcachefs (loop0): check_inodes... [ 128.032609][ T5859] bcachefs (loop2): shutting down [pid 5863] exit_group(0) = ? [pid 5863] +++ exited with 0 +++ [pid 5858] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5863, si_uid=0, si_status=0, si_utime=5 /* 0.05 s */, si_stime=47 /* 0.47 s */} --- [pid 5858] restart_syscall(<... resuming interrupted clone ...>) = 0 [pid 5858] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5858] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5858] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5858] getdents64(3, 0x55557342f6f0 /* 4 entries */, 32768) = 112 [ 128.046318][ T5862] done [ 128.063702][ T5862] bcachefs (loop0): resume_logged_ops... done [ 128.071471][ T5859] bcachefs (loop2): going read-only [ 128.077068][ T5862] bcachefs (loop0): delete_dead_inodes... [ 128.093526][ T5858] bcachefs (loop1): shutting down [ 128.105419][ T5862] done [pid 5858] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5862] <... mount resumed>) = 0 [pid 5862] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY) = 3 [pid 5862] chdir("./file0") = 0 [pid 5862] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [pid 5862] ioctl(4, LOOP_CLR_FD) = 0 [pid 5862] close(4) = 0 [ 128.115826][ T5859] bcachefs (loop2): finished waiting for writes to stop [ 128.123711][ T5858] bcachefs (loop1): going read-only [ 128.133216][ T5858] bcachefs (loop1): finished waiting for writes to stop [ 128.133549][ T5862] bcachefs (loop0): done starting filesystem [pid 5862] exit_group(0) = ? [pid 5862] +++ exited with 0 +++ [pid 5857] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5862, si_uid=0, si_status=0, si_utime=5 /* 0.05 s */, si_stime=54 /* 0.54 s */} --- [pid 5857] restart_syscall(<... resuming interrupted clone ...>) = 0 [pid 5857] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5857] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5857] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5857] getdents64(3, 0x55557342f6f0 /* 4 entries */, 32768) = 112 [ 128.262274][ T5857] bcachefs (loop0): shutting down [ 128.267329][ T5857] bcachefs (loop0): going read-only [ 128.274250][ T5857] bcachefs (loop0): finished waiting for writes to stop [ 158.340692][ T5916] Allocator stuck? Waited for 30 seconds [ 158.340692][ T5907] Allocator stuck? Waited for 30 seconds [ 158.340729][ T5916] Allocator debug: [ 158.340736][ T5907] Allocator debug: [ 158.340738][ T5916] capacity1536 [ 158.340746][ T5907] capacity1536 [ 158.340748][ T5916] reserved 31232 [ 158.340756][ T5907] reserved 31232 [ 158.340758][ T5916] hidden 0 [ 158.340766][ T5907] hidden 0 [ 158.340768][ T5916] btree 0 [ 158.340775][ T5907] btree 0 [ 158.340778][ T5916] data 0 [ 158.340785][ T5907] data 0 [ 158.340787][ T5916] cached 0 [ 158.340795][ T5907] cached 0 [ 158.340797][ T5916] reserved 0 [ 158.340805][ T5907] reserved 0 [ 158.340806][ T5916] online_reserved 256 [ 158.340814][ T5907] online_reserved 256 [ 158.340816][ T5916] nr_inodes 0 [ 158.340824][ T5907] nr_inodes 0 [ 158.340825][ T5916] [ 158.340834][ T5907] [ 158.340835][ T5916] freelist_wait waiting [ 158.340843][ T5907] freelist_wait waiting [ 158.340845][ T5916] open buckets allocated1 [ 158.340853][ T5907] open buckets allocated1 [ 158.340866][ T5916] open buckets total 1024 [ 158.340874][ T5907] open buckets total 1024 [ 158.340875][ T5916] open_buckets_wait empty [ 158.340883][ T5907] open_buckets_wait empty [ 158.340884][ T5916] open_buckets_btree 0 [ 158.340892][ T5907] open_buckets_btree 0 [ 158.340894][ T5916] open_buckets_user 0 [ 158.340901][ T5907] open_buckets_user 0 [ 158.340903][ T5916] btree reserve cache 0 [ 158.340910][ T5907] btree reserve cache 0 [ 158.340912][ T5916] [ 158.340919][ T5907] [ 158.340921][ T5916] Dev 0: [ 158.340927][ T5907] Dev 0: [ 158.340929][ T5916] buckets sectors fragmented [ 158.340935][ T5907] buckets sectors fragmented [ 158.340940][ T5916] free 0 0 0 [ 158.340946][ T5907] free 0 0 0 [ 158.340951][ T5916] sb 0 0 0 [ 158.340957][ T5907] sb 0 0 0 [ 158.340962][ T5916] journal 0 0 0 [ 158.340968][ T5907] journal 0 0 0 [ 158.340973][ T5916] btree 0 0 0 [ 158.340979][ T5907] btree 0 0 0 [ 158.340984][ T5916] user 0 0 0 [ 158.340990][ T5907] user 0 0 0 [ 158.340994][ T5916] cached 0 0 0 [ 158.341001][ T5907] cached 0 0 0 [ 158.341005][ T5916] parity 0 0 0 [ 158.341012][ T5907] parity 0 0 0 [ 158.341016][ T5916] stripe 0 0 0 [ 158.341023][ T5907] stripe 0 0 0 [ 158.341027][ T5916] need_gc_gens 0 0 0 [ 158.341033][ T5907] need_gc_gens 0 0 0 [ 158.341038][ T5916] need_discard 0 0 0 [ 158.341044][ T5907] need_discard 0 0 0 [ 158.341048][ T5916] unstriped 0 0 0 [ 158.341055][ T5907] unstriped 0 0 0 [ 158.341059][ T5916] capacity 128 [ 158.341066][ T5907] capacity 128 [ 158.341068][ T5916] [ 158.341075][ T5907] [ 158.341076][ T5916] reserves: [ 158.341083][ T5907] reserves: [ 158.341085][ T5916] stripe 60 [ 158.341092][ T5907] stripe 60 [ 158.341094][ T5916] normal 58 [ 158.341101][ T5907] normal 58 [ 158.341103][ T5916] copygc 56 [ 158.341110][ T5907] copygc 56 [ 158.341112][ T5916] btree 28 [ 158.341120][ T5907] btree 28 [ 158.341121][ T5916] btree_copygc 0 [ 158.341129][ T5907] btree_copygc 0 [ 158.341130][ T5916] reclaim 0 [ 158.341138][ T5907] reclaim 0 [ 158.341139][ T5916] interior_updates 0 [ 158.341147][ T5907] interior_updates 0 [ 158.341148][ T5916] [ 158.341157][ T5916] open buckets 0 [ 158.341158][ T5907] [ 158.341165][ T5907] open buckets 0 [ 158.341166][ T5916] buckets to invalidate 0 [ 158.341174][ T5907] buckets to invalidate 0 [ 158.341175][ T5916] [ 158.341183][ T5916] Copygc debug: [ 158.341184][ T5907] [ 158.341191][ T5916] running: 1 [ 158.341193][ T5907] Copygc debug: [ 158.341200][ T5916] copygc_wait:0 [ 158.341202][ T5907] running: 1 [ 158.341208][ T5916] copygc_wait_at:0 [ 158.341210][ T5907] copygc_wait:0 [ 158.341217][ T5916] Currently waiting for:0 B [ 158.341219][ T5907] copygc_wait_at:0 [ 158.341226][ T5916] Currently waiting since:640 KiB [ 158.341227][ T5907] Currently waiting for:0 B [ 158.341236][ T5916] Currently calculated wait: [ 158.341238][ T5907] Currently waiting since:640 KiB [ 158.341245][ T5916] loop1: 0 B [ 158.341247][ T5907] Currently calculated wait: [ 158.341253][ T5916] [<0>] bch2_copygc_wait_to_text+0x831/0xa40 [ 158.341256][ T5907] loop3: 0 B [ 158.341263][ T5916] [<0>] bch2_print_allocator_stuck+0x55c/0x640 [ 158.341265][ T5907] [<0>] bch2_copygc_wait_to_text+0x831/0xa40 [ 158.341274][ T5916] [<0>] __bch2_wait_on_allocator+0x17f/0x240 [ 158.341275][ T5907] [<0>] bch2_print_allocator_stuck+0x55c/0x640 [ 158.341284][ T5916] [<0>] bch2_btree_update_start+0x18dc/0x1de0 [ 158.341285][ T5907] [<0>] __bch2_wait_on_allocator+0x17f/0x240 [ 158.341294][ T5916] [<0>] bch2_btree_node_rewrite+0x17e/0x1120 [ 158.341295][ T5907] [<0>] bch2_btree_update_start+0x18dc/0x1de0 [ 158.341304][ T5916] [<0>] bch2_btree_node_rewrite_pos+0x187/0x240 [ 158.341305][ T5907] [<0>] bch2_btree_node_rewrite+0x17e/0x1120 [ 158.341314][ T5916] [<0>] __bch2_move_data_phys+0x139d/0x1c50 [ 158.341315][ T5907] [<0>] bch2_btree_node_rewrite_pos+0x187/0x240 [ 158.341323][ T5916] [<0>] bch2_evacuate_bucket+0x228/0x3a0 [ 158.341332][ T5907] [<0>] __bch2_move_data_phys+0x139d/0x1c50 [ 158.341333][ T5916] [<0>] bch2_copygc+0x3a89/0x4380 [ 158.341342][ T5907] [<0>] bch2_evacuate_bucket+0x228/0x3a0 [ 158.341344][ T5916] [<0>] bch2_copygc_thread+0x97a/0xe00 [ 158.341352][ T5907] [<0>] bch2_copygc+0x3a89/0x4380 [ 158.341354][ T5916] [<0>] kthread+0x711/0x8a0 [ 158.341362][ T5916] [<0>] ret_from_fork+0x3fc/0x770 [ 158.341366][ T5907] [<0>] bch2_copygc_thread+0x97a/0xe00 [ 158.341372][ T5916] [<0>] ret_from_fork_asm+0x1a/0x30 [ 158.341376][ T5907] [<0>] kthread+0x711/0x8a0 [ 158.341381][ T5916] [ 158.341385][ T5907] [<0>] ret_from_fork+0x3fc/0x770 [ 158.341389][ T5916] Journal debug: [ 158.341395][ T5907] [<0>] ret_from_fork_asm+0x1a/0x30 [ 158.341398][ T5916] flags: replay_done,running,may_skip_flush [ 158.341404][ T5907] [ 158.341408][ T5916] dirty journal entries: 0/32768 [ 158.341412][ T5907] Journal debug: [ 158.341418][ T5916] seq: 13 [ 158.341421][ T5907] flags: replay_done,running,may_skip_flush [ 158.341427][ T5916] seq_ondisk: 13 [ 158.341432][ T5907] dirty journal entries: 0/32768 [ 158.341436][ T5916] last_seq: 14 [ 158.341442][ T5907] seq: 13 [ 158.341446][ T5916] last_seq_ondisk: 13 [ 158.341451][ T5907] seq_ondisk: 13 [ 158.341455][ T5916] flushed_seq_ondisk: 13 [ 158.341460][ T5907] last_seq: 14 [ 158.341464][ T5916] watermark: stripe [ 158.341469][ T5907] last_seq_ondisk: 13 [ 158.341473][ T5916] each entry reserved: 321 [ 158.341479][ T5907] flushed_seq_ondisk: 13 [ 158.341483][ T5916] nr flush writes: 3 [ 158.341488][ T5907] watermark: stripe [ 158.341492][ T5916] nr noflush writes: 0 [ 158.341498][ T5907] each entry reserved: 321 [ 158.341501][ T5916] average write size: 1.43 KiB [ 158.341507][ T5907] nr flush writes: 3 [ 158.341511][ T5916] free buf: 65536 [ 158.341516][ T5907] nr noflush writes: 0 [ 158.341520][ T5916] nr direct reclaim: 0 [ 158.341526][ T5907] average write size: 1.43 KiB [ 158.341529][ T5916] nr background reclaim: 2 [ 158.341535][ T5907] free buf: 65536 [ 158.341538][ T5916] reclaim kicked: 0 [ 158.341545][ T5907] nr direct reclaim: 0 [ 158.341548][ T5916] reclaim runs in: 0 ms [ 158.341554][ T5907] nr background reclaim: 2 [ 158.341557][ T5916] blocked: 0 [ 158.341563][ T5907] reclaim kicked: 0 [ 158.341566][ T5916] current entry sectors: 256 [ 158.341572][ T5907] reclaim runs in: 0 ms [ 158.341575][ T5916] current entry error: (No error) [ 158.341582][ T5907] blocked: 0 [ 158.341585][ T5916] current entry: closed [ 158.341591][ T5907] current entry sectors: 256 [ 158.341595][ T5916] unwritten entries: [ 158.341600][ T5907] current entry error: (No error) [ 158.341603][ T5916] last buf closed [ 158.341610][ T5907] current entry: closed [ 158.341617][ T5916] space: [ 158.341620][ T5907] unwritten entries: [ 158.341625][ T5916] discarded 256:1536 [ 158.341629][ T5907] last buf closed [ 158.341635][ T5916] clean ondisk 256:1536 [ 158.341637][ T5907] space: [ 158.341644][ T5916] clean 256:1536 [ 158.341646][ T5907] discarded 256:1536 [ 158.341654][ T5916] total 256:2048 [ 158.341656][ T5907] clean ondisk 256:1536 [ 158.341664][ T5916] dev 0: [ 158.341666][ T5907] clean 256:1536 [ 158.341672][ T5916] durability 1: [ 158.341676][ T5907] total 256:2048 [ 158.341680][ T5916] nr 8 [ 158.341685][ T5907] dev 0: [ 158.341689][ T5916] bucket size 256 [ 158.341694][ T5907] durability 1: [ 158.341699][ T5916] available 6:232 [ 158.341702][ T5907] nr 8 [ 158.341708][ T5916] discard_idx 0 [ 158.341712][ T5907] bucket size 256 [ 158.341717][ T5916] dirty_ondisk 0 (seq 13) [ 158.341721][ T5907] available 6:232 [ 158.341727][ T5916] dirty_idx 0 (seq 13) [ 158.341730][ T5907] discard_idx 0 [ 158.341737][ T5916] cur_idx 0 (seq 13) [ 158.341740][ T5907] dirty_ondisk 0 (seq 13) [ 158.341746][ T5916] replicas want 1 need 1 [ 158.341750][ T5907] dirty_idx 0 (seq 13) [ 158.341759][ T5907] cur_idx 0 (seq 13) [ 158.341768][ T5907] replicas want 1 need 1 [ 158.353716][ T5913] Allocator stuck? Waited for 30 seconds [ 158.353729][ T5913] Allocator debug: [ 158.353737][ T5913] capacity1536 [ 158.353745][ T5913] reserved 31232 [ 158.353754][ T5913] hidden 0 [ 158.353763][ T5913] btree 0 [ 158.353771][ T5913] data 0 [ 158.353780][ T5913] cached 0 [ 158.353788][ T5913] reserved 0 [ 158.353797][ T5913] online_reserved 256 [ 158.353805][ T5913] nr_inodes 0 [ 158.353814][ T5913] [ 158.353821][ T5913] freelist_wait waiting [ 158.353830][ T5913] open buckets allocated1 [ 158.353838][ T5913] open buckets total 1024 [ 158.353847][ T5913] open_buckets_wait empty [ 158.353856][ T5913] open_buckets_btree 0 [ 158.353864][ T5913] open_buckets_user 0 [ 158.353873][ T5913] btree reserve cache 0 [ 158.353881][ T5913] [ 158.353888][ T5913] Dev 0: [ 158.353896][ T5913] buckets sectors fragmented [ 158.353907][ T5913] free 0 0 0 [ 158.353917][ T5913] sb 0 0 0 [ 158.353927][ T5913] journal 0 0 0 [ 158.353937][ T5913] btree 0 0 0 [ 158.353948][ T5913] user 0 0 0 [ 158.353958][ T5913] cached 0 0 0 [ 158.353968][ T5913] parity 0 0 0 [ 158.353978][ T5913] stripe 0 0 0 [ 158.353988][ T5913] need_gc_gens 0 0 0 [ 158.353998][ T5913] need_discard 0 0 0 [ 158.354008][ T5913] unstriped 0 0 0 [ 158.354036][ T5913] capacity 128 [ 158.354045][ T5913] [ 158.354053][ T5913] reserves: [ 158.354061][ T5913] stripe 60 [ 158.354070][ T5913] normal 58 [ 158.354079][ T5913] copygc 56 [ 158.354089][ T5913] btree 28 [ 158.354098][ T5913] btree_copygc 0 [ 158.354107][ T5913] reclaim 0 [ 158.354116][ T5913] interior_updates 0 [ 158.354125][ T5913] [ 158.354133][ T5913] open buckets 0 [ 158.354142][ T5913] buckets to invalidate 0 [ 158.354152][ T5913] [ 158.354159][ T5913] Copygc debug: [ 158.354168][ T5913] running: 1 [ 158.354176][ T5913] copygc_wait:0 [ 158.354185][ T5913] copygc_wait_at:0 [ 158.354194][ T5913] Currently waiting for:0 B [ 158.354203][ T5913] Currently waiting since:640 KiB [ 158.354212][ T5913] Currently calculated wait: [ 158.354221][ T5913] loop2: 0 B [ 158.354230][ T5913] [<0>] bch2_copygc_wait_to_text+0x831/0xa40 [ 158.354240][ T5913] [<0>] bch2_print_allocator_stuck+0x55c/0x640 [ 158.354250][ T5913] [<0>] __bch2_wait_on_allocator+0x17f/0x240 [ 158.354260][ T5913] [<0>] bch2_btree_update_start+0x18dc/0x1de0 [ 158.354270][ T5913] [<0>] bch2_btree_node_rewrite+0x17e/0x1120 [ 158.354280][ T5913] [<0>] bch2_btree_node_rewrite_pos+0x187/0x240 [ 158.354291][ T5913] [<0>] __bch2_move_data_phys+0x139d/0x1c50 [ 158.354301][ T5913] [<0>] bch2_evacuate_bucket+0x228/0x3a0 [ 158.354310][ T5913] [<0>] bch2_copygc+0x3a89/0x4380 [ 158.354320][ T5913] [<0>] bch2_copygc_thread+0x97a/0xe00 [ 158.354330][ T5913] [<0>] kthread+0x711/0x8a0 [ 158.354339][ T5913] [<0>] ret_from_fork+0x3fc/0x770 [ 158.354348][ T5913] [<0>] ret_from_fork_asm+0x1a/0x30 [ 158.354358][ T5913] [ 158.354365][ T5913] Journal debug: [ 158.354374][ T5913] flags: replay_done,running,may_skip_flush [ 158.354385][ T5913] dirty journal entries: 0/32768 [ 158.354395][ T5913] seq: 13 [ 158.354404][ T5913] seq_ondisk: 13 [ 158.354413][ T5913] last_seq: 14 [ 158.354422][ T5913] last_seq_ondisk: 13 [ 158.354432][ T5913] flushed_seq_ondisk: 13 [ 158.354441][ T5913] watermark: stripe [ 158.354450][ T5913] each entry reserved: 321 [ 158.354459][ T5913] nr flush writes: 3 [ 158.354469][ T5913] nr noflush writes: 0 [ 158.354478][ T5913] average write size: 1.43 KiB [ 158.354487][ T5913] free buf: 65536 [ 158.354497][ T5913] nr direct reclaim: 0 [ 158.354506][ T5913] nr background reclaim: 2 [ 158.354515][ T5913] reclaim kicked: 0 [ 158.354525][ T5913] reclaim runs in: 0 ms [ 158.354534][ T5913] blocked: 0 [ 158.354543][ T5913] current entry sectors: 256 [ 158.354552][ T5913] current entry error: (No error) [ 158.354562][ T5913] current entry: closed [ 158.354572][ T5913] unwritten entries: [ 158.354580][ T5913] last buf closed [ 158.354589][ T5913] space: [ 158.354597][ T5913] discarded 256:1536 [ 158.354607][ T5913] clean ondisk 256:1536 [ 158.354623][ T5913] clean 256:1536 [ 158.354632][ T5913] total 256:2048 [ 158.354642][ T5913] dev 0: [ 158.354650][ T5913] durability 1: [ 158.354659][ T5913] nr 8 [ 158.354668][ T5913] bucket size 256 [ 158.354678][ T5913] available 6:232 [ 158.354687][ T5913] discard_idx 0 [ 158.354696][ T5913] dirty_ondisk 0 (seq 13) [ 158.354722][ T5913] dirty_idx 0 (seq 13) [ 158.354733][ T5913] cur_idx 0 (seq 13) [ 158.354765][ T5913] replicas want 1 need 1 [ 158.355598][ T5919] Allocator stuck? Waited for 30 seconds [ 158.355619][ T5919] Allocator debug: [ 158.355630][ T5919] capacity1536 [ 158.355640][ T5919] reserved 31232 [ 158.355650][ T5919] hidden 0 [ 158.355661][ T5919] btree 0 [ 158.355671][ T5919] data 0 [ 158.355682][ T5919] cached 0 [ 158.355692][ T5919] reserved 0 [ 158.355703][ T5919] online_reserved 256 [ 158.355713][ T5919] nr_inodes 0 [ 158.355723][ T5919] [ 158.355732][ T5919] freelist_wait waiting [ 158.355742][ T5919] open buckets allocated1 [ 158.355751][ T5919] open buckets total 1024 [ 158.355761][ T5919] open_buckets_wait empty [ 158.355772][ T5919] open_buckets_btree 0 [ 158.355782][ T5919] open_buckets_user 0 [ 158.355791][ T5919] btree reserve cache 0 [ 158.355801][ T5919] [ 158.355810][ T5919] Dev 0: [ 158.355819][ T5919] buckets sectors fragmented [ 158.355831][ T5919] free 0 0 0 [ 158.355843][ T5919] sb 0 0 0 [ 158.355856][ T5919] journal 0 0 0 [ 158.355868][ T5919] btree 0 0 0 [ 158.355879][ T5919] user 0 0 0 [ 158.355891][ T5919] cached 0 0 0 [ 158.355903][ T5919] parity 0 0 0 [ 158.355915][ T5919] stripe 0 0 0 [ 158.355926][ T5919] need_gc_gens 0 0 0 [ 158.355938][ T5919] need_discard 0 0 0 [ 158.355950][ T5919] unstriped 0 0 0 [ 158.355962][ T5919] capacity 128 [ 158.355973][ T5919] [ 158.355981][ T5919] reserves: [ 158.355991][ T5919] stripe 60 [ 158.356001][ T5919] normal 58 [ 158.356011][ T5919] copygc 56 [ 158.356022][ T5919] btree 28 [ 158.356032][ T5919] btree_copygc 0 [ 158.356042][ T5919] reclaim 0 [ 158.356053][ T5919] interior_updates 0 [ 158.356063][ T5919] [ 158.356071][ T5919] open buckets 0 [ 158.356082][ T5919] buckets to invalidate 0 [ 158.356091][ T5919] [ 158.356100][ T5919] Copygc debug: [ 158.356110][ T5919] running: 1 [ 158.356119][ T5919] copygc_wait:0 [ 158.356128][ T5919] copygc_wait_at:0 [ 158.356138][ T5919] Currently waiting for:0 B [ 158.356149][ T5919] Currently waiting since:640 KiB [ 158.356159][ T5919] Currently calculated wait: [ 158.356170][ T5919] loop0: 0 B [ 158.356179][ T5919] [<0>] bch2_copygc_wait_to_text+0x831/0xa40 [ 158.356191][ T5919] [<0>] bch2_print_allocator_stuck+0x55c/0x640 [ 158.356202][ T5919] [<0>] __bch2_wait_on_allocator+0x17f/0x240 [ 158.356214][ T5919] [<0>] bch2_btree_update_start+0x18dc/0x1de0 [ 158.356225][ T5919] [<0>] bch2_btree_node_rewrite+0x17e/0x1120 [ 158.356236][ T5919] [<0>] bch2_btree_node_rewrite_pos+0x187/0x240 [ 158.356248][ T5919] [<0>] __bch2_move_data_phys+0x139d/0x1c50 [ 158.356259][ T5919] [<0>] bch2_evacuate_bucket+0x228/0x3a0 [ 158.356270][ T5919] [<0>] bch2_copygc+0x3a89/0x4380 [ 158.356281][ T5919] [<0>] bch2_copygc_thread+0x97a/0xe00 [ 158.356292][ T5919] [<0>] kthread+0x711/0x8a0 [ 158.356302][ T5919] [<0>] ret_from_fork+0x3fc/0x770 [ 158.356313][ T5919] [<0>] ret_from_fork_asm+0x1a/0x30 [ 158.356323][ T5919] [ 158.356332][ T5919] Journal debug: [ 158.356342][ T5919] flags: replay_done,running,may_skip_flush [ 158.356354][ T5919] dirty journal entries: 0/32768 [ 158.356365][ T5919] seq: 12 [ 158.356375][ T5919] seq_ondisk: 12 [ 158.356386][ T5919] last_seq: 13 [ 158.356396][ T5919] last_seq_ondisk: 12 [ 158.356407][ T5919] flushed_seq_ondisk: 12 [ 158.356417][ T5919] watermark: stripe [ 158.356428][ T5919] each entry reserved: 321 [ 158.356438][ T5919] nr flush writes: 2 [ 158.356460][ T5919] nr noflush writes: 0 [ 158.356470][ T5919] average write size: 1.57 KiB [ 158.356480][ T5919] free buf: 65536 [ 158.356502][ T5919] nr direct reclaim: 0 [ 158.356511][ T5919] nr background reclaim: 2 [ 158.356520][ T5919] reclaim kicked: 0 [ 158.356529][ T5919] reclaim runs in: 0 ms [ 158.356556][ T5919] blocked: 0 [ 158.356566][ T5919] current entry sectors: 256 [ 158.356576][ T5919] current entry error: (No error) [ 158.356587][ T5919] current entry: closed [ 158.356597][ T5919] unwritten entries: [ 158.356606][ T5919] last buf closed [ 158.356623][ T5919] space: [ 158.356624][ T5910] Allocator stuck? Waited for 30 seconds [ 158.356632][ T5919] discarded 256:1536 [ 158.356636][ T5910] Allocator debug: [ 158.356642][ T5919] clean ondisk 256:1536 [ 158.356646][ T5910] capacity1536 [ 158.356653][ T5919] clean 256:1536 [ 158.356655][ T5910] reserved 31232 [ 158.356664][ T5919] total 256:2048 [ 158.356666][ T5910] hidden 0 [ 158.356675][ T5919] dev 0: [ 158.356678][ T5910] btree 0 [ 158.356684][ T5919] durability 1: [ 158.356688][ T5910] data 0 [ 158.356694][ T5919] nr 8 [ 158.356698][ T5910] cached 0 [ 158.356704][ T5919] bucket size 256 [ 158.356708][ T5910] reserved 0 [ 158.356714][ T5919] available 6:240 [ 158.356718][ T5910] online_reserved 256 [ 158.356725][ T5919] discard_idx 0 [ 158.356729][ T5910] nr_inodes 0 [ 158.356735][ T5919] dirty_ondisk 0 (seq 12) [ 158.356739][ T5910] [ 158.356746][ T5919] dirty_idx 0 (seq 12) [ 158.356748][ T5910] freelist_wait waiting [ 158.356757][ T5919] cur_idx 0 (seq 12) [ 158.356758][ T5910] open buckets allocated1 [ 158.356768][ T5919] replicas want 1 need 1 [ 158.356770][ T5910] open buckets total 1024 [ 158.356780][ T5910] open_buckets_wait empty [ 158.356790][ T5910] open_buckets_btree 0 [ 158.356799][ T5910] open_buckets_user 0 [ 158.356808][ T5910] btree reserve cache 0 [ 158.356817][ T5910] [ 158.356825][ T5910] Dev 0: [ 158.356833][ T5910] buckets sectors fragmented [ 158.356844][ T5910] free 0 0 0 [ 158.356856][ T5910] sb 0 0 0 [ 158.356868][ T5910] journal 0 0 0 [ 158.356879][ T5910] btree 0 0 0 [ 158.356894][ T5910] user 0 0 0 [ 158.356905][ T5910] cached 0 0 0 [ 158.356916][ T5910] parity 0 0 0 [ 158.356927][ T5910] stripe 0 0 0 [ 158.356939][ T5910] need_gc_gens 0 0 0 [ 158.356950][ T5910] need_discard 0 0 0 [ 158.356962][ T5910] unstriped 0 0 0 [ 160.675934][ T5910] capacity 128 [ 160.680777][ T5910] [ 160.683300][ T5910] reserves: [ 160.686587][ T5910] stripe 60 [ 160.691397][ T5910] normal 58 [ 160.696162][ T5910] copygc 56 [ 160.700951][ T5910] btree 28 [ 160.705713][ T5910] btree_copygc 0 [ 160.710606][ T5910] reclaim 0 [ 160.715383][ T5910] interior_updates 0 [ 160.720273][ T5910] [ 160.722781][ T5910] open buckets 0 [ 160.727529][ T5910] buckets to invalidate 0 [ 160.732330][ T5910] [ 160.734666][ T5910] Copygc debug: [ 160.738132][ T5910] running: 1 [ 160.741522][ T5910] copygc_wait:0 [ 160.745153][ T5910] copygc_wait_at:0 [ 160.749030][ T5910] Currently waiting for:0 B [ 160.753734][ T5910] Currently waiting since:640 KiB [ 160.758925][ T5910] Currently calculated wait: [ 160.763712][ T5910] loop4: 0 B [ 160.767255][ T5910] [<0>] bch2_copygc_wait_to_text+0x831/0xa40 [ 160.773447][ T5910] [<0>] bch2_print_allocator_stuck+0x55c/0x640 [ 160.779832][ T5910] [<0>] __bch2_wait_on_allocator+0x17f/0x240 [ 160.785980][ T5910] [<0>] bch2_btree_update_start+0x18dc/0x1de0 [ 160.792274][ T5910] [<0>] bch2_btree_node_rewrite+0x17e/0x1120 [ 160.798428][ T5910] [<0>] bch2_btree_node_rewrite_pos+0x187/0x240 [ 160.805356][ T5910] [<0>] __bch2_move_data_phys+0x139d/0x1c50 [ 160.811563][ T5910] [<0>] bch2_evacuate_bucket+0x228/0x3a0 [ 160.817371][ T5910] [<0>] bch2_copygc+0x3a89/0x4380 [ 160.822603][ T5910] [<0>] bch2_copygc_thread+0x97a/0xe00 [ 160.828245][ T5910] [<0>] kthread+0x711/0x8a0 [ 160.832956][ T5910] [<0>] ret_from_fork+0x3fc/0x770 [ 160.838155][ T5910] [<0>] ret_from_fork_asm+0x1a/0x30 [ 160.843545][ T5910] [ 160.845881][ T5910] Journal debug: [ 160.849416][ T5910] flags: replay_done,running,may_skip_flush [ 160.857074][ T5910] dirty journal entries: 0/32768 [ 160.862397][ T5910] seq: 12 [ 160.867246][ T5910] seq_ondisk: 12 [ 160.872126][ T5910] last_seq: 13 [ 160.876973][ T5910] last_seq_ondisk: 12 [ 160.881906][ T5910] flushed_seq_ondisk: 12 [ 160.886771][ T5910] watermark: stripe [ 160.892022][ T5910] each entry reserved: 321 [ 160.896963][ T5910] nr flush writes: 2 [ 160.901794][ T5910] nr noflush writes: 0 [ 160.906556][ T5910] average write size: 1.57 KiB [ 160.911988][ T5910] free buf: 65536 [ 160.917104][ T5910] nr direct reclaim: 0 [ 160.921892][ T5910] nr background reclaim: 1 [ 160.926654][ T5910] reclaim kicked: 0 [ 160.931479][ T5910] reclaim runs in: 0 ms [ 160.936532][ T5910] blocked: 0 [ 160.941327][ T5910] current entry sectors: 256 [ 160.946277][ T5910] current entry error: (No error) [ 160.951885][ T5910] current entry: closed [ 160.957084][ T5910] unwritten entries: [ 160.961185][ T5910] last buf closed [ 160.964993][ T5910] space: [ 160.968001][ T5910] discarded 256:1536 [ 160.973399][ T5910] clean ondisk 256:1536 [ 160.978768][ T5910] clean 256:1536 [ 160.984153][ T5910] total 256:2048 [ 160.989582][ T5910] dev 0: [ 160.992598][ T5910] durability 1: [ 160.996213][ T5910] nr 8 [ 161.000998][ T5910] bucket size 256 [ 161.005965][ T5910] available 6:240 [ 161.011116][ T5910] discard_idx 0 [ 161.015881][ T5910] dirty_ondisk 0 (seq 12) [ 161.021457][ T5910] dirty_idx 0 (seq 12) [ 161.027003][ T5910] cur_idx 0 (seq 12) [ 161.032579][ T5910] replicas want 1 need 1 [ 286.331228][ T31] INFO: task syz-executor399:5857 blocked for more than 143 seconds. [ 286.339361][ T31] Not tainted 6.16.0-rc3-next-20250626-syzkaller #0 [ 286.346627][ T31] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 286.355350][ T31] task:syz-executor399 state:D stack:26232 pid:5857 tgid:5857 ppid:5856 task_flags:0x400140 flags:0x00004002 [ 286.367368][ T31] Call Trace: [ 286.370674][ T31] [ 286.373615][ T31] __schedule+0x16f5/0x4d00 [ 286.378140][ T31] ? schedule+0x165/0x360 [ 286.382588][ T31] ? __lock_acquire+0xab9/0xd20 [ 286.387458][ T31] ? __pfx___schedule+0x10/0x10 [ 286.392366][ T31] ? schedule+0x91/0x360 [ 286.396632][ T31] schedule+0x165/0x360 [ 286.400947][ T31] schedule_timeout+0x9a/0x270 [ 286.405732][ T31] ? __pfx_schedule_timeout+0x10/0x10 [ 286.411163][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 286.416376][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 286.421662][ T31] ? wait_for_completion+0x267/0x5d0 [ 286.426983][ T31] wait_for_completion+0x2bf/0x5d0 [ 286.432158][ T31] ? __pfx_wait_for_completion+0x10/0x10 [ 286.437817][ T31] ? try_to_wake_up+0x7e5/0x1290 [ 286.442801][ T31] kthread_stop+0x194/0x5f0 [ 286.447328][ T31] bch2_copygc_stop+0x4f/0x150 [ 286.452149][ T31] __bch2_fs_read_only+0x47/0x5b0 [ 286.457189][ T31] bch2_fs_read_only+0x42a/0xb00 [ 286.462165][ T31] ? __bch2_fs_stop+0xf8/0x900 [ 286.466946][ T31] ? __pfx_bch2_fs_read_only+0x10/0x10 [ 286.472432][ T31] ? __bch2_print+0x176/0x220 [ 286.477121][ T31] ? __pfx___might_resched+0x10/0x10 [ 286.482451][ T31] ? down_write+0x162/0x1f0 [ 286.486968][ T31] ? __pfx_down_write+0x10/0x10 [ 286.491867][ T31] ? do_raw_spin_unlock+0x122/0x240 [ 286.497089][ T31] __bch2_fs_stop+0x100/0x900 [ 286.501822][ T31] ? evict_inodes+0x66c/0x6c0 [ 286.506548][ T31] ? __pfx___bch2_fs_stop+0x10/0x10 [ 286.511807][ T31] ? __pfx_evict_inodes+0x10/0x10 [ 286.516854][ T31] ? bch2_sync_fs+0x14f/0x2e0 [ 286.521584][ T31] ? __pfx_bch2_put_super+0x10/0x10 [ 286.526799][ T31] generic_shutdown_super+0x132/0x2c0 [ 286.532215][ T31] bch2_kill_sb+0x41/0x50 [ 286.536559][ T31] deactivate_locked_super+0xbc/0x130 [ 286.541977][ T31] cleanup_mnt+0x425/0x4c0 [ 286.546412][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 286.551663][ T31] task_work_run+0x1d4/0x260 [ 286.556284][ T31] ? __pfx_task_work_run+0x10/0x10 [ 286.561476][ T31] ? __x64_sys_umount+0x122/0x160 [ 286.566571][ T31] ptrace_notify+0x281/0x2c0 [ 286.571231][ T31] ? __pfx_ptrace_notify+0x10/0x10 [ 286.576369][ T31] ? __x64_sys_umount+0x122/0x160 [ 286.581574][ T31] ? __pfx___x64_sys_umount+0x10/0x10 [ 286.587149][ T31] ? rcu_is_watching+0x15/0xb0 [ 286.591966][ T31] syscall_exit_work+0xc6/0x1d0 [ 286.596840][ T31] do_syscall_64+0x2ad/0x3b0 [ 286.601472][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 286.606689][ T31] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 286.612807][ T31] ? clear_bhb_loop+0x60/0xb0 [ 286.617509][ T31] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 286.623477][ T31] RIP: 0033:0x7fe352b30477 [ 286.627925][ T31] RSP: 002b:00007ffd45983b38 EFLAGS: 00000202 ORIG_RAX: 00000000000000a6 [ 286.636394][ T31] RAX: 0000000000000000 RBX: 000000000001ebc2 RCX: 00007fe352b30477 [ 286.644434][ T31] RDX: 0000000000000000 RSI: 0000000000000009 RDI: 00007ffd45983bf0 [ 286.652457][ T31] RBP: 00007ffd45983bf0 R08: 0000000000000000 R09: 0000000000000000 [ 286.660468][ T31] R10: 00000000ffffffff R11: 0000000000000202 R12: 00007ffd45984c60 [ 286.668439][ T31] R13: 000055557342f6c0 R14: 0000000000000001 R15: 431bde82d7b634db [ 286.676507][ T31] [ 286.679651][ T31] INFO: task syz-executor399:5858 blocked for more than 143 seconds. [ 286.687704][ T31] Not tainted 6.16.0-rc3-next-20250626-syzkaller #0 [ 286.694832][ T31] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 286.703538][ T31] task:syz-executor399 state:D stack:25096 pid:5858 tgid:5858 ppid:5856 task_flags:0x400140 flags:0x00004002 [ 286.715503][ T31] Call Trace: [ 286.718804][ T31] [ 286.721769][ T31] __schedule+0x16f5/0x4d00 [ 286.726315][ T31] ? schedule+0x165/0x360 [ 286.730711][ T31] ? __lock_acquire+0xab9/0xd20 [ 286.735585][ T31] ? __pfx___schedule+0x10/0x10 [ 286.740501][ T31] ? schedule+0x91/0x360 [ 286.744763][ T31] schedule+0x165/0x360 [ 286.748920][ T31] schedule_timeout+0x9a/0x270 [ 286.753722][ T31] ? __pfx_schedule_timeout+0x10/0x10 [ 286.759113][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 286.764370][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 286.769624][ T31] ? wait_for_completion+0x267/0x5d0 [ 286.774942][ T31] wait_for_completion+0x2bf/0x5d0 [ 286.780141][ T31] ? __pfx_wait_for_completion+0x10/0x10 [ 286.785802][ T31] ? try_to_wake_up+0x7e5/0x1290 [ 286.790824][ T31] kthread_stop+0x194/0x5f0 [ 286.795385][ T31] bch2_copygc_stop+0x4f/0x150 [ 286.800209][ T31] __bch2_fs_read_only+0x47/0x5b0 [ 286.805251][ T31] bch2_fs_read_only+0x42a/0xb00 [ 286.810242][ T31] ? __bch2_fs_stop+0xf8/0x900 [ 286.815036][ T31] ? __pfx_bch2_fs_read_only+0x10/0x10 [ 286.820528][ T31] ? __bch2_print+0x176/0x220 [ 286.825218][ T31] ? __pfx___might_resched+0x10/0x10 [ 286.830573][ T31] ? down_write+0x162/0x1f0 [ 286.835083][ T31] ? __pfx_down_write+0x10/0x10 [ 286.839956][ T31] ? do_raw_spin_unlock+0x122/0x240 [ 286.845167][ T31] __bch2_fs_stop+0x100/0x900 [ 286.849931][ T31] ? evict_inodes+0x66c/0x6c0 [ 286.854631][ T31] ? __pfx___bch2_fs_stop+0x10/0x10 [ 286.859862][ T31] ? __pfx_evict_inodes+0x10/0x10 [ 286.864903][ T31] ? bch2_sync_fs+0x14f/0x2e0 [ 286.869630][ T31] ? __pfx_bch2_put_super+0x10/0x10 [ 286.874841][ T31] generic_shutdown_super+0x132/0x2c0 [ 286.880255][ T31] bch2_kill_sb+0x41/0x50 [ 286.884592][ T31] deactivate_locked_super+0xbc/0x130 [ 286.890017][ T31] cleanup_mnt+0x425/0x4c0 [ 286.894460][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 286.899825][ T31] task_work_run+0x1d4/0x260 [ 286.904486][ T31] ? __pfx_task_work_run+0x10/0x10 [ 286.909651][ T31] ? __x64_sys_umount+0x122/0x160 [ 286.914706][ T31] ptrace_notify+0x281/0x2c0 [ 286.919303][ T31] ? __pfx_ptrace_notify+0x10/0x10 [ 286.924464][ T31] ? __x64_sys_umount+0x122/0x160 [ 286.929554][ T31] ? __pfx___x64_sys_umount+0x10/0x10 [ 286.934938][ T31] ? rcu_is_watching+0x15/0xb0 [ 286.939762][ T31] syscall_exit_work+0xc6/0x1d0 [ 286.944665][ T31] do_syscall_64+0x2ad/0x3b0 [ 286.949272][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 286.954540][ T31] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 286.960669][ T31] ? clear_bhb_loop+0x60/0xb0 [ 286.965393][ T31] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 286.971338][ T31] RIP: 0033:0x7fe352b30477 [ 286.975793][ T31] RSP: 002b:00007ffd45983b38 EFLAGS: 00000202 ORIG_RAX: 00000000000000a6 [ 286.984265][ T31] RAX: 0000000000000000 RBX: 000000000001ebbc RCX: 00007fe352b30477 [ 286.992276][ T31] RDX: 0000000000000000 RSI: 0000000000000009 RDI: 00007ffd45983bf0 [ 287.000290][ T31] RBP: 00007ffd45983bf0 R08: 0000000000000000 R09: 0000000000000000 [ 287.008270][ T31] R10: 00000000ffffffff R11: 0000000000000202 R12: 00007ffd45984c60 [ 287.016291][ T31] R13: 000055557342f6c0 R14: 0000000000000001 R15: 431bde82d7b634db [ 287.024312][ T31] [ 287.027382][ T31] INFO: task syz-executor399:5859 blocked for more than 144 seconds. [ 287.035494][ T31] Not tainted 6.16.0-rc3-next-20250626-syzkaller #0 [ 287.042649][ T31] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 287.051416][ T31] task:syz-executor399 state:D stack:26920 pid:5859 tgid:5859 ppid:5856 task_flags:0x400140 flags:0x00004002 [ 287.063527][ T31] Call Trace: [ 287.066829][ T31] [ 287.069818][ T31] __schedule+0x16f5/0x4d00 [ 287.074362][ T31] ? schedule+0x165/0x360 [ 287.078697][ T31] ? __lock_acquire+0xab9/0xd20 [ 287.083587][ T31] ? __pfx___schedule+0x10/0x10 [ 287.088470][ T31] ? schedule+0x91/0x360 [ 287.092767][ T31] schedule+0x165/0x360 [ 287.096946][ T31] schedule_timeout+0x9a/0x270 [ 287.101797][ T31] ? __pfx_schedule_timeout+0x10/0x10 [ 287.107208][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 287.112464][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 287.117683][ T31] ? wait_for_completion+0x267/0x5d0 [ 287.123038][ T31] wait_for_completion+0x2bf/0x5d0 [ 287.128181][ T31] ? __pfx_wait_for_completion+0x10/0x10 [ 287.133864][ T31] ? try_to_wake_up+0x7e5/0x1290 [ 287.138827][ T31] kthread_stop+0x194/0x5f0 [ 287.143373][ T31] bch2_copygc_stop+0x4f/0x150 [ 287.148157][ T31] __bch2_fs_read_only+0x47/0x5b0 [ 287.153222][ T31] bch2_fs_read_only+0x42a/0xb00 [ 287.158177][ T31] ? __bch2_fs_stop+0xf8/0x900 [ 287.162976][ T31] ? __pfx_bch2_fs_read_only+0x10/0x10 [ 287.168446][ T31] ? __bch2_print+0x176/0x220 [ 287.173170][ T31] ? __pfx___might_resched+0x10/0x10 [ 287.178488][ T31] ? down_write+0x162/0x1f0 [ 287.183059][ T31] ? __pfx_down_write+0x10/0x10 [ 287.187936][ T31] ? do_raw_spin_unlock+0x122/0x240 [ 287.193194][ T31] __bch2_fs_stop+0x100/0x900 [ 287.197905][ T31] ? evict_inodes+0x66c/0x6c0 [ 287.202630][ T31] ? __pfx___bch2_fs_stop+0x10/0x10 [ 287.207859][ T31] ? __pfx_evict_inodes+0x10/0x10 [ 287.212932][ T31] ? bch2_sync_fs+0x14f/0x2e0 [ 287.217631][ T31] ? __pfx_bch2_put_super+0x10/0x10 [ 287.222878][ T31] generic_shutdown_super+0x132/0x2c0 [ 287.228273][ T31] bch2_kill_sb+0x41/0x50 [ 287.232666][ T31] deactivate_locked_super+0xbc/0x130 [ 287.238066][ T31] cleanup_mnt+0x425/0x4c0 [ 287.242536][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 287.247758][ T31] task_work_run+0x1d4/0x260 [ 287.252399][ T31] ? __pfx_task_work_run+0x10/0x10 [ 287.257530][ T31] ? __x64_sys_umount+0x122/0x160 [ 287.262601][ T31] ptrace_notify+0x281/0x2c0 [ 287.267212][ T31] ? __pfx_ptrace_notify+0x10/0x10 [ 287.272394][ T31] ? __x64_sys_umount+0x122/0x160 [ 287.277444][ T31] ? __pfx___x64_sys_umount+0x10/0x10 [ 287.282872][ T31] ? rcu_is_watching+0x15/0xb0 [ 287.287661][ T31] syscall_exit_work+0xc6/0x1d0 [ 287.292552][ T31] do_syscall_64+0x2ad/0x3b0 [ 287.297161][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 287.302435][ T31] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 287.308535][ T31] ? clear_bhb_loop+0x60/0xb0 [ 287.313269][ T31] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 287.319186][ T31] RIP: 0033:0x7fe352b30477 [ 287.323639][ T31] RSP: 002b:00007ffd45983b38 EFLAGS: 00000202 ORIG_RAX: 00000000000000a6 [ 287.332180][ T31] RAX: 0000000000000000 RBX: 000000000001ebcc RCX: 00007fe352b30477 [ 287.340277][ T31] RDX: 0000000000000000 RSI: 0000000000000009 RDI: 00007ffd45983bf0 [ 287.348259][ T31] RBP: 00007ffd45983bf0 R08: 0000000000000000 R09: 0000000000000000 [ 287.356272][ T31] R10: 00000000ffffffff R11: 0000000000000202 R12: 00007ffd45984c60 [ 287.364307][ T31] R13: 000055557342f6c0 R14: 0000000000000001 R15: 431bde82d7b634db [ 287.372364][ T31] [ 287.375499][ T31] INFO: task syz-executor399:5860 blocked for more than 144 seconds. [ 287.383630][ T31] Not tainted 6.16.0-rc3-next-20250626-syzkaller #0 [ 287.390778][ T31] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 287.399538][ T31] task:syz-executor399 state:D stack:26920 pid:5860 tgid:5860 ppid:5856 task_flags:0x400140 flags:0x00004002 [ 287.411510][ T31] Call Trace: [ 287.414776][ T31] [ 287.417697][ T31] __schedule+0x16f5/0x4d00 [ 287.422284][ T31] ? __pfx_rcu_read_lock_sched_held+0x10/0x10 [ 287.428379][ T31] ? schedule+0x165/0x360 [ 287.432755][ T31] ? __lock_acquire+0xab9/0xd20 [ 287.437622][ T31] ? __pfx___schedule+0x10/0x10 [ 287.442559][ T31] ? schedule+0x91/0x360 [ 287.446814][ T31] schedule+0x165/0x360 [ 287.451020][ T31] schedule_timeout+0x9a/0x270 [ 287.455814][ T31] ? __pfx_schedule_timeout+0x10/0x10 [ 287.461238][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 287.466493][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 287.471749][ T31] ? wait_for_completion+0x267/0x5d0 [ 287.477049][ T31] wait_for_completion+0x2bf/0x5d0 [ 287.482218][ T31] ? __pfx_wait_for_completion+0x10/0x10 [ 287.487875][ T31] ? try_to_wake_up+0x7e5/0x1290 [ 287.492850][ T31] kthread_stop+0x194/0x5f0 [ 287.497372][ T31] bch2_copygc_stop+0x4f/0x150 [ 287.502172][ T31] __bch2_fs_read_only+0x47/0x5b0 [ 287.507213][ T31] bch2_fs_read_only+0x42a/0xb00 [ 287.512177][ T31] ? __bch2_fs_stop+0xf8/0x900 [ 287.516950][ T31] ? __pfx_bch2_fs_read_only+0x10/0x10 [ 287.522448][ T31] ? __bch2_print+0x176/0x220 [ 287.527145][ T31] ? __pfx___might_resched+0x10/0x10 [ 287.532478][ T31] ? down_write+0x162/0x1f0 [ 287.536992][ T31] ? __pfx_down_write+0x10/0x10 [ 287.541879][ T31] ? do_raw_spin_unlock+0x122/0x240 [ 287.547095][ T31] __bch2_fs_stop+0x100/0x900 [ 287.551837][ T31] ? evict_inodes+0x66c/0x6c0 [ 287.556542][ T31] ? __pfx___bch2_fs_stop+0x10/0x10 [ 287.561809][ T31] ? __pfx_evict_inodes+0x10/0x10 [ 287.566866][ T31] ? bch2_sync_fs+0x14f/0x2e0 [ 287.571596][ T31] ? __pfx_bch2_put_super+0x10/0x10 [ 287.576812][ T31] generic_shutdown_super+0x132/0x2c0 [ 287.582231][ T31] bch2_kill_sb+0x41/0x50 [ 287.586595][ T31] deactivate_locked_super+0xbc/0x130 [ 287.592015][ T31] cleanup_mnt+0x425/0x4c0 [ 287.596475][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 287.601747][ T31] task_work_run+0x1d4/0x260 [ 287.606364][ T31] ? __pfx_task_work_run+0x10/0x10 [ 287.611514][ T31] ? __x64_sys_umount+0x122/0x160 [ 287.616580][ T31] ptrace_notify+0x281/0x2c0 [ 287.621242][ T31] ? __pfx_ptrace_notify+0x10/0x10 [ 287.626381][ T31] ? __x64_sys_umount+0x122/0x160 [ 287.631447][ T31] ? __pfx___x64_sys_umount+0x10/0x10 [ 287.636843][ T31] ? rcu_is_watching+0x15/0xb0 [ 287.641656][ T31] syscall_exit_work+0xc6/0x1d0 [ 287.646535][ T31] do_syscall_64+0x2ad/0x3b0 [ 287.651160][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 287.656381][ T31] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 287.662525][ T31] ? clear_bhb_loop+0x60/0xb0 [ 287.667220][ T31] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 287.673141][ T31] RIP: 0033:0x7fe352b30477 [ 287.677565][ T31] RSP: 002b:00007ffd45983b38 EFLAGS: 00000202 ORIG_RAX: 00000000000000a6 [ 287.686125][ T31] RAX: 0000000000000000 RBX: 000000000001ebcb RCX: 00007fe352b30477 [ 287.694570][ T31] RDX: 0000000000000000 RSI: 0000000000000009 RDI: 00007ffd45983bf0 [ 287.702729][ T31] RBP: 00007ffd45983bf0 R08: 0000000000000000 R09: 0000000000000000 [ 287.710812][ T31] R10: 00000000ffffffff R11: 0000000000000202 R12: 00007ffd45984c60 [ 287.718779][ T31] R13: 000055557342f6c0 R14: 0000000000000001 R15: 431bde82d7b634db [ 287.726803][ T31] [ 287.729860][ T31] INFO: task syz-executor399:5861 blocked for more than 144 seconds. [ 287.737946][ T31] Not tainted 6.16.0-rc3-next-20250626-syzkaller #0 [ 287.745097][ T31] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 287.753793][ T31] task:syz-executor399 state:D stack:26920 pid:5861 tgid:5861 ppid:5856 task_flags:0x400140 flags:0x00004002 [ 287.765778][ T31] Call Trace: [ 287.769071][ T31] [ 287.772158][ T31] __schedule+0x16f5/0x4d00 [ 287.776712][ T31] ? schedule+0x165/0x360 [ 287.781094][ T31] ? __lock_acquire+0xab9/0xd20 [ 287.785963][ T31] ? __pfx___schedule+0x10/0x10 [ 287.790865][ T31] ? schedule+0x91/0x360 [ 287.795122][ T31] schedule+0x165/0x360 [ 287.799278][ T31] schedule_timeout+0x9a/0x270 [ 287.804105][ T31] ? __pfx_schedule_timeout+0x10/0x10 [ 287.809571][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 287.814801][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 287.820060][ T31] ? wait_for_completion+0x267/0x5d0 [ 287.825409][ T31] wait_for_completion+0x2bf/0x5d0 [ 287.830612][ T31] ? __pfx_wait_for_completion+0x10/0x10 [ 287.836270][ T31] ? try_to_wake_up+0x7e5/0x1290 [ 287.841269][ T31] kthread_stop+0x194/0x5f0 [ 287.845799][ T31] bch2_copygc_stop+0x4f/0x150 [ 287.850599][ T31] __bch2_fs_read_only+0x47/0x5b0 [ 287.855639][ T31] bch2_fs_read_only+0x42a/0xb00 [ 287.860623][ T31] ? __bch2_fs_stop+0xf8/0x900 [ 287.865417][ T31] ? __pfx_bch2_fs_read_only+0x10/0x10 [ 287.870959][ T31] ? __bch2_print+0x176/0x220 [ 287.875651][ T31] ? __pfx___might_resched+0x10/0x10 [ 287.881002][ T31] ? down_write+0x162/0x1f0 [ 287.885514][ T31] ? __pfx_down_write+0x10/0x10 [ 287.890409][ T31] ? do_raw_spin_unlock+0x122/0x240 [ 287.895623][ T31] __bch2_fs_stop+0x100/0x900 [ 287.900369][ T31] ? evict_inodes+0x66c/0x6c0 [ 287.905065][ T31] ? __pfx___bch2_fs_stop+0x10/0x10 [ 287.910391][ T31] ? __pfx_evict_inodes+0x10/0x10 [ 287.915446][ T31] ? bch2_sync_fs+0x14f/0x2e0 [ 287.920183][ T31] ? __pfx_bch2_put_super+0x10/0x10 [ 287.925402][ T31] generic_shutdown_super+0x132/0x2c0 [ 287.930810][ T31] bch2_kill_sb+0x41/0x50 [ 287.935151][ T31] deactivate_locked_super+0xbc/0x130 [ 287.940577][ T31] cleanup_mnt+0x425/0x4c0 [ 287.945009][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 287.950260][ T31] task_work_run+0x1d4/0x260 [ 287.954882][ T31] ? __pfx_task_work_run+0x10/0x10 [ 287.960049][ T31] ? __x64_sys_umount+0x122/0x160 [ 287.965095][ T31] ptrace_notify+0x281/0x2c0 [ 287.969727][ T31] ? __pfx_ptrace_notify+0x10/0x10 [ 287.974880][ T31] ? __x64_sys_umount+0x122/0x160 [ 287.979953][ T31] ? __pfx___x64_sys_umount+0x10/0x10 [ 287.985383][ T31] ? rcu_is_watching+0x15/0xb0 [ 287.990202][ T31] syscall_exit_work+0xc6/0x1d0 [ 287.995075][ T31] do_syscall_64+0x2ad/0x3b0 [ 287.999716][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 288.004927][ T31] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 288.011024][ T31] ? clear_bhb_loop+0x60/0xb0 [ 288.015718][ T31] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 288.021665][ T31] RIP: 0033:0x7fe352b30477 [ 288.026096][ T31] RSP: 002b:00007ffd45983b38 EFLAGS: 00000202 ORIG_RAX: 00000000000000a6 [ 288.034541][ T31] RAX: 0000000000000000 RBX: 000000000001ebdb RCX: 00007fe352b30477 [ 288.042559][ T31] RDX: 0000000000000000 RSI: 0000000000000009 RDI: 00007ffd45983bf0 [ 288.050559][ T31] RBP: 00007ffd45983bf0 R08: 0000000000000000 R09: 0000000000000000 [ 288.058536][ T31] R10: 00000000ffffffff R11: 0000000000000202 R12: 00007ffd45984c60 [ 288.066590][ T31] R13: 000055557342f6c0 R14: 0000000000000001 R15: 431bde82d7b634db [ 288.074601][ T31] [ 288.077635][ T31] [ 288.077635][ T31] Showing all locks held in the system: [ 288.085469][ T31] 6 locks held by kworker/u8:0/12: [ 288.090616][ T31] 1 lock held by khungtaskd/31: [ 288.095472][ T31] #0: ffffffff8e13bf20 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x2e/0x180 [ 288.105526][ T31] 2 locks held by getty/5589: [ 288.110255][ T31] #0: ffff888033f2f0a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 [ 288.120114][ T31] #1: ffffc9000331b2f0 (&ldata->atomic_read_lock){+.+.}-{4:4}, at: n_tty_read+0x43e/0x1400 [ 288.130266][ T31] 2 locks held by syz-executor399/5857: [ 288.135815][ T31] #0: ffff88807ef740e0 (&type->s_umount_key#42){+.+.}-{4:4}, at: deactivate_super+0xa9/0xe0 [ 288.146062][ T31] #1: ffff88806c480278 (&c->state_lock){++++}-{4:4}, at: __bch2_fs_stop+0xf8/0x900 [ 288.155536][ T31] 2 locks held by syz-executor399/5858: [ 288.161122][ T31] #0: ffff8880234400e0 (&type->s_umount_key#42){+.+.}-{4:4}, at: deactivate_super+0xa9/0xe0 [ 288.171365][ T31] #1: ffff88806c580278 (&c->state_lock){++++}-{4:4}, at: __bch2_fs_stop+0xf8/0x900 [ 288.180809][ T31] 2 locks held by syz-executor399/5859: [ 288.186358][ T31] #0: ffff88802f3140e0 (&type->s_umount_key#42){+.+.}-{4:4}, at: deactivate_super+0xa9/0xe0 [ 288.196606][ T31] #1: ffff88806c500278 (&c->state_lock){++++}-{4:4}, at: __bch2_fs_stop+0xf8/0x900 [ 288.206067][ T31] 2 locks held by syz-executor399/5860: [ 288.211653][ T31] #0: ffff888033fae0e0 (&type->s_umount_key#42){+.+.}-{4:4}, at: deactivate_super+0xa9/0xe0 [ 288.221913][ T31] #1: ffff88806c400278 (&c->state_lock){++++}-{4:4}, at: __bch2_fs_stop+0xf8/0x900 [ 288.231380][ T31] 2 locks held by syz-executor399/5861: [ 288.236950][ T31] #0: ffff8880321980e0 (&type->s_umount_key#42){+.+.}-{4:4}, at: deactivate_super+0xa9/0xe0 [ 288.247228][ T31] #1: ffff888072f80278 (&c->state_lock){++++}-{4:4}, at: __bch2_fs_stop+0xf8/0x900 [ 288.256692][ T31] 2 locks held by bch-copygc/loop/5907: [ 288.262278][ T31] #0: ffff88806c404398 (&c->btree_trans_barrier){.+.+}-{0:0}, at: bch2_trans_srcu_lock+0xaf/0x220 [ 288.273047][ T31] #1: ffff88806c426710 (&c->gc_lock){.+.+}-{4:4}, at: bch2_btree_update_start+0x542/0x1de0 [ 288.283184][ T31] 2 locks held by bch-copygc/loop/5910: [ 288.288743][ T31] #0: ffff888072f84398 (&c->btree_trans_barrier){.+.+}-{0:0}, at: bch2_trans_srcu_lock+0xaf/0x220 [ 288.299487][ T31] #1: ffff888072fa6710 (&c->gc_lock){.+.+}-{4:4}, at: bch2_btree_update_start+0x542/0x1de0 [ 288.309639][ T31] 2 locks held by bch-copygc/loop/5913: [ 288.315190][ T31] #0: ffff88806c504398 (&c->btree_trans_barrier){.+.+}-{0:0}, at: bch2_trans_srcu_lock+0xaf/0x220 [ 288.325936][ T31] #1: ffff88806c526710 (&c->gc_lock){.+.+}-{4:4}, at: bch2_btree_update_start+0x542/0x1de0 [ 288.336107][ T31] 2 locks held by bch-copygc/loop/5916: [ 288.341752][ T31] #0: ffff88806c584398 (&c->btree_trans_barrier){.+.+}-{0:0}, at: bch2_trans_srcu_lock+0xaf/0x220 [ 288.352521][ T31] #1: ffff88806c5a6710 (&c->gc_lock){.+.+}-{4:4}, at: bch2_btree_update_start+0x542/0x1de0 [ 288.362699][ T31] 2 locks held by bch-copygc/loop/5919: [ 288.368243][ T31] #0: ffff88806c484398 (&c->btree_trans_barrier){.+.+}-{0:0}, at: bch2_trans_srcu_lock+0xaf/0x220 [ 288.379130][ T31] #1: ffff88806c4a6710 (&c->gc_lock){.+.+}-{4:4}, at: bch2_btree_update_start+0x542/0x1de0 [ 288.389372][ T31] [ 288.391744][ T31] ============================================= [ 288.391744][ T31] [ 288.400190][ T31] NMI backtrace for cpu 0 [ 288.400206][ T31] CPU: 0 UID: 0 PID: 31 Comm: khungtaskd Not tainted 6.16.0-rc3-next-20250626-syzkaller #0 PREEMPT(full) [ 288.400221][ T31] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 05/07/2025 [ 288.400229][ T31] Call Trace: [ 288.400234][ T31] [ 288.400240][ T31] dump_stack_lvl+0x189/0x250 [ 288.400262][ T31] ? __wake_up_klogd+0xd9/0x110 [ 288.400276][ T31] ? __pfx_dump_stack_lvl+0x10/0x10 [ 288.400296][ T31] ? __pfx__printk+0x10/0x10 [ 288.400324][ T31] nmi_cpu_backtrace+0x39e/0x3d0 [ 288.400342][ T31] ? __pfx_nmi_cpu_backtrace+0x10/0x10 [ 288.400356][ T31] ? _printk+0xcf/0x120 [ 288.400372][ T31] ? __pfx__printk+0x10/0x10 [ 288.400387][ T31] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10 [ 288.400405][ T31] nmi_trigger_cpumask_backtrace+0x17a/0x300 [ 288.400422][ T31] watchdog+0xfee/0x1030 [ 288.400438][ T31] ? watchdog+0x1de/0x1030 [ 288.400457][ T31] kthread+0x711/0x8a0 [ 288.400472][ T31] ? __pfx_watchdog+0x10/0x10 [ 288.400485][ T31] ? __pfx_kthread+0x10/0x10 [ 288.400498][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 288.400515][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 288.400532][ T31] ? __pfx_kthread+0x10/0x10 [ 288.400545][ T31] ret_from_fork+0x3fc/0x770 [ 288.400564][ T31] ? __pfx_ret_from_fork+0x10/0x10 [ 288.400584][ T31] ? __switch_to_asm+0x39/0x70 [ 288.400596][ T31] ? __switch_to_asm+0x33/0x70 [ 288.400607][ T31] ? __pfx_kthread+0x10/0x10 [ 288.400620][ T31] ret_from_fork_asm+0x1a/0x30 [ 288.400642][ T31] [ 288.400647][ T31] Sending NMI from CPU 0 to CPUs 1: [ 288.556463][ C1] NMI backtrace for cpu 1 [ 288.556481][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Not tainted 6.16.0-rc3-next-20250626-syzkaller #0 PREEMPT(full) [ 288.556516][ C1] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 05/07/2025 [ 288.556526][ C1] RIP: 0010:pv_native_safe_halt+0x13/0x20 [ 288.556562][ C1] Code: cc cc cc cc cc cc cc 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 66 90 0f 00 2d 63 49 19 00 f3 0f 1e fa fb f4 cc cc cc cc cc cc cc cc cc cc cc cc 90 90 90 90 90 90 90 90 90 [ 288.556577][ C1] RSP: 0018:ffffc90000197de0 EFLAGS: 000002c2 [ 288.556611][ C1] RAX: ee04a213eb698a00 RBX: ffffffff8196c478 RCX: ee04a213eb698a00 [ 288.556625][ C1] RDX: 0000000000000001 RSI: ffffffff8d992b0c RDI: ffffffff8be32080 [ 288.556638][ C1] RBP: ffffc90000197f20 R08: ffff8880b8732f1b R09: 1ffff110170e65e3 [ 288.556652][ C1] R10: dffffc0000000000 R11: ffffed10170e65e4 R12: ffffffff8fa19330 [ 288.556665][ C1] R13: 0000000000000001 R14: 0000000000000001 R15: 1ffff110039d5b40 [ 288.556678][ C1] FS: 0000000000000000(0000) GS:ffff888125d1f000(0000) knlGS:0000000000000000 [ 288.556692][ C1] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 288.556705][ C1] CR2: 000055735c162168 CR3: 000000000df36000 CR4: 00000000003526f0 [ 288.556721][ C1] Call Trace: [ 288.556729][ C1] [ 288.556736][ C1] default_idle+0x13/0x20 [ 288.556753][ C1] default_idle_call+0x74/0xb0 [ 288.556770][ C1] do_idle+0x1e8/0x510 [ 288.556799][ C1] ? __pfx_do_idle+0x10/0x10 [ 288.556823][ C1] ? _raw_spin_unlock_irqrestore+0x85/0x110 [ 288.556858][ C1] ? lockdep_hardirqs_on+0x9c/0x150 [ 288.556882][ C1] ? _raw_spin_unlock_irqrestore+0xad/0x110 [ 288.556904][ C1] ? __pfx__raw_spin_unlock_irqrestore+0x10/0x10 [ 288.556932][ C1] cpu_startup_entry+0x44/0x60 [ 288.556956][ C1] start_secondary+0x101/0x110 [ 288.556977][ C1] common_startup_64+0x13e/0x147 [ 288.557002][ C1] [ 288.557461][ T31] Kernel panic - not syncing: hung_task: blocked tasks [ 288.748686][ T31] CPU: 0 UID: 0 PID: 31 Comm: khungtaskd Not tainted 6.16.0-rc3-next-20250626-syzkaller #0 PREEMPT(full) [ 288.759968][ T31] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 05/07/2025 [ 288.770022][ T31] Call Trace: [ 288.773301][ T31] [ 288.776233][ T31] dump_stack_lvl+0x99/0x250 [ 288.780843][ T31] ? __asan_memcpy+0x40/0x70 [ 288.785436][ T31] ? __pfx_dump_stack_lvl+0x10/0x10 [ 288.790647][ T31] ? __pfx__printk+0x10/0x10 [ 288.795250][ T31] panic+0x2db/0x790 [ 288.799181][ T31] ? __pfx_panic+0x10/0x10 [ 288.803617][ T31] ? nmi_backtrace_stall_check+0x433/0x440 [ 288.809428][ T31] ? preempt_schedule_thunk+0x16/0x30 [ 288.814817][ T31] ? nmi_trigger_cpumask_backtrace+0x2b6/0x300 [ 288.820970][ T31] watchdog+0x102d/0x1030 [ 288.825302][ T31] ? watchdog+0x1de/0x1030 [ 288.829751][ T31] kthread+0x711/0x8a0 [ 288.833820][ T31] ? __pfx_watchdog+0x10/0x10 [ 288.838491][ T31] ? __pfx_kthread+0x10/0x10 [ 288.843078][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 288.848279][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 288.853479][ T31] ? __pfx_kthread+0x10/0x10 [ 288.858071][ T31] ret_from_fork+0x3fc/0x770 [ 288.862660][ T31] ? __pfx_ret_from_fork+0x10/0x10 [ 288.867778][ T31] ? __switch_to_asm+0x39/0x70 [ 288.872533][ T31] ? __switch_to_asm+0x33/0x70 [ 288.877286][ T31] ? __pfx_kthread+0x10/0x10 [ 288.881870][ T31] ret_from_fork_asm+0x1a/0x30 [ 288.886635][ T31] [ 288.890014][ T31] Kernel Offset: disabled [ 288.894334][ T31] Rebooting in 86400 seconds..