./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor3006984561 <...> Warning: Permanently added '10.128.0.193' (ED25519) to the list of known hosts. execve("./syz-executor3006984561", ["./syz-executor3006984561"], 0x7ffdbd6988d0 /* 10 vars */) = 0 brk(NULL) = 0x55555ecda000 brk(0x55555ecdad40) = 0x55555ecdad40 arch_prctl(ARCH_SET_FS, 0x55555ecda3c0) = 0 set_tid_address(0x55555ecda690) = 5772 set_robust_list(0x55555ecda6a0, 24) = 0 rseq(0x55555ecdace0, 0x20, 0, 0x53053053) = 0 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 readlink("/proc/self/exe", "/root/syz-executor3006984561", 4096) = 28 getrandom("\x56\x9e\xf6\xdb\x29\x15\x06\x9c", 8, GRND_NONBLOCK) = 8 brk(NULL) = 0x55555ecdad40 brk(0x55555ecfbd40) = 0x55555ecfbd40 brk(0x55555ecfc000) = 0x55555ecfc000 mprotect(0x7fb088cf2000, 16384, PROT_READ) = 0 mmap(0x3ffffffff000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3ffffffff000 mmap(0x400000000000, 16777216, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x400000000000 mmap(0x400001000000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x400001000000 chmod("/dev/raw-gadget", 0666) = 0 mkdir("./syzkaller.vmHUeW", 0700) = 0 chmod("./syzkaller.vmHUeW", 0777) = 0 chdir("./syzkaller.vmHUeW") = 0 unshare(CLONE_NEWPID) = 0 clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x55555ecda690) = 5773 ./strace-static-x86_64: Process 5773 attached [pid 5773] set_robust_list(0x55555ecda6a0, 24) = 0 [pid 5773] socket(AF_BLUETOOTH, SOCK_RAW, BTPROTO_HCI) = 3 [pid 5773] openat(AT_FDCWD, "/dev/vhci", O_RDWR) = 4 [pid 5773] dup2(4, 202) = 202 [pid 5773] close(4) = 0 [pid 5773] write(202, "\xff\x00", 2) = 2 [pid 5773] read(202, "\xff\x00\x00\x00", 4) = 4 [pid 5773] rt_sigaction(SIGRT_1, {sa_handler=0x7fb088c9aa40, sa_mask=[], sa_flags=SA_RESTORER|SA_ONSTACK|SA_RESTART|SA_SIGINFO, sa_restorer=0x7fb088c8c380}, NULL, 8) = 0 [pid 5773] rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0 [pid 5773] mmap(NULL, 8392704, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7fb08841f000 [pid 5773] mprotect(0x7fb088420000, 8388608, PROT_READ|PROT_WRITE) = 0 [pid 5773] rt_sigprocmask(SIG_BLOCK, ~[], [], 8) = 0 [pid 5773] clone3({flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, child_tid=0x7fb088c1f990, parent_tid=0x7fb088c1f990, exit_signal=0, stack=0x7fb08841f000, stack_size=0x800300, tls=0x7fb088c1f6c0}./strace-static-x86_64: Process 5775 attached => {parent_tid=[2]}, 88) = 2 [pid 5775] rseq(0x7fb088c1ffe0, 0x20, 0, 0x53053053 [pid 5773] rt_sigprocmask(SIG_SETMASK, [], [pid 5775] <... rseq resumed>) = 0 [pid 5775] set_robust_list(0x7fb088c1f9a0, 24 [pid 5773] <... rt_sigprocmask resumed>NULL, 8) = 0 [pid 5775] <... set_robust_list resumed>) = 0 [pid 5773] ioctl(3, HCIDEVUP [pid 5775] rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 [pid 5775] read(202, "\x01\x03\x0c\x00", 1024) = 4 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\xfc", iov_len=2}, {iov_base="\x01\x03\x0c", iov_len=3}, {iov_base="\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., iov_len=249}], 4) = 255 [pid 5775] read(202, "\x01\x03\x10\x00", 1024) = 4 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\xfc", iov_len=2}, {iov_base="\x01\x03\x10", iov_len=3}, {iov_base="\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., iov_len=249}], 4) = 255 [pid 5775] read(202, "\x01\x01\x10\x00", 1024) = 4 [ 206.860915][ T52] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1 [ 206.873585][ T52] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\xfc", iov_len=2}, {iov_base="\x01\x01\x10", iov_len=3}, {iov_base="\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., iov_len=249}], 4) = 255 [pid 5775] read(202, "\x01\x09\x10\x00", 1024) = 4 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\x0a", iov_len=2}, {iov_base="\x01\x09\x10", iov_len=3}, {iov_base="\x00\xaa\xaa\xaa\xaa\xaa\xaa", iov_len=7}], 4) = 13 [pid 5775] read(202, "\x01\x05\x10\x00", 1024) = 4 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\x0b", iov_len=2}, {iov_base="\x01\x05\x10", iov_len=3}, {iov_base="\x00\xfd\x03\x60\x04\x00\x06\x00", iov_len=8}], 4) = 14 [pid 5775] read(202, "\x01\x23\x0c\x00", 1024) = 4 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\xfc", iov_len=2}, {iov_base="\x01\x23\x0c", iov_len=3}, {iov_base="\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., iov_len=249}], 4) = 255 [pid 5775] read(202, "\x01\x14\x0c\x00", 1024) = 4 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\xfc", iov_len=2}, {iov_base="\x01\x14\x0c", iov_len=3}, {iov_base="\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., iov_len=249}], 4) = 255 [pid 5775] read(202, "\x01\x25\x0c\x00", 1024) = 4 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\xfc", iov_len=2}, {iov_base="\x01\x25\x0c", iov_len=3}, {iov_base="\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., iov_len=249}], 4) = 255 [pid 5775] read(202, "\x01\x38\x0c\x00", 1024) = 4 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\xfc", iov_len=2}, {iov_base="\x01\x38\x0c", iov_len=3}, {iov_base="\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., iov_len=249}], 4) = 255 [ 206.901134][ T52] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9 [ 206.923724][ T52] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4 [ 206.938783][ T52] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3 [pid 5775] read(202, "\x01\x39\x0c\x00", 1024) = 4 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\xfc", iov_len=2}, {iov_base="\x01\x39\x0c", iov_len=3}, {iov_base="\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., iov_len=249}], 4) = 255 [pid 5775] read(202, "\x01\x16\x0c\x02\x00\x7d", 1024) = 6 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\xfc", iov_len=2}, {iov_base="\x01\x16\x0c", iov_len=3}, {iov_base="\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., iov_len=249}], 4) = 255 [pid 5773] <... ioctl resumed>, 0) = -1 EALREADY (Operation already in progress) [pid 5773] ioctl(3, HCISETSCAN [pid 5775] read(202, "\x01\x1a\x0c\x01\x02", 1024) = 5 [pid 5775] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x0e\x04", iov_len=2}, {iov_base="\x01\x1a\x0c", iov_len=3}, {iov_base="\x00", iov_len=1}], 4) = 7 [pid 5775] rt_sigprocmask(SIG_BLOCK, ~[RT_1], NULL, 8) = 0 [pid 5773] <... ioctl resumed>, 0x7fff6d32b130) = 0 [pid 5775] madvise(0x7fb08841f000, 8372224, MADV_DONTNEED [pid 5773] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x04\x0a", iov_len=2}, {iov_base="\xaa\xaa\xaa\xaa\xaa\x10\x00\x00\x00\x01", iov_len=10}], 3 [pid 5775] <... madvise resumed>) = 0 [pid 5773] <... writev resumed>) = 13 [pid 5775] exit(0) = ? [pid 5775] +++ exited with 0 +++ [pid 5773] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x03\x0b", iov_len=2}, {iov_base="\x00\xc8\x00\xaa\xaa\xaa\xaa\xaa\x10\x01\x00", iov_len=11}], 3) = 14 [pid 5773] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\v\v", iov_len=2}, {iov_base="\x00\xc8\x00\x00\x00\x00\x00\x00\x00\x00\x00", iov_len=11}], 3) = 14 [pid 5773] writev(202, [{iov_base="\x04", iov_len=1}, {iov_base="\x3e\x13", iov_len=2}, {iov_base="\x01\x00\xc9\x00\x01\x00\xaa\xaa\xaa\xaa\xaa\x11\x00\x00\x00\x00\x00\x00\x00", iov_len=19}], 3) = 22 [pid 5773] close(3) = 0 [pid 5773] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5773] getppid() = 0 [pid 5773] prlimit64(0, RLIMIT_AS, {rlim_cur=204800*1024, rlim_max=204800*1024}, NULL) = 0 [pid 5773] prlimit64(0, RLIMIT_MEMLOCK, {rlim_cur=32768*1024, rlim_max=32768*1024}, NULL) = 0 [pid 5773] prlimit64(0, RLIMIT_FSIZE, {rlim_cur=139264*1024, rlim_max=139264*1024}, NULL) = 0 [pid 5773] prlimit64(0, RLIMIT_STACK, {rlim_cur=1024*1024, rlim_max=1024*1024}, NULL) = 0 [pid 5773] prlimit64(0, RLIMIT_CORE, {rlim_cur=131072*1024, rlim_max=131072*1024}, NULL) = 0 [pid 5773] prlimit64(0, RLIMIT_NOFILE, {rlim_cur=256, rlim_max=256}, NULL) = 0 [ 206.950271][ T52] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2 [pid 5773] unshare(CLONE_NEWNS) = 0 [pid 5773] mount(NULL, "/", NULL, MS_REC|MS_PRIVATE, NULL) = 0 [pid 5773] unshare(CLONE_NEWIPC) = 0 [pid 5773] unshare(CLONE_NEWCGROUP) = 0 [pid 5773] unshare(CLONE_NEWUTS) = 0 [pid 5773] unshare(CLONE_SYSVSEM) = 0 [pid 5773] openat(AT_FDCWD, "/proc/sys/kernel/shmmax", O_WRONLY|O_CLOEXEC) = 3 [pid 5773] write(3, "16777216", 8) = 8 [pid 5773] close(3) = 0 [pid 5773] openat(AT_FDCWD, "/proc/sys/kernel/shmall", O_WRONLY|O_CLOEXEC) = 3 [pid 5773] write(3, "536870912", 9) = 9 [pid 5773] close(3) = 0 [pid 5773] openat(AT_FDCWD, "/proc/sys/kernel/shmmni", O_WRONLY|O_CLOEXEC) = 3 [pid 5773] write(3, "1024", 4) = 4 [pid 5773] close(3) = 0 [pid 5773] openat(AT_FDCWD, "/proc/sys/kernel/msgmax", O_WRONLY|O_CLOEXEC) = 3 [pid 5773] write(3, "8192", 4) = 4 [pid 5773] close(3) = 0 [pid 5773] openat(AT_FDCWD, "/proc/sys/kernel/msgmni", O_WRONLY|O_CLOEXEC) = 3 [pid 5773] write(3, "1024", 4) = 4 [pid 5773] close(3) = 0 [pid 5773] openat(AT_FDCWD, "/proc/sys/kernel/msgmnb", O_WRONLY|O_CLOEXEC) = 3 [pid 5773] write(3, "1024", 4) = 4 [pid 5773] close(3) = 0 [pid 5773] openat(AT_FDCWD, "/proc/sys/kernel/sem", O_WRONLY|O_CLOEXEC) = 3 [pid 5773] write(3, "1024 1048576 500 1024", 21) = 21 [pid 5773] close(3) = 0 [pid 5773] getpid() = 1 [pid 5773] capget({version=_LINUX_CAPABILITY_VERSION_3, pid=1}, {effective=1< u64s 11 type alloc_v4 0:2:0 len 0 ver 0, fixing [ 208.961090][ T5780] bcachefs (loop0): btree_node_read_work: rewriting btree node at due to error [ 208.961090][ T5780] btree=alloc level=0 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 [ 208.985615][ T5076] Bluetooth: hci0: command tx timeout [ 209.019729][ T5780] bcachefs (loop0): scan_for_btree_nodes... [ 209.114375][ T5780] bcachefs (loop0): btree node scan found 7 nodes after overwrites [ 209.128708][ T5780] done [ 209.131598][ T5780] bcachefs (loop0): check_topology... [ 209.134255][ T5780] bcachefs (loop0): btree root extents unreadable, must recover from scan [ 209.149038][ T5780] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=extents level=0 POS_MIN - SPOS_MAX [ 209.160575][ T5780] bcachefs (loop0): bch2_get_scanned_nodes(): recovering u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 209.184341][ T9] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 209.184411][ T9] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 209.184484][ T9] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 209.217966][ T9] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 209.218065][ T9] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 209.218173][ T9] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 209.253268][ T9] bcachefs (loop0): Unable to continue, halting [ 209.262803][ T5780] Topology repair: unreadable btree node at [ 209.262895][ T5780] btree=extents level=0 u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0, fixing [ 209.288257][ T5780] empty interior btree node at btree=extents level=1 [ 209.288315][ T5780] u64s 5 type btree_ptr SPOS_MAX len 0 ver 0, fixing [ 209.302274][ T5780] bcachefs (loop0): empty btree root extents [ 209.310063][ T5780] bcachefs (loop0): btree root dirents unreadable, must recover from scan [ 209.319551][ T5780] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=dirents level=0 POS_MIN - SPOS_MAX [ 209.330833][ T5780] bcachefs (loop0): bch2_get_scanned_nodes(): recovering 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 [ 209.357155][ T5780] done [ 209.360072][ T5780] bcachefs (loop0): accounting_read... done [ 209.367091][ T5780] bcachefs (loop0): alloc_read... done [ 209.374054][ T5780] bcachefs (loop0): stripes_read... done [ 209.380008][ T5780] bcachefs (loop0): snapshots_read... done [ 209.388670][ T5780] bcachefs (loop0): scanning for old btree nodes: min_version 0.11: inode_btree_change [ 209.398635][ T5780] bcachefs (loop0): going read-write [ 209.453131][ T4225] bucket 0:26 gen 0 data type btree sector count overflow: 0 + -256 > U32_MAX [ 209.453236][ T4225] 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, shutting down [ 209.480189][ T4225] bcachefs (loop0): inconsistency detected - emergency read only at journal seq 1688849860263947 [ 209.480433][ T52] bcachefs (loop0): unable to write journal to sufficient devices [ 209.499833][ T4225] 2 transaction updates for btree_update_nodes_written journal seq 0 [ 209.499924][ T4225] update: btree=alloc cached=0 bch2_trans_start_alloc_update+0x12cc/0x14b0 [ 209.500008][ T4225] old u64s 5 type deleted 0:26:0 len 0 ver 0 [ 209.500081][ T4225] new u64s 13 type alloc_v4 0:26:0 len 0 ver 0: [ 209.500162][ T4225] gen 0 oldest_gen 0 data_type free [ 209.500222][ T4225] journal_seq_nonempty 0 [ 209.500264][ T4225] journal_seq_empty 0 [ 209.500305][ T4225] need_discard 0 [ 209.500346][ T4225] need_inc_gen 0 [ 209.500387][ T4225] dirty_sectors 0 [ 209.500428][ T4225] stripe_sectors 0 [ 209.500470][ T4225] cached_sectors 0 [ 209.500511][ T4225] stripe 0 [ 209.500552][ T4225] stripe_redundancy 0 [ 209.500593][ T4225] io_time[READ] 0 [ 209.500635][ T4225] io_time[WRITE] 0 [ 209.500675][ T4225] fragmentation 0 [ 209.500715][ T4225] bp_start 8 [ 209.500754][ T4225] [ 209.500792][ T4225] update: btree=alloc cached=1 bch2_trans_start_alloc_update+0x12cc/0x14b0 [ 209.500846][ T4225] old u64s 5 type deleted 0:26:0 len 0 ver 0 [ 209.500897][ T4225] new u64s 13 type alloc_v4 0:26:0 len 0 ver 0: [ 209.500944][ T4225] gen 0 oldest_gen 0 data_type free [ 209.500987][ T4225] journal_seq_nonempty 0 [ 209.501028][ T4225] journal_seq_empty 0 [ 209.501069][ T4225] need_discard 0 [ 209.501110][ T4225] need_inc_gen 0 [ 209.501157][ T4225] dirty_sectors 0 [ 209.501199][ T4225] stripe_sectors 0 [ 209.501240][ T4225] cached_sectors 0 [ 209.501281][ T4225] stripe 0 [ 209.501322][ T4225] stripe_redundancy 0 [ 209.501364][ T4225] io_time[READ] 0 [ 209.501405][ T4225] io_time[WRITE] 0 [ 209.501446][ T4225] fragmentation 0 [ 209.501486][ T4225] bp_start 8 [ 209.501525][ T4225] [ 209.501571][ T4225] btree_root: btree=alloc level=0 u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 4ec7817d80e96728 written 8 min_key POS_MIN durability: 1 ptr: 0:30:0 gen 0 [ 209.732561][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.741416][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.750229][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.759009][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.767925][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.776615][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.785437][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.794387][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.803164][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.812044][ T5780] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 209.822211][ T5780] bcachefs (loop0): bch2_move_btree(): error journal_shutdown [ 209.830061][ T5780] bcachefs (loop0): bch2_scan_old_btree_nodes(): error journal_shutdown [ 209.839436][ T5780] bcachefs (loop0): bch2_fs_recovery(): error journal_shutdown [ 209.847276][ T5780] bcachefs (loop0): bch2_fs_start(): error starting filesystem journal_shutdown [ 209.856835][ T5780] bcachefs (loop0): shutting down [ 209.856947][ T8] bcachefs (loop0): going read-only [ 209.867694][ T8] bcachefs (loop0): finished waiting for writes to stop [ 209.875061][ T8] bcachefs (loop0): flushing journal and stopping allocators, journal seq 1688849860263947 [ 209.885442][ T8] bcachefs (loop0): flushing journal and stopping allocators complete, journal seq 1688849860263947 [ 209.896831][ T8] bcachefs (loop0): unclean shutdown complete, journal seq 1688849860263947 [ 209.906506][ T8] bcachefs (loop0): done going read-only, filesystem not clean [ 209.928385][ T5780] bcachefs (loop0): shutdown complete [pid 5780] mount("/dev/loop0", "./file1", "bcachefs", MS_NOSUID|MS_I_VERSION, "norecovery,recovery_pass_last=check_lrus,journal_flush_disabled,fsck,background_compression=lz4,rate"...) = -1 EIO (Input/output error) [pid 5780] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 [pid 5780] ioctl(3, LOOP_CLR_FD) = 0 [ 210.697585][ T5780] bcachefs: bch2_fs_get_tree() error: journal_shutdown [pid 5780] close(3) = 0 [pid 5780] close(3) = -1 EBADF (Bad file descriptor) [pid 5780] close(4) = -1 EBADF (Bad file descriptor) [pid 5780] close(5) = -1 EBADF (Bad file descriptor) [pid 5780] close(6) = -1 EBADF (Bad file descriptor) [pid 5780] close(7) = -1 EBADF (Bad file descriptor) [pid 5780] close(8) = -1 EBADF (Bad file descriptor) [pid 5780] close(9) = -1 EBADF (Bad file descriptor) [pid 5780] close(10) = -1 EBADF (Bad file descriptor) [pid 5780] close(11) = -1 EBADF (Bad file descriptor) [pid 5780] close(12) = -1 EBADF (Bad file descriptor) [pid 5780] close(13) = -1 EBADF (Bad file descriptor) [pid 5780] close(14) = -1 EBADF (Bad file descriptor) [pid 5780] close(15) = -1 EBADF (Bad file descriptor) [pid 5780] close(16) = -1 EBADF (Bad file descriptor) [pid 5780] close(17) = -1 EBADF (Bad file descriptor) [pid 5780] close(18) = -1 EBADF (Bad file descriptor) [pid 5780] close(19) = -1 EBADF (Bad file descriptor) [pid 5780] close(20) = -1 EBADF (Bad file descriptor) [pid 5780] close(21) = -1 EBADF (Bad file descriptor) [pid 5780] close(22) = -1 EBADF (Bad file descriptor) [pid 5780] close(23) = -1 EBADF (Bad file descriptor) [pid 5780] close(24) = -1 EBADF (Bad file descriptor) [pid 5780] close(25) = -1 EBADF (Bad file descriptor) [pid 5780] close(26) = -1 EBADF (Bad file descriptor) [pid 5780] close(27) = -1 EBADF (Bad file descriptor) [pid 5780] close(28) = -1 EBADF (Bad file descriptor) [pid 5780] close(29) = -1 EBADF (Bad file descriptor) [pid 5780] exit_group(0) = ? [ 211.062010][ T52] Bluetooth: hci0: command tx timeout [pid 5780] +++ exited with 0 +++ [pid 5773] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=3, si_uid=0, si_status=0, si_utime=4 /* 0.04 s */, si_stime=187 /* 1.87 s */} --- [pid 5773] restart_syscall(<... resuming interrupted clone ...>) = 0 [pid 5773] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5773] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=80, ...}, AT_EMPTY_PATH) = 0 [pid 5773] getdents64(3, 0x55555ecdb850 /* 4 entries */, 32768) = 112 [pid 5773] umount2("./0/file1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] newfstatat(AT_FDCWD, "./0/file1", {st_mode=S_IFDIR|0700, st_size=40, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5773] umount2("./0/file1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] openat(AT_FDCWD, "./0/file1", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 4 [pid 5773] newfstatat(4, "", {st_mode=S_IFDIR|0700, st_size=40, ...}, AT_EMPTY_PATH) = 0 [pid 5773] getdents64(4, 0x55555ece3890 /* 2 entries */, 32768) = 48 [pid 5773] getdents64(4, 0x55555ece3890 /* 0 entries */, 32768) = 0 [pid 5773] close(4) = 0 [pid 5773] rmdir("./0/file1") = 0 [pid 5773] umount2("./0/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] newfstatat(AT_FDCWD, "./0/binderfs", {st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5773] unlink("./0/binderfs") = 0 [pid 5773] getdents64(3, 0x55555ecdb850 /* 0 entries */, 32768) = 0 [pid 5773] close(3) = 0 [pid 5773] rmdir("./0") = 0 [pid 5773] mkdir("./1", 0777) = 0 [pid 5773] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 [pid 5773] ioctl(3, LOOP_CLR_FD) = -1 ENXIO (No such device or address) [ 211.115004][ T5780] syz-executor300 (5780) used greatest stack depth: 784 bytes left [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [264]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x19\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x19\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [264]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x62\x72\x6f\x75\x74\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x20\x00\x00\x00\x00\x00\x00\x00\x30\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x62\x72\x6f\x75\x74\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x20\x00\x00\x00\x00\x00\x00\x00\x30\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [168]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, ARPT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x07\x00\x00\x00\x00\x00\x00\x00\xe8\x00\x00\x00\xd0\x01\x00\x00\x00\x00\x00\x00\xe8\x00\x00\x00\xd0\x01\x00\x00\x04\x00\x00\x00\xb8\x03\x00\x00", [68]) = 0 [pid 5773] getsockopt(3, SOL_IP, ARPT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xb8\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [992]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x78\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x78\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [672]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1b\x00\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\xff\xff\xff\xff\x30\x01\x00\x00\xc8\x01\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\xff\xff\xff\xff\x30\x01\x00\x00\xc8\x01\x00\x00\x05\x00\x00\x00\x10\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x10\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [824]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xc8\x01\x00\x00\x60\x02\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xc8\x01\x00\x00\x60\x02\x00\x00\x06\x00\x00\x00\xa8\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xa8\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [976]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x98\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x98\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\xe0\x01\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xe0\x01\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [520]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x78\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x78\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [672]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET6, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x58\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x58\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [896]) = 0 [pid 5773] setsockopt(3, SOL_IPV6, IP6T_SO_SET_REPLACE, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x04\x00\x00\x00\x58\x03\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x00\xa8\x32\x6d\xff\x7f\x00\x00\x00\x00\x00\x00"..., 952) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1b\x00\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xff\xff\xff\xff\xa0\x01\x00\x00\x70\x02\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xff\xff\xff\xff\xa0\x01\x00\x00\x70\x02\x00\x00\x05\x00\x00\x00\x28\x04\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x28\x04\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [1104]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x06\x00\x00\x00\xf8\x04\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xf8\x04\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [1312]) = 0 [pid 5773] setsockopt(3, SOL_IPV6, IP6T_SO_SET_REPLACE, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x06\x00\x00\x00\xf8\x04\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x06\x00\x00\x00\x00\xa8\x32\x6d\xff\x7f\x00\x00\x00\x00\x00\x00"..., 1368) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\x88\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x88\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [688]) = 0 [pid 5773] setsockopt(3, SOL_IPV6, IP6T_SO_SET_REPLACE, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x03\x00\x00\x00\x88\x02\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\x00\xa8\x32\x6d\xff\x7f\x00\x00\x00\x00\x00\x00"..., 744) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x58\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x58\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [896]) = 0 [pid 5773] setsockopt(3, SOL_IPV6, IP6T_SO_SET_REPLACE, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x04\x00\x00\x00\x58\x03\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x00\xa8\x32\x6d\xff\x7f\x00\x00\x00\x00\x00\x00"..., 952) = 0 [pid 5773] close(3) = 0 [pid 5773] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5790 attached , child_tidptr=0x55555ecda690) = 4 [pid 5790] set_robust_list(0x55555ecda6a0, 24) = 0 [pid 5790] chdir("./1") = 0 [pid 5790] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5790] setpgid(0, 0) = 0 [pid 5790] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5790] write(3, "1000", 4) = 4 [pid 5790] close(3) = 0 [pid 5790] symlink("/dev/binderfs", "./binderfs"executing program ) = 0 [pid 5790] write(1, "executing program\n", 18) = 18 [pid 5790] prlimit64(0, RLIMIT_RTPRIO, {rlim_cur=8, rlim_max=139}, NULL) = 0 [pid 5790] sched_setscheduler(0, SCHED_FIFO, [7]) = 0 [pid 5790] memfd_create("syzkaller", 0) = 3 [pid 5790] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fb080000000 [pid 5790] 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) = 16777216 [pid 5790] munmap(0x7fb080000000, 138412032) = 0 [pid 5790] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [pid 5790] ioctl(4, LOOP_SET_FD, 3) = 0 [pid 5790] close(3) = 0 [pid 5790] close(4) = 0 [pid 5790] mkdir("./file1", 0777) = 0 [ 211.842728][ T5790] loop0: detected capacity change from 0 to 32768 [ 211.974949][ T5790] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,journal_flush_disabled,fsck,fix_errors=ask,norecovery,recovery_pass_last=check_lrus,nojournal_transaction_names [ 212.001216][ T5790] bcachefs (loop0): recovering from clean shutdown, journal seq 1688849860263946 [ 212.012161][ T5790] bcachefs (loop0): Version upgrade required: [ 212.012161][ T5790] Version upgrade from 0.24: unwritten_extents to 1.7: mi_btree_bitmap incomplete [ 212.012161][ T5790] Doing incompatible version upgrade from 0.24: unwritten_extents to 1.20: directory_size [ 212.012161][ T5790] running recovery passes: check_allocations,check_alloc_info,check_lrus,check_btree_backpointers,check_backpointers_to_extents,check_extents_to_backpointers,check_alloc_to_lru_refs,bucket_gens_init,check_snapshot_trees,check_snapshots,check_subvols,check_subvol_children,delete_dead_snapshots,check_inodes,check_extents,check_indirect_extents,check_dirents,check_xattrs,check_root,check_unreachable_inodes,check_subvolume_structure,check_directory_structure,check_nlinks,set_fs_needs_rebalance [ 212.090821][ T5790] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 212.090892][ T5790] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 212.090962][ T5790] node offset 0/16 bset u64s 0: bset version 11 older than superblock version_min 24, fix? [ 212.124334][ T5790] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 212.124409][ T5790] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 212.124482][ T5790] node offset 0/16 bset u64s 0: bset version 11 older than superblock version_min 24, fix? [ 212.157602][ T5790] bcachefs (loop0): Unable to continue, halting [ 212.164399][ T5790] bcachefs (loop0): flagging btree extents lost data [ 212.171272][ T5790] bcachefs (loop0): running explicit recovery pass check_topology (2), currently at recovery_pass_empty (0) [ 212.183019][ T5790] bcachefs (loop0): running explicit recovery pass scan_for_btree_nodes (1), currently at recovery_pass_empty (0) [ 212.196901][ T5790] error reading btree root btree=extents level=0: btree_node_read_error, fixing [ 212.219601][ T5790] bcachefs (loop0): error validating btree node on loop0 at btree dirents level 0/0 [ 212.219676][ T5790] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 101895aefce4bdf written 24 min_key POS_MIN durability: 1 ptr: 0:41:0 gen 0 [ 212.219745][ T5790] node offset 0/24: got wrong btree node: got [ 212.219800][ T5790] btree=dirents level=0 seq 9aa2895aefce4bdf 1 [ 212.219846][ T5790] min: POS_MIN [ 212.219885][ T5790] max: SPOS_MAX [ 212.263996][ T5790] bcachefs (loop0): flagging btree dirents lost data [ 212.272800][ T5790] error reading btree root btree=dirents level=0: btree_node_read_error, fixing [ 212.293266][ T5790] bcachefs (loop0): error validating btree node at btree alloc level 0/0 [ 212.293334][ T5790] 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 [ 212.293408][ T5790] node offset 8/24 bset u64s 375 bset byte offset 184: keys out of order: u64s 11 type alloc_v4 0:32:0 len 0 ver 0 > u64s 11 type alloc_v4 0:2:0 len 0 ver 0, fixing [ 212.334129][ T5790] bcachefs (loop0): btree_node_read_work: rewriting btree node at due to error [ 212.334129][ T5790] btree=alloc level=0 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 [ 212.388146][ T5790] bcachefs (loop0): scan_for_btree_nodes... [ 212.481173][ T5790] bcachefs (loop0): btree node scan found 7 nodes after overwrites [ 212.495569][ T5790] done [ 212.498464][ T5790] bcachefs (loop0): check_topology... [ 212.500477][ T5790] bcachefs (loop0): btree root extents unreadable, must recover from scan [ 212.515479][ T5790] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=extents level=0 POS_MIN - SPOS_MAX [ 212.527029][ T5790] bcachefs (loop0): bch2_get_scanned_nodes(): recovering u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 212.550634][ T9] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 212.550705][ T9] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 212.550776][ T9] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 212.584110][ T9] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 212.584210][ T9] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 212.584298][ T9] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 212.620211][ T9] bcachefs (loop0): Unable to continue, halting [ 212.629330][ T5790] Topology repair: unreadable btree node at [ 212.629394][ T5790] btree=extents level=0 u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0, fixing [ 212.655069][ T5790] empty interior btree node at btree=extents level=1 [ 212.655129][ T5790] u64s 5 type btree_ptr SPOS_MAX len 0 ver 0, fixing [ 212.669035][ T5790] bcachefs (loop0): empty btree root extents [ 212.677363][ T5790] bcachefs (loop0): btree root dirents unreadable, must recover from scan [ 212.686814][ T5790] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=dirents level=0 POS_MIN - SPOS_MAX [ 212.698093][ T5790] bcachefs (loop0): bch2_get_scanned_nodes(): recovering 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 [ 212.726250][ T5790] done [ 212.729168][ T5790] bcachefs (loop0): accounting_read... done [ 212.735980][ T5790] bcachefs (loop0): alloc_read... done [ 212.744185][ T5790] bcachefs (loop0): stripes_read... done [ 212.750154][ T5790] bcachefs (loop0): snapshots_read... done [ 212.758804][ T5790] bcachefs (loop0): scanning for old btree nodes: min_version 0.11: inode_btree_change [ 212.768876][ T5790] bcachefs (loop0): going read-write [ 212.818663][ T190] bucket 0:26 gen 0 data type btree sector count overflow: 0 + -256 > U32_MAX [ 212.818773][ T190] 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, shutting down [ 212.846777][ T190] bcachefs (loop0): inconsistency detected - emergency read only at journal seq 1688849860263947 [ 212.847195][ T52] bcachefs (loop0): unable to write journal to sufficient devices [ 212.865876][ T190] 2 transaction updates for btree_update_nodes_written journal seq 0 [ 212.865967][ T190] update: btree=alloc cached=0 bch2_trans_start_alloc_update+0x12cc/0x14b0 [ 212.866057][ T190] old u64s 5 type deleted 0:26:0 len 0 ver 0 [ 212.866129][ T190] new u64s 13 type alloc_v4 0:26:0 len 0 ver 0: [ 212.866202][ T190] gen 0 oldest_gen 0 data_type free [ 212.866264][ T190] journal_seq_nonempty 0 [ 212.866306][ T190] journal_seq_empty 0 [ 212.866347][ T190] need_discard 0 [ 212.866387][ T190] need_inc_gen 0 [ 212.866428][ T190] dirty_sectors 0 [ 212.866469][ T190] stripe_sectors 0 [ 212.866510][ T190] cached_sectors 0 [ 212.866550][ T190] stripe 0 [ 212.866591][ T190] stripe_redundancy 0 [ 212.866632][ T190] io_time[READ] 0 [ 212.866673][ T190] io_time[WRITE] 0 [ 212.866714][ T190] fragmentation 0 [ 212.866754][ T190] bp_start 8 [ 212.866792][ T190] [ 212.866832][ T190] update: btree=alloc cached=1 bch2_trans_start_alloc_update+0x12cc/0x14b0 [ 212.866885][ T190] old u64s 5 type deleted 0:26:0 len 0 ver 0 [ 212.866933][ T190] new u64s 13 type alloc_v4 0:26:0 len 0 ver 0: [ 212.866980][ T190] gen 0 oldest_gen 0 data_type free [ 212.867030][ T190] journal_seq_nonempty 0 [ 212.867071][ T190] journal_seq_empty 0 [ 212.867111][ T190] need_discard 0 [ 212.867151][ T190] need_inc_gen 0 [ 212.867192][ T190] dirty_sectors 0 [ 212.867232][ T190] stripe_sectors 0 [ 212.867272][ T190] cached_sectors 0 [ 212.867312][ T190] stripe 0 [ 212.867352][ T190] stripe_redundancy 0 [ 212.867393][ T190] io_time[READ] 0 [ 212.867433][ T190] io_time[WRITE] 0 [ 212.867473][ T190] fragmentation 0 [ 212.867512][ T190] bp_start 8 [ 212.867550][ T190] [ 212.867594][ T190] btree_root: btree=alloc level=0 u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c3343a73b60a2a2b written 8 min_key POS_MIN durability: 1 ptr: 0:30:0 gen 0 [ 213.097221][ T5790] bcachefs (loop0): bch2_move_btree(): error journal_shutdown [ 213.105064][ T5790] bcachefs (loop0): bch2_scan_old_btree_nodes(): error journal_shutdown [ 213.114438][ T5790] bcachefs (loop0): bch2_fs_recovery(): error journal_shutdown [ 213.122495][ T5790] bcachefs (loop0): bch2_fs_start(): error starting filesystem journal_shutdown [ 213.131893][ T5790] bcachefs (loop0): shutting down [ 213.132514][ T8] bcachefs (loop0): going read-only [ 213.142856][ T8] bcachefs (loop0): finished waiting for writes to stop [ 213.150067][ T8] bcachefs (loop0): flushing journal and stopping allocators, journal seq 1688849860263947 [ 213.160429][ T8] bcachefs (loop0): flushing journal and stopping allocators complete, journal seq 1688849860263947 [ 213.172104][ T8] bcachefs (loop0): unclean shutdown complete, journal seq 1688849860263947 [ 213.181575][ T8] bcachefs (loop0): done going read-only, filesystem not clean [ 213.193876][ T52] Bluetooth: hci0: command tx timeout [ 213.207144][ T5790] bcachefs (loop0): shutdown complete [pid 5790] mount("/dev/loop0", "./file1", "bcachefs", MS_NOSUID|MS_I_VERSION, "norecovery,recovery_pass_last=check_lrus,journal_flush_disabled,fsck,background_compression=lz4,rate"...) = -1 EIO (Input/output error) [pid 5790] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 [pid 5790] ioctl(3, LOOP_CLR_FD) = 0 [ 213.958014][ T5790] bcachefs: bch2_fs_get_tree() error: journal_shutdown [pid 5790] close(3) = 0 [pid 5790] close(3) = -1 EBADF (Bad file descriptor) [pid 5790] close(4) = -1 EBADF (Bad file descriptor) [pid 5790] close(5) = -1 EBADF (Bad file descriptor) [pid 5790] close(6) = -1 EBADF (Bad file descriptor) [pid 5790] close(7) = -1 EBADF (Bad file descriptor) [pid 5790] close(8) = -1 EBADF (Bad file descriptor) [pid 5790] close(9) = -1 EBADF (Bad file descriptor) [pid 5790] close(10) = -1 EBADF (Bad file descriptor) [pid 5790] close(11) = -1 EBADF (Bad file descriptor) [pid 5790] close(12) = -1 EBADF (Bad file descriptor) [pid 5790] close(13) = -1 EBADF (Bad file descriptor) [pid 5790] close(14) = -1 EBADF (Bad file descriptor) [pid 5790] close(15) = -1 EBADF (Bad file descriptor) [pid 5790] close(16) = -1 EBADF (Bad file descriptor) [pid 5790] close(17) = -1 EBADF (Bad file descriptor) [pid 5790] close(18) = -1 EBADF (Bad file descriptor) [pid 5790] close(19) = -1 EBADF (Bad file descriptor) [pid 5790] close(20) = -1 EBADF (Bad file descriptor) [pid 5790] close(21) = -1 EBADF (Bad file descriptor) [pid 5790] close(22) = -1 EBADF (Bad file descriptor) [pid 5790] close(23) = -1 EBADF (Bad file descriptor) [pid 5790] close(24) = -1 EBADF (Bad file descriptor) [pid 5790] close(25) = -1 EBADF (Bad file descriptor) [pid 5790] close(26) = -1 EBADF (Bad file descriptor) [pid 5790] close(27) = -1 EBADF (Bad file descriptor) [pid 5790] close(28) = -1 EBADF (Bad file descriptor) [pid 5790] close(29) = -1 EBADF (Bad file descriptor) [pid 5790] exit_group(0) = ? [pid 5790] +++ exited with 0 +++ [pid 5773] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=4, si_uid=0, si_status=0, si_utime=2 /* 0.02 s */, si_stime=176 /* 1.76 s */} --- [pid 5773] restart_syscall(<... resuming interrupted clone ...>) = 0 [pid 5773] umount2("./1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] openat(AT_FDCWD, "./1", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5773] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=80, ...}, AT_EMPTY_PATH) = 0 [pid 5773] getdents64(3, 0x55555ecdb850 /* 4 entries */, 32768) = 112 [pid 5773] umount2("./1/file1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] newfstatat(AT_FDCWD, "./1/file1", {st_mode=S_IFDIR|0700, st_size=40, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5773] umount2("./1/file1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] openat(AT_FDCWD, "./1/file1", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 4 [pid 5773] newfstatat(4, "", {st_mode=S_IFDIR|0700, st_size=40, ...}, AT_EMPTY_PATH) = 0 [pid 5773] getdents64(4, 0x55555ece3890 /* 2 entries */, 32768) = 48 [pid 5773] getdents64(4, 0x55555ece3890 /* 0 entries */, 32768) = 0 [pid 5773] close(4) = 0 [pid 5773] rmdir("./1/file1") = 0 [pid 5773] umount2("./1/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] newfstatat(AT_FDCWD, "./1/binderfs", {st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5773] unlink("./1/binderfs") = 0 [pid 5773] getdents64(3, 0x55555ecdb850 /* 0 entries */, 32768) = 0 [pid 5773] close(3) = 0 [pid 5773] rmdir("./1") = 0 [pid 5773] mkdir("./2", 0777) = 0 [pid 5773] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 [pid 5773] ioctl(3, LOOP_CLR_FD) = -1 ENXIO (No such device or address) [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [264]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x19\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x19\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [264]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x62\x72\x6f\x75\x74\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x20\x00\x00\x00\x00\x00\x00\x00\x30\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x62\x72\x6f\x75\x74\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x20\x00\x00\x00\x00\x00\x00\x00\x30\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [168]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, ARPT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x07\x00\x00\x00\x00\x00\x00\x00\xe8\x00\x00\x00\xd0\x01\x00\x00\x00\x00\x00\x00\xe8\x00\x00\x00\xd0\x01\x00\x00\x04\x00\x00\x00\xb8\x03\x00\x00", [68]) = 0 [pid 5773] getsockopt(3, SOL_IP, ARPT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xb8\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [992]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x78\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x78\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [672]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1b\x00\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\xff\xff\xff\xff\x30\x01\x00\x00\xc8\x01\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\xff\xff\xff\xff\x30\x01\x00\x00\xc8\x01\x00\x00\x05\x00\x00\x00\x10\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x10\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [824]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xc8\x01\x00\x00\x60\x02\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xc8\x01\x00\x00\x60\x02\x00\x00\x06\x00\x00\x00\xa8\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xa8\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [976]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x98\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x98\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\xe0\x01\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xe0\x01\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [520]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x78\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x78\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [672]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET6, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x58\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x58\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [896]) = 0 [pid 5773] setsockopt(3, SOL_IPV6, IP6T_SO_SET_REPLACE, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x04\x00\x00\x00\x58\x03\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x00\xa8\x32\x6d\xff\x7f\x00\x00\x00\x00\x00\x00"..., 952) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1b\x00\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xff\xff\xff\xff\xa0\x01\x00\x00\x70\x02\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xff\xff\xff\xff\xa0\x01\x00\x00\x70\x02\x00\x00\x05\x00\x00\x00\x28\x04\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x28\x04\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [1104]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x06\x00\x00\x00\xf8\x04\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xf8\x04\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [1312]) = 0 [pid 5773] setsockopt(3, SOL_IPV6, IP6T_SO_SET_REPLACE, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x06\x00\x00\x00\xf8\x04\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x06\x00\x00\x00\x00\xa8\x32\x6d\xff\x7f\x00\x00\x00\x00\x00\x00"..., 1368) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\x88\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x88\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [688]) = 0 [pid 5773] setsockopt(3, SOL_IPV6, IP6T_SO_SET_REPLACE, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x03\x00\x00\x00\x88\x02\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\x00\xa8\x32\x6d\xff\x7f\x00\x00\x00\x00\x00\x00"..., 744) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x58\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x58\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [896]) = 0 [pid 5773] setsockopt(3, SOL_IPV6, IP6T_SO_SET_REPLACE, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x04\x00\x00\x00\x58\x03\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x00\xa8\x32\x6d\xff\x7f\x00\x00\x00\x00\x00\x00"..., 952) = 0 [pid 5773] close(3) = 0 [pid 5773] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5800 attached [pid 5800] set_robust_list(0x55555ecda6a0, 24 [pid 5773] <... clone resumed>, child_tidptr=0x55555ecda690) = 5 [pid 5800] <... set_robust_list resumed>) = 0 [pid 5800] chdir("./2") = 0 [pid 5800] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5800] setpgid(0, 0) = 0 [pid 5800] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5800] write(3, "1000", 4) = 4 [pid 5800] close(3) = 0 [pid 5800] symlink("/dev/binderfs", "./binderfs") = 0 [pid 5800] write(1, "executing program\n", 18) = 18 executing program [pid 5800] prlimit64(0, RLIMIT_RTPRIO, {rlim_cur=8, rlim_max=139}, NULL) = 0 [pid 5800] sched_setscheduler(0, SCHED_FIFO, [7]) = 0 [pid 5800] memfd_create("syzkaller", 0) = 3 [pid 5800] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fb080000000 [pid 5800] 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) = 16777216 [pid 5800] munmap(0x7fb080000000, 138412032) = 0 [pid 5800] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [pid 5800] ioctl(4, LOOP_SET_FD, 3) = 0 [pid 5800] close(3) = 0 [pid 5800] close(4) = 0 [pid 5800] mkdir("./file1", 0777) = 0 [ 215.037564][ T5800] loop0: detected capacity change from 0 to 32768 [ 215.175504][ T5800] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,journal_flush_disabled,fsck,fix_errors=ask,norecovery,recovery_pass_last=check_lrus,nojournal_transaction_names [ 215.202516][ T5800] bcachefs (loop0): recovering from clean shutdown, journal seq 1688849860263946 [ 215.213429][ T5800] bcachefs (loop0): Version upgrade required: [ 215.213429][ T5800] Version upgrade from 0.24: unwritten_extents to 1.7: mi_btree_bitmap incomplete [ 215.213429][ T5800] Doing incompatible version upgrade from 0.24: unwritten_extents to 1.20: directory_size [ 215.213429][ T5800] running recovery passes: check_allocations,check_alloc_info,check_lrus,check_btree_backpointers,check_backpointers_to_extents,check_extents_to_backpointers,check_alloc_to_lru_refs,bucket_gens_init,check_snapshot_trees,check_snapshots,check_subvols,check_subvol_children,delete_dead_snapshots,check_inodes,check_extents,check_indirect_extents,check_dirents,check_xattrs,check_root,check_unreachable_inodes,check_subvolume_structure,check_directory_structure,check_nlinks,set_fs_needs_rebalance [ 215.222142][ T52] Bluetooth: hci0: command tx timeout [ 215.298776][ T5800] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 215.298849][ T5800] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 215.298920][ T5800] node offset 0/16 bset u64s 0: bset version 11 older than superblock version_min 24, fix? [ 215.332275][ T5800] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 215.332343][ T5800] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 215.332415][ T5800] node offset 0/16 bset u64s 0: bset version 11 older than superblock version_min 24, fix? [ 215.365403][ T5800] bcachefs (loop0): Unable to continue, halting [ 215.372217][ T5800] bcachefs (loop0): flagging btree extents lost data [ 215.379072][ T5800] bcachefs (loop0): running explicit recovery pass check_topology (2), currently at recovery_pass_empty (0) [ 215.390902][ T5800] bcachefs (loop0): running explicit recovery pass scan_for_btree_nodes (1), currently at recovery_pass_empty (0) [ 215.404756][ T5800] error reading btree root btree=extents level=0: btree_node_read_error, fixing [ 215.426126][ T5800] bcachefs (loop0): error validating btree node on loop0 at btree dirents level 0/0 [ 215.426200][ T5800] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 101895aefce4bdf written 24 min_key POS_MIN durability: 1 ptr: 0:41:0 gen 0 [ 215.426268][ T5800] node offset 0/24: got wrong btree node: got [ 215.426315][ T5800] btree=dirents level=0 seq 9aa2895aefce4bdf 1 [ 215.426360][ T5800] min: POS_MIN [ 215.426398][ T5800] max: SPOS_MAX [ 215.470442][ T5800] bcachefs (loop0): flagging btree dirents lost data [ 215.479086][ T5800] error reading btree root btree=dirents level=0: btree_node_read_error, fixing [ 215.499898][ T5800] bcachefs (loop0): error validating btree node at btree alloc level 0/0 [ 215.499967][ T5800] 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.500045][ T5800] node offset 8/24 bset u64s 375 bset byte offset 184: keys out of order: u64s 11 type alloc_v4 0:32:0 len 0 ver 0 > u64s 11 type alloc_v4 0:2:0 len 0 ver 0, fixing [ 215.540794][ T5800] bcachefs (loop0): btree_node_read_work: rewriting btree node at due to error [ 215.540794][ T5800] btree=alloc level=0 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.596732][ T5800] bcachefs (loop0): scan_for_btree_nodes... [ 215.687821][ T5800] bcachefs (loop0): btree node scan found 7 nodes after overwrites [ 215.702244][ T5800] done [ 215.705164][ T5800] bcachefs (loop0): check_topology... [ 215.707107][ T5800] bcachefs (loop0): btree root extents unreadable, must recover from scan [ 215.722179][ T5800] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=extents level=0 POS_MIN - SPOS_MAX [ 215.733992][ T5800] bcachefs (loop0): bch2_get_scanned_nodes(): recovering u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 215.757746][ T9] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 215.757816][ T9] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 215.757884][ T9] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 215.794517][ T9] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 215.794617][ T9] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 215.794725][ T9] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 215.828209][ T9] bcachefs (loop0): Unable to continue, halting [ 215.837332][ T5800] Topology repair: unreadable btree node at [ 215.837392][ T5800] btree=extents level=0 u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0, fixing [ 215.862603][ T5800] empty interior btree node at btree=extents level=1 [ 215.862660][ T5800] u64s 5 type btree_ptr SPOS_MAX len 0 ver 0, fixing [ 215.876567][ T5800] bcachefs (loop0): empty btree root extents [ 215.884483][ T5800] bcachefs (loop0): btree root dirents unreadable, must recover from scan [ 215.893992][ T5800] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=dirents level=0 POS_MIN - SPOS_MAX [ 215.905340][ T5800] bcachefs (loop0): bch2_get_scanned_nodes(): recovering 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 [ 215.931320][ T5800] done [ 215.934354][ T5800] bcachefs (loop0): accounting_read... done [ 215.941094][ T5800] bcachefs (loop0): alloc_read... done [ 215.947815][ T5800] bcachefs (loop0): stripes_read... done [ 215.954018][ T5800] bcachefs (loop0): snapshots_read... done [ 215.962264][ T5800] bcachefs (loop0): scanning for old btree nodes: min_version 0.11: inode_btree_change [ 215.972229][ T5800] bcachefs (loop0): going read-write [ 216.047348][ T190] bucket 0:38 gen 0 data type btree sector count overflow: 0 + -256 > U32_MAX [ 216.047455][ T190] 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, shutting down [ 216.074008][ T190] bcachefs (loop0): inconsistency detected - emergency read only at journal seq 1688849860263947 [ 216.074195][ T52] bcachefs (loop0): unable to write journal to sufficient devices [ 216.093477][ T190] 2 transaction updates for btree_update_nodes_written journal seq 0 [ 216.093566][ T190] update: btree=alloc cached=0 bch2_trans_start_alloc_update+0x12cc/0x14b0 [ 216.093649][ T190] old u64s 5 type deleted 0:38:0 len 0 ver 0 [ 216.093722][ T190] new u64s 13 type alloc_v4 0:38:0 len 0 ver 0: [ 216.093794][ T190] gen 0 oldest_gen 0 data_type free [ 216.093863][ T190] journal_seq_nonempty 0 [ 216.093925][ T190] journal_seq_empty 0 [ 216.093987][ T190] need_discard 0 [ 216.094049][ T190] need_inc_gen 0 [ 216.094113][ T190] dirty_sectors 0 [ 216.094172][ T190] stripe_sectors 0 [ 216.094224][ T190] cached_sectors 0 [ 216.094265][ T190] stripe 0 [ 216.094309][ T190] stripe_redundancy 0 [ 216.094350][ T190] io_time[READ] 0 [ 216.094391][ T190] io_time[WRITE] 0 [ 216.094432][ T190] fragmentation 0 [ 216.094479][ T190] bp_start 8 [ 216.094518][ T190] [ 216.094556][ T190] update: btree=alloc cached=1 bch2_trans_start_alloc_update+0x12cc/0x14b0 [ 216.094609][ T190] old u64s 5 type deleted 0:38:0 len 0 ver 0 [ 216.094656][ T190] new u64s 13 type alloc_v4 0:38:0 len 0 ver 0: [ 216.094708][ T190] gen 0 oldest_gen 0 data_type free [ 216.094752][ T190] journal_seq_nonempty 0 [ 216.094793][ T190] journal_seq_empty 0 [ 216.094834][ T190] need_discard 0 [ 216.094875][ T190] need_inc_gen 0 [ 216.094915][ T190] dirty_sectors 0 [ 216.094956][ T190] stripe_sectors 0 [ 216.094997][ T190] cached_sectors 0 [ 216.095037][ T190] stripe 0 [ 216.095078][ T190] stripe_redundancy 0 [ 216.095119][ T190] io_time[READ] 0 [ 216.095159][ T190] io_time[WRITE] 0 [ 216.095200][ T190] fragmentation 0 [ 216.095239][ T190] bp_start 8 [ 216.095278][ T190] [ 216.095323][ T190] btree_root: btree=inodes level=0 u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 98b92b4e66e26fd1 written 8 min_key POS_MIN durability: 1 ptr: 0:30:0 gen 0 [ 216.312054][ T5800] bch2_btree_update_start: 24 callbacks suppressed [ 216.312129][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.327565][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.336282][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.345104][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.353842][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.362698][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.371378][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.380137][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.388870][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.397684][ T5800] bcachefs (loop0): bch2_btree_update_start(): error journal_shutdown [ 216.407881][ T5800] bcachefs (loop0): bch2_move_btree(): error journal_shutdown [ 216.415985][ T5800] bcachefs (loop0): bch2_scan_old_btree_nodes(): error journal_shutdown [ 216.425398][ T5800] bcachefs (loop0): bch2_fs_recovery(): error journal_shutdown [ 216.433272][ T5800] bcachefs (loop0): bch2_fs_start(): error starting filesystem journal_shutdown [ 216.442586][ T5800] bcachefs (loop0): shutting down [ 216.442703][ T8] bcachefs (loop0): going read-only [ 216.453351][ T8] bcachefs (loop0): finished waiting for writes to stop [ 216.460554][ T8] bcachefs (loop0): flushing journal and stopping allocators, journal seq 1688849860263947 [ 216.470971][ T8] bcachefs (loop0): flushing journal and stopping allocators complete, journal seq 1688849860263947 [ 216.482416][ T8] bcachefs (loop0): unclean shutdown complete, journal seq 1688849860263947 [ 216.492191][ T8] bcachefs (loop0): done going read-only, filesystem not clean [ 216.515160][ T5800] bcachefs (loop0): shutdown complete [pid 5800] mount("/dev/loop0", "./file1", "bcachefs", MS_NOSUID|MS_I_VERSION, "norecovery,recovery_pass_last=check_lrus,journal_flush_disabled,fsck,background_compression=lz4,rate"...) = -1 EIO (Input/output error) [pid 5800] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 [pid 5800] ioctl(3, LOOP_CLR_FD) = 0 [ 217.207361][ T5800] bcachefs: bch2_fs_get_tree() error: journal_shutdown [pid 5800] close(3) = 0 [pid 5800] close(3) = -1 EBADF (Bad file descriptor) [pid 5800] close(4) = -1 EBADF (Bad file descriptor) [pid 5800] close(5) = -1 EBADF (Bad file descriptor) [pid 5800] close(6) = -1 EBADF (Bad file descriptor) [pid 5800] close(7) = -1 EBADF (Bad file descriptor) [pid 5800] close(8) = -1 EBADF (Bad file descriptor) [pid 5800] close(9) = -1 EBADF (Bad file descriptor) [pid 5800] close(10) = -1 EBADF (Bad file descriptor) [pid 5800] close(11) = -1 EBADF (Bad file descriptor) [pid 5800] close(12) = -1 EBADF (Bad file descriptor) [pid 5800] close(13) = -1 EBADF (Bad file descriptor) [pid 5800] close(14) = -1 EBADF (Bad file descriptor) [pid 5800] close(15) = -1 EBADF (Bad file descriptor) [pid 5800] close(16) = -1 EBADF (Bad file descriptor) [pid 5800] close(17) = -1 EBADF (Bad file descriptor) [pid 5800] close(18) = -1 EBADF (Bad file descriptor) [pid 5800] close(19) = -1 EBADF (Bad file descriptor) [pid 5800] close(20) = -1 EBADF (Bad file descriptor) [pid 5800] close(21) = -1 EBADF (Bad file descriptor) [pid 5800] close(22) = -1 EBADF (Bad file descriptor) [pid 5800] close(23) = -1 EBADF (Bad file descriptor) [pid 5800] close(24) = -1 EBADF (Bad file descriptor) [pid 5800] close(25) = -1 EBADF (Bad file descriptor) [pid 5800] close(26) = -1 EBADF (Bad file descriptor) [pid 5800] close(27) = -1 EBADF (Bad file descriptor) [pid 5800] close(28) = -1 EBADF (Bad file descriptor) [pid 5800] close(29) = -1 EBADF (Bad file descriptor) [pid 5800] exit_group(0) = ? [pid 5800] +++ exited with 0 +++ [pid 5773] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5, si_uid=0, si_status=0, si_utime=5 /* 0.05 s */, si_stime=179 /* 1.79 s */} --- [pid 5773] restart_syscall(<... resuming interrupted clone ...>) = 0 [pid 5773] umount2("./2", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] openat(AT_FDCWD, "./2", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5773] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=80, ...}, AT_EMPTY_PATH) = 0 [pid 5773] getdents64(3, 0x55555ecdb850 /* 4 entries */, 32768) = 112 [pid 5773] umount2("./2/file1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] newfstatat(AT_FDCWD, "./2/file1", {st_mode=S_IFDIR|0700, st_size=40, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5773] umount2("./2/file1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] openat(AT_FDCWD, "./2/file1", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 4 [pid 5773] newfstatat(4, "", {st_mode=S_IFDIR|0700, st_size=40, ...}, AT_EMPTY_PATH) = 0 [pid 5773] getdents64(4, 0x55555ece3890 /* 2 entries */, 32768) = 48 [pid 5773] getdents64(4, 0x55555ece3890 /* 0 entries */, 32768) = 0 [pid 5773] close(4) = 0 [pid 5773] rmdir("./2/file1") = 0 [pid 5773] umount2("./2/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] newfstatat(AT_FDCWD, "./2/binderfs", {st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5773] unlink("./2/binderfs") = 0 [pid 5773] getdents64(3, 0x55555ecdb850 /* 0 entries */, 32768) = 0 [pid 5773] close(3) = 0 [pid 5773] rmdir("./2") = 0 [pid 5773] mkdir("./3", 0777) = 0 [pid 5773] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 [pid 5773] ioctl(3, LOOP_CLR_FD) = -1 ENXIO (No such device or address) [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [264]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x19\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x19\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [264]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x62\x72\x6f\x75\x74\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x20\x00\x00\x00\x00\x00\x00\x00\x30\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x62\x72\x6f\x75\x74\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x20\x00\x00\x00\x00\x00\x00\x00\x30\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [168]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, ARPT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x07\x00\x00\x00\x00\x00\x00\x00\xe8\x00\x00\x00\xd0\x01\x00\x00\x00\x00\x00\x00\xe8\x00\x00\x00\xd0\x01\x00\x00\x04\x00\x00\x00\xb8\x03\x00\x00", [68]) = 0 [pid 5773] getsockopt(3, SOL_IP, ARPT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xb8\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [992]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x78\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x78\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [672]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1b\x00\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\xff\xff\xff\xff\x30\x01\x00\x00\xc8\x01\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\xff\xff\xff\xff\x30\x01\x00\x00\xc8\x01\x00\x00\x05\x00\x00\x00\x10\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x10\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [824]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xc8\x01\x00\x00\x60\x02\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xc8\x01\x00\x00\x60\x02\x00\x00\x06\x00\x00\x00\xa8\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xa8\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [976]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x98\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x98\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\xe0\x01\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xe0\x01\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [520]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x78\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x78\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [672]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET6, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x58\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x58\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [896]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1b\x00\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xff\xff\xff\xff\xa0\x01\x00\x00\x70\x02\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xff\xff\xff\xff\xa0\x01\x00\x00\x70\x02\x00\x00\x05\x00\x00\x00\x28\x04\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x28\x04\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [1104]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x06\x00\x00\x00\xf8\x04\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xf8\x04\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [1312]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\x88\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x88\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [688]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x58\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x58\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [896]) = 0 [pid 5773] close(3) = 0 [pid 5773] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5810 attached [pid 5810] set_robust_list(0x55555ecda6a0, 24 [pid 5773] <... clone resumed>, child_tidptr=0x55555ecda690) = 6 [pid 5810] <... set_robust_list resumed>) = 0 [pid 5810] chdir("./3") = 0 [pid 5810] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5810] setpgid(0, 0) = 0 [pid 5810] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5810] write(3, "1000", 4) = 4 [pid 5810] close(3) = 0 [pid 5810] symlink("/dev/binderfs", "./binderfs"executing program ) = 0 [pid 5810] write(1, "executing program\n", 18) = 18 [pid 5810] prlimit64(0, RLIMIT_RTPRIO, {rlim_cur=8, rlim_max=139}, NULL) = 0 [pid 5810] sched_setscheduler(0, SCHED_FIFO, [7]) = 0 [pid 5810] memfd_create("syzkaller", 0) = 3 [pid 5810] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fb080000000 [pid 5810] 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) = 16777216 [pid 5810] munmap(0x7fb080000000, 138412032) = 0 [pid 5810] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [pid 5810] ioctl(4, LOOP_SET_FD, 3) = 0 [pid 5810] close(3) = 0 [pid 5810] close(4) = 0 [pid 5810] mkdir("./file1", 0777) = 0 [ 218.280057][ T5810] loop0: detected capacity change from 0 to 32768 [ 218.404195][ T5810] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,journal_flush_disabled,fsck,fix_errors=ask,norecovery,recovery_pass_last=check_lrus,nojournal_transaction_names [ 218.430741][ T5810] bcachefs (loop0): recovering from clean shutdown, journal seq 1688849860263946 [ 218.441606][ T5810] bcachefs (loop0): Version upgrade required: [ 218.441606][ T5810] Version upgrade from 0.24: unwritten_extents to 1.7: mi_btree_bitmap incomplete [ 218.441606][ T5810] Doing incompatible version upgrade from 0.24: unwritten_extents to 1.20: directory_size [ 218.441606][ T5810] running recovery passes: check_allocations,check_alloc_info,check_lrus,check_btree_backpointers,check_backpointers_to_extents,check_extents_to_backpointers,check_alloc_to_lru_refs,bucket_gens_init,check_snapshot_trees,check_snapshots,check_subvols,check_subvol_children,delete_dead_snapshots,check_inodes,check_extents,check_indirect_extents,check_dirents,check_xattrs,check_root,check_unreachable_inodes,check_subvolume_structure,check_directory_structure,check_nlinks,set_fs_needs_rebalance [ 218.520557][ T5810] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 218.520628][ T5810] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 218.520700][ T5810] node offset 0/16 bset u64s 0: bset version 11 older than superblock version_min 24, fix? [ 218.554113][ T5810] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 218.554182][ T5810] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 218.554255][ T5810] node offset 0/16 bset u64s 0: bset version 11 older than superblock version_min 24, fix? [ 218.587734][ T5810] bcachefs (loop0): Unable to continue, halting [ 218.594687][ T5810] bcachefs (loop0): flagging btree extents lost data [ 218.601551][ T5810] bcachefs (loop0): running explicit recovery pass check_topology (2), currently at recovery_pass_empty (0) [ 218.613407][ T5810] bcachefs (loop0): running explicit recovery pass scan_for_btree_nodes (1), currently at recovery_pass_empty (0) [ 218.627331][ T5810] error reading btree root btree=extents level=0: btree_node_read_error, fixing [ 218.648485][ T5810] bcachefs (loop0): error validating btree node on loop0 at btree dirents level 0/0 [ 218.648557][ T5810] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 101895aefce4bdf written 24 min_key POS_MIN durability: 1 ptr: 0:41:0 gen 0 [ 218.648630][ T5810] node offset 0/24: got wrong btree node: got [ 218.648678][ T5810] btree=dirents level=0 seq 9aa2895aefce4bdf 1 [ 218.648723][ T5810] min: POS_MIN [ 218.648761][ T5810] max: SPOS_MAX [ 218.693485][ T5810] bcachefs (loop0): flagging btree dirents lost data [ 218.702488][ T5810] error reading btree root btree=dirents level=0: btree_node_read_error, fixing [ 218.723203][ T5810] bcachefs (loop0): error validating btree node at btree alloc level 0/0 [ 218.723273][ T5810] 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 [ 218.723350][ T5810] node offset 8/24 bset u64s 375 bset byte offset 184: keys out of order: u64s 11 type alloc_v4 0:32:0 len 0 ver 0 > u64s 11 type alloc_v4 0:2:0 len 0 ver 0, fixing [ 218.764843][ T5810] bcachefs (loop0): btree_node_read_work: rewriting btree node at due to error [ 218.764843][ T5810] btree=alloc level=0 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 [ 218.819638][ T5810] bcachefs (loop0): scan_for_btree_nodes... [ 218.913382][ T5810] bcachefs (loop0): btree node scan found 7 nodes after overwrites [ 218.927771][ T5810] done [ 218.930675][ T5810] bcachefs (loop0): check_topology... [ 218.933224][ T5810] bcachefs (loop0): btree root extents unreadable, must recover from scan [ 218.948732][ T5810] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=extents level=0 POS_MIN - SPOS_MAX [ 218.960444][ T5810] bcachefs (loop0): bch2_get_scanned_nodes(): recovering u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 218.984899][ T97] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 218.984970][ T97] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 218.985041][ T97] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 219.018598][ T97] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 219.018667][ T97] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 219.018740][ T97] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 219.052135][ T97] bcachefs (loop0): Unable to continue, halting [ 219.061090][ T5810] Topology repair: unreadable btree node at [ 219.061153][ T5810] btree=extents level=0 u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0, fixing [ 219.086076][ T5810] empty interior btree node at btree=extents level=1 [ 219.086136][ T5810] u64s 5 type btree_ptr SPOS_MAX len 0 ver 0, fixing [ 219.100169][ T5810] bcachefs (loop0): empty btree root extents [ 219.108023][ T5810] bcachefs (loop0): btree root dirents unreadable, must recover from scan [ 219.117549][ T5810] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=dirents level=0 POS_MIN - SPOS_MAX [ 219.128818][ T5810] bcachefs (loop0): bch2_get_scanned_nodes(): recovering 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 [ 219.154962][ T5810] done [ 219.157873][ T5810] bcachefs (loop0): accounting_read... done [ 219.164784][ T5810] bcachefs (loop0): alloc_read... done [ 219.171514][ T5810] bcachefs (loop0): stripes_read... done [ 219.177726][ T5810] bcachefs (loop0): snapshots_read... done [ 219.186070][ T5810] bcachefs (loop0): scanning for old btree nodes: min_version 0.11: inode_btree_change [ 219.196014][ T5810] bcachefs (loop0): going read-write [ 219.269985][ T190] bucket 0:38 gen 0 data type btree sector count overflow: 0 + -256 > U32_MAX [ 219.270093][ T190] 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, shutting down [ 219.298229][ T190] bcachefs (loop0): inconsistency detected - emergency read only at journal seq 1688849860263947 [ 219.298410][ T52] bcachefs (loop0): unable to write journal to sufficient devices [ 219.317636][ T190] 2 transaction updates for btree_update_nodes_written journal seq 0 [ 219.317728][ T190] update: btree=alloc cached=0 bch2_trans_start_alloc_update+0x12cc/0x14b0 [ 219.317811][ T190] old u64s 5 type deleted 0:38:0 len 0 ver 0 [ 219.317881][ T190] new u64s 13 type alloc_v4 0:38:0 len 0 ver 0: [ 219.317935][ T190] gen 0 oldest_gen 0 data_type free [ 219.317980][ T190] journal_seq_nonempty 0 [ 219.318022][ T190] journal_seq_empty 0 [ 219.318063][ T190] need_discard 0 [ 219.318105][ T190] need_inc_gen 0 [ 219.318147][ T190] dirty_sectors 0 [ 219.318188][ T190] stripe_sectors 0 [ 219.318233][ T190] cached_sectors 0 [ 219.318275][ T190] stripe 0 [ 219.318317][ T190] stripe_redundancy 0 [ 219.318357][ T190] io_time[READ] 0 [ 219.318399][ T190] io_time[WRITE] 0 [ 219.318439][ T190] fragmentation 0 [ 219.318488][ T190] bp_start 8 [ 219.318527][ T190] [ 219.318566][ T190] update: btree=alloc cached=1 bch2_trans_start_alloc_update+0x12cc/0x14b0 [ 219.318619][ T190] old u64s 5 type deleted 0:38:0 len 0 ver 0 [ 219.318666][ T190] new u64s 13 type alloc_v4 0:38:0 len 0 ver 0: [ 219.318714][ T190] gen 0 oldest_gen 0 data_type free [ 219.318757][ T190] journal_seq_nonempty 0 [ 219.318798][ T190] journal_seq_empty 0 [ 219.318839][ T190] need_discard 0 [ 219.318879][ T190] need_inc_gen 0 [ 219.318921][ T190] dirty_sectors 0 [ 219.318962][ T190] stripe_sectors 0 [ 219.319003][ T190] cached_sectors 0 [ 219.319045][ T190] stripe 0 [ 219.319085][ T190] stripe_redundancy 0 [ 219.319126][ T190] io_time[READ] 0 [ 219.319168][ T190] io_time[WRITE] 0 [ 219.319209][ T190] fragmentation 0 [ 219.319253][ T190] bp_start 8 [ 219.319294][ T190] [ 219.319341][ T190] btree_root: btree=inodes level=0 u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 46b7f7ff25d2d3b9 written 8 min_key POS_MIN durability: 1 ptr: 0:30:0 gen 0 [ 219.537983][ T5810] bcachefs (loop0): bch2_move_btree(): error journal_shutdown [ 219.546975][ T5810] bcachefs (loop0): bch2_scan_old_btree_nodes(): error journal_shutdown [ 219.556389][ T5810] bcachefs (loop0): bch2_fs_recovery(): error journal_shutdown [ 219.564340][ T5810] bcachefs (loop0): bch2_fs_start(): error starting filesystem journal_shutdown [ 219.573784][ T5810] bcachefs (loop0): shutting down [ 219.574015][ T8] bcachefs (loop0): going read-only [ 219.586212][ T8] bcachefs (loop0): finished waiting for writes to stop [ 219.594152][ T8] bcachefs (loop0): flushing journal and stopping allocators, journal seq 1688849860263947 [ 219.604719][ T8] bcachefs (loop0): flushing journal and stopping allocators complete, journal seq 1688849860263947 [ 219.616160][ T8] bcachefs (loop0): unclean shutdown complete, journal seq 1688849860263947 [ 219.625737][ T8] bcachefs (loop0): done going read-only, filesystem not clean [ 219.645852][ T5810] bcachefs (loop0): shutdown complete [pid 5810] mount("/dev/loop0", "./file1", "bcachefs", MS_NOSUID|MS_I_VERSION, "norecovery,recovery_pass_last=check_lrus,journal_flush_disabled,fsck,background_compression=lz4,rate"...) = -1 EIO (Input/output error) [pid 5810] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 [pid 5810] ioctl(3, LOOP_CLR_FD) = 0 [ 220.387931][ T5810] bcachefs: bch2_fs_get_tree() error: journal_shutdown [pid 5810] close(3) = 0 [pid 5810] close(3) = -1 EBADF (Bad file descriptor) [pid 5810] close(4) = -1 EBADF (Bad file descriptor) [pid 5810] close(5) = -1 EBADF (Bad file descriptor) [pid 5810] close(6) = -1 EBADF (Bad file descriptor) [pid 5810] close(7) = -1 EBADF (Bad file descriptor) [pid 5810] close(8) = -1 EBADF (Bad file descriptor) [pid 5810] close(9) = -1 EBADF (Bad file descriptor) [pid 5810] close(10) = -1 EBADF (Bad file descriptor) [pid 5810] close(11) = -1 EBADF (Bad file descriptor) [pid 5810] close(12) = -1 EBADF (Bad file descriptor) [pid 5810] close(13) = -1 EBADF (Bad file descriptor) [pid 5810] close(14) = -1 EBADF (Bad file descriptor) [pid 5810] close(15) = -1 EBADF (Bad file descriptor) [pid 5810] close(16) = -1 EBADF (Bad file descriptor) [pid 5810] close(17) = -1 EBADF (Bad file descriptor) [pid 5810] close(18) = -1 EBADF (Bad file descriptor) [pid 5810] close(19) = -1 EBADF (Bad file descriptor) [pid 5810] close(20) = -1 EBADF (Bad file descriptor) [pid 5810] close(21) = -1 EBADF (Bad file descriptor) [pid 5810] close(22) = -1 EBADF (Bad file descriptor) [pid 5810] close(23) = -1 EBADF (Bad file descriptor) [pid 5810] close(24) = -1 EBADF (Bad file descriptor) [pid 5810] close(25) = -1 EBADF (Bad file descriptor) [pid 5810] close(26) = -1 EBADF (Bad file descriptor) [pid 5810] close(27) = -1 EBADF (Bad file descriptor) [pid 5810] close(28) = -1 EBADF (Bad file descriptor) [pid 5810] close(29) = -1 EBADF (Bad file descriptor) [pid 5810] exit_group(0) = ? [pid 5810] +++ exited with 0 +++ [pid 5773] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=6, si_uid=0, si_status=0, si_utime=2 /* 0.02 s */, si_stime=178 /* 1.78 s */} --- [pid 5773] restart_syscall(<... resuming interrupted clone ...>) = 0 [pid 5773] umount2("./3", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] openat(AT_FDCWD, "./3", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5773] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=80, ...}, AT_EMPTY_PATH) = 0 [pid 5773] getdents64(3, 0x55555ecdb850 /* 4 entries */, 32768) = 112 [pid 5773] umount2("./3/file1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] newfstatat(AT_FDCWD, "./3/file1", {st_mode=S_IFDIR|0700, st_size=40, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5773] umount2("./3/file1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] openat(AT_FDCWD, "./3/file1", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 4 [pid 5773] newfstatat(4, "", {st_mode=S_IFDIR|0700, st_size=40, ...}, AT_EMPTY_PATH) = 0 [pid 5773] getdents64(4, 0x55555ece3890 /* 2 entries */, 32768) = 48 [pid 5773] getdents64(4, 0x55555ece3890 /* 0 entries */, 32768) = 0 [pid 5773] close(4) = 0 [pid 5773] rmdir("./3/file1") = 0 [pid 5773] umount2("./3/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5773] newfstatat(AT_FDCWD, "./3/binderfs", {st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5773] unlink("./3/binderfs") = 0 [pid 5773] getdents64(3, 0x55555ecdb850 /* 0 entries */, 32768) = 0 [pid 5773] close(3) = 0 [pid 5773] rmdir("./3") = 0 [pid 5773] mkdir("./4", 0777) = 0 [pid 5773] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 [pid 5773] ioctl(3, LOOP_CLR_FD) = -1 ENXIO (No such device or address) [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [264]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x19\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x19\x00\x00\x00\x00\x00\x00\x00\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [264]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_INFO, "\x62\x72\x6f\x75\x74\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x20\x00\x00\x00\x00\x00\x00\x00\x30\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [120]) = 0 [pid 5773] getsockopt(3, SOL_IP, EBT_SO_GET_ENTRIES, "\x62\x72\x6f\x75\x74\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x20\x00\x00\x00\x00\x00\x00\x00\x30\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [168]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, ARPT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x07\x00\x00\x00\x00\x00\x00\x00\xe8\x00\x00\x00\xd0\x01\x00\x00\x00\x00\x00\x00\xe8\x00\x00\x00\xd0\x01\x00\x00\x04\x00\x00\x00\xb8\x03\x00\x00", [68]) = 0 [pid 5773] getsockopt(3, SOL_IP, ARPT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xb8\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [992]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x78\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x78\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [672]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1b\x00\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\xff\xff\xff\xff\x30\x01\x00\x00\xc8\x01\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\xff\xff\xff\xff\x30\x01\x00\x00\xc8\x01\x00\x00\x05\x00\x00\x00\x10\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x10\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [824]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xc8\x01\x00\x00\x60\x02\x00\x00\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xc8\x01\x00\x00\x60\x02\x00\x00\x06\x00\x00\x00\xa8\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xa8\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [976]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x98\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x98\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\xe0\x01\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xe0\x01\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [520]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_INFO, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x98\x00\x00\x00\x30\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x78\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IP, IPT_SO_GET_ENTRIES, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x78\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [672]) = 0 [pid 5773] close(3) = 0 [pid 5773] socket(AF_INET6, SOCK_STREAM, IPPROTO_TCP) = 3 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x58\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x66\x69\x6c\x74\x65\x72\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x58\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [896]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1b\x00\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xff\xff\xff\xff\xa0\x01\x00\x00\x70\x02\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xff\xff\xff\xff\xa0\x01\x00\x00\x70\x02\x00\x00\x05\x00\x00\x00\x28\x04\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x6e\x61\x74\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x28\x04\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [1104]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x1f\x00\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\x70\x02\x00\x00\x40\x03\x00\x00\x06\x00\x00\x00\xf8\x04\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x6d\x61\x6e\x67\x6c\x65\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xf8\x04\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [1312]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x09\x00\x00\x00\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\xd0\x00\x00\x00\xff\xff\xff\xff\x03\x00\x00\x00\x88\x02\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x72\x61\x77\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x88\x02\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [688]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_INFO, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0e\x00\x00\x00\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xd0\x00\x00\x00\xa0\x01\x00\x00\xff\xff\xff\xff\x04\x00\x00\x00\x58\x03\x00\x00", [84]) = 0 [pid 5773] getsockopt(3, SOL_IPV6, IP6T_SO_GET_ENTRIES, "\x73\x65\x63\x75\x72\x69\x74\x79\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x58\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., [896]) = 0 [pid 5773] close(3) = 0 [pid 5773] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5820 attached [pid 5820] set_robust_list(0x55555ecda6a0, 24 [pid 5773] <... clone resumed>, child_tidptr=0x55555ecda690) = 7 [pid 5820] <... set_robust_list resumed>) = 0 [pid 5820] chdir("./4") = 0 [pid 5820] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5820] setpgid(0, 0executing program ) = 0 [pid 5820] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5820] write(3, "1000", 4) = 4 [pid 5820] close(3) = 0 [pid 5820] symlink("/dev/binderfs", "./binderfs") = 0 [pid 5820] write(1, "executing program\n", 18) = 18 [pid 5820] prlimit64(0, RLIMIT_RTPRIO, {rlim_cur=8, rlim_max=139}, NULL) = 0 [pid 5820] sched_setscheduler(0, SCHED_FIFO, [7]) = 0 [pid 5820] memfd_create("syzkaller", 0) = 3 [pid 5820] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fb080000000 [pid 5820] 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) = 16777216 [pid 5820] munmap(0x7fb080000000, 138412032) = 0 [pid 5820] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [pid 5820] ioctl(4, LOOP_SET_FD, 3) = 0 [pid 5820] close(3) = 0 [pid 5820] close(4) = 0 [pid 5820] mkdir("./file1", 0777) = 0 [ 221.520238][ T5820] loop0: detected capacity change from 0 to 32768 [ 221.627065][ T5820] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,journal_flush_disabled,fsck,fix_errors=ask,norecovery,recovery_pass_last=check_lrus,nojournal_transaction_names [ 221.653850][ T5820] bcachefs (loop0): recovering from clean shutdown, journal seq 1688849860263946 [ 221.664792][ T5820] bcachefs (loop0): Version upgrade required: [ 221.664792][ T5820] Version upgrade from 0.24: unwritten_extents to 1.7: mi_btree_bitmap incomplete [ 221.664792][ T5820] Doing incompatible version upgrade from 0.24: unwritten_extents to 1.20: directory_size [ 221.664792][ T5820] running recovery passes: check_allocations,check_alloc_info,check_lrus,check_btree_backpointers,check_backpointers_to_extents,check_extents_to_backpointers,check_alloc_to_lru_refs,bucket_gens_init,check_snapshot_trees,check_snapshots,check_subvols,check_subvol_children,delete_dead_snapshots,check_inodes,check_extents,check_indirect_extents,check_dirents,check_xattrs,check_root,check_unreachable_inodes,check_subvolume_structure,check_directory_structure,check_nlinks,set_fs_needs_rebalance [ 221.745000][ T5820] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 221.745072][ T5820] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 221.745146][ T5820] node offset 0/16 bset u64s 0: bset version 11 older than superblock version_min 24, fix? [ 221.778927][ T5820] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 221.778997][ T5820] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 221.779069][ T5820] node offset 0/16 bset u64s 0: bset version 11 older than superblock version_min 24, fix? [ 221.812307][ T5820] bcachefs (loop0): Unable to continue, halting [ 221.819016][ T5820] bcachefs (loop0): flagging btree extents lost data [ 221.826078][ T5820] bcachefs (loop0): running explicit recovery pass check_topology (2), currently at recovery_pass_empty (0) [ 221.838153][ T5820] bcachefs (loop0): running explicit recovery pass scan_for_btree_nodes (1), currently at recovery_pass_empty (0) [ 221.852621][ T5820] error reading btree root btree=extents level=0: btree_node_read_error, fixing [ 221.874667][ T5820] bcachefs (loop0): error validating btree node on loop0 at btree dirents level 0/0 [ 221.874740][ T5820] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 101895aefce4bdf written 24 min_key POS_MIN durability: 1 ptr: 0:41:0 gen 0 [ 221.874809][ T5820] node offset 0/24: got wrong btree node: got [ 221.874857][ T5820] btree=dirents level=0 seq 9aa2895aefce4bdf 1 [ 221.874904][ T5820] min: POS_MIN [ 221.874942][ T5820] max: SPOS_MAX [ 221.919238][ T5820] bcachefs (loop0): flagging btree dirents lost data [ 221.927615][ T5820] error reading btree root btree=dirents level=0: btree_node_read_error, fixing [ 221.949406][ T5820] bcachefs (loop0): error validating btree node at btree alloc level 0/0 [ 221.949478][ T5820] 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 [ 221.949553][ T5820] node offset 8/24 bset u64s 375 bset byte offset 184: keys out of order: u64s 11 type alloc_v4 0:32:0 len 0 ver 0 > u64s 11 type alloc_v4 0:2:0 len 0 ver 0, fixing [ 221.990252][ T5820] bcachefs (loop0): btree_node_read_work: rewriting btree node at due to error [ 221.990252][ T5820] btree=alloc level=0 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 [ 222.046655][ T5820] bcachefs (loop0): scan_for_btree_nodes... [ 222.137706][ T5820] bcachefs (loop0): btree node scan found 7 nodes after overwrites [ 222.152350][ T5820] done [ 222.155243][ T5820] bcachefs (loop0): check_topology... [ 222.157374][ T5820] bcachefs (loop0): btree root extents unreadable, must recover from scan [ 222.172461][ T5820] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=extents level=0 POS_MIN - SPOS_MAX [ 222.184123][ T5820] bcachefs (loop0): bch2_get_scanned_nodes(): recovering u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 222.207735][ T9] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 222.207805][ T9] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 222.207876][ T9] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 222.241141][ T9] bcachefs (loop0): error validating btree node at btree extents level 0/0 [ 222.241209][ T9] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 222.241281][ T9] node offset 8/16 bset u64s 49 bset byte offset 176: bad k->u64s 0 (min 5 max 255), fix? [ 222.276881][ T9] bcachefs (loop0): Unable to continue, halting [ 222.286272][ T5820] Topology repair: unreadable btree node at [ 222.286337][ T5820] btree=extents level=0 u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq c6c25c03258c59c5 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0, fixing [ 222.311287][ T5820] empty interior btree node at btree=extents level=1 [ 222.311346][ T5820] u64s 5 type btree_ptr SPOS_MAX len 0 ver 0, fixing [ 222.325354][ T5820] bcachefs (loop0): empty btree root extents [ 222.334174][ T5820] bcachefs (loop0): btree root dirents unreadable, must recover from scan [ 222.343449][ T5820] bcachefs (loop0): bch2_get_scanned_nodes(): recovery btree=dirents level=0 POS_MIN - SPOS_MAX [ 222.355021][ T5820] bcachefs (loop0): bch2_get_scanned_nodes(): recovering 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 [ 222.380237][ T5820] done [ 222.383463][ T5820] bcachefs (loop0): accounting_read... done [ 222.390302][ T5820] bcachefs (loop0): alloc_read... done [ 222.396901][ T5820] bcachefs (loop0): stripes_read... done [ 222.403058][ T5820] bcachefs (loop0): snapshots_read... done [ 222.410853][ T5820] bcachefs (loop0): scanning for old btree nodes: min_version 0.11: inode_btree_change [ 222.420867][ T5820] bcachefs (loop0): going read-write [ 222.461427][ T5820] ===================================================== [ 222.468731][ T5820] BUG: KMSAN: uninit-value in bch2_btree_node_check_topology+0xf8a/0x2e70 [ 222.477645][ T5820] bch2_btree_node_check_topology+0xf8a/0x2e70 [ 222.484259][ T5820] bch2_btree_insert_keys_interior+0x1dd4/0x2210 [ 222.490754][ T5820] btree_split+0x36ce/0xc230 [ 222.495665][ T5820] bch2_btree_insert_node+0xaba/0x27f0 [ 222.501403][ T5820] bch2_btree_node_rewrite+0x10fc/0x1ae0 [ 222.507448][ T5820] bch2_move_btree+0xc3f/0x1410 [ 222.512610][ T5820] bch2_scan_old_btree_nodes+0x15f/0x580 [ 222.518410][ T5820] bch2_fs_recovery+0x5ccf/0x6230 [ 222.523741][ T5820] bch2_fs_start+0x7ca/0xc20 [ 222.528536][ T5820] bch2_fs_get_tree+0x143a/0x2330 [ 222.533851][ T5820] vfs_get_tree+0xb1/0x5a0 [ 222.538513][ T5820] do_new_mount+0x71f/0x15e0 [ 222.543479][ T5820] path_mount+0x742/0x1f10 [ 222.548077][ T5820] __se_sys_mount+0x71f/0x800 [ 222.553076][ T5820] __x64_sys_mount+0xe4/0x150 [ 222.557949][ T5820] x64_sys_call+0x39bf/0x3c30 [ 222.562896][ T5820] do_syscall_64+0xcd/0x1e0 [ 222.567623][ T5820] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 222.573832][ T5820] [ 222.576260][ T5820] Uninit was created at: [ 222.580724][ T5820] ___kmalloc_large_node+0x22c/0x370 [ 222.586334][ T5820] __kmalloc_large_node_noprof+0x3f/0x1e0 [ 222.592459][ T5820] __kmalloc_node_noprof+0xc96/0x1250 [ 222.598062][ T5820] __kvmalloc_node_noprof+0xc0/0x2d0 [ 222.603694][ T5820] bch2_btree_node_mem_alloc+0xa72/0x2ee0 [ 222.609624][ T5820] bch2_btree_reserve_get+0x37f/0x2290 [ 222.615371][ T5820] bch2_btree_update_start+0x2b0e/0x2d60 [ 222.621247][ T5820] bch2_btree_node_rewrite+0x1d7/0x1ae0 [ 222.627106][ T5820] async_btree_node_rewrite_work+0x74c/0x1aa0 [ 222.633439][ T5820] process_scheduled_works+0xae0/0x1c40 [ 222.639152][ T5820] worker_thread+0xea7/0x14f0 [ 222.644115][ T5820] kthread+0x6b9/0xef0 [ 222.648360][ T5820] ret_from_fork+0x6d/0x90 [ 222.653056][ T5820] ret_from_fork_asm+0x1a/0x30 [ 222.658044][ T5820] [ 222.660526][ T5820] CPU: 1 UID: 0 PID: 5820 Comm: syz-executor300 Not tainted 6.14.0-rc2-syzkaller-00056-gab68d7eb7b1a #0 [ 222.672036][ T5820] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 12/27/2024 [ 222.682351][ T5820] ===================================================== [ 222.689368][ T5820] Disabling lock debugging due to kernel taint [ 222.695746][ T5820] Kernel panic - not syncing: kmsan.panic set ... [ 222.702310][ T5820] CPU: 1 UID: 0 PID: 5820 Comm: syz-executor300 Tainted: G B 6.14.0-rc2-syzkaller-00056-gab68d7eb7b1a #0 [ 222.715079][ T5820] Tainted: [B]=BAD_PAGE [ 222.719315][ T5820] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 12/27/2024 [ 222.729493][ T5820] Call Trace: [ 222.732857][ T5820] [ 222.735928][ T5820] dump_stack_lvl+0x216/0x2d0 [ 222.740746][ T5820] ? kmsan_get_shadow_origin_ptr+0x4d/0xb0 [ 222.746705][ T5820] dump_stack+0x1e/0x24 [ 222.751014][ T5820] panic+0x4e2/0xcf0 [ 222.755100][ T5820] ? kmsan_get_metadata+0xe1/0x1c0 [ 222.760405][ T5820] kmsan_report+0x2c7/0x2d0 [ 222.765126][ T5820] ? __msan_warning+0x95/0x120 [ 222.770007][ T5820] ? bch2_btree_node_check_topology+0xf8a/0x2e70 [ 222.776513][ T5820] ? bch2_btree_insert_keys_interior+0x1dd4/0x2210 [ 222.783191][ T5820] ? btree_split+0x36ce/0xc230 [ 222.788215][ T5820] ? bch2_btree_insert_node+0xaba/0x27f0 [ 222.794010][ T5820] ? bch2_btree_node_rewrite+0x10fc/0x1ae0 [ 222.800060][ T5820] ? bch2_move_btree+0xc3f/0x1410 [ 222.805245][ T5820] ? bch2_scan_old_btree_nodes+0x15f/0x580 [ 222.811219][ T5820] ? bch2_fs_recovery+0x5ccf/0x6230 [ 222.816560][ T5820] ? bch2_fs_start+0x7ca/0xc20 [ 222.821490][ T5820] ? bch2_fs_get_tree+0x143a/0x2330 [ 222.826822][ T5820] ? vfs_get_tree+0xb1/0x5a0 [ 222.831533][ T5820] ? do_new_mount+0x71f/0x15e0 [ 222.836444][ T5820] ? path_mount+0x742/0x1f10 [ 222.841206][ T5820] ? __se_sys_mount+0x71f/0x800 [ 222.846252][ T5820] ? __x64_sys_mount+0xe4/0x150 [ 222.851228][ T5820] ? x64_sys_call+0x39bf/0x3c30 [ 222.856212][ T5820] ? do_syscall_64+0xcd/0x1e0 [ 222.861068][ T5820] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 222.867463][ T5820] ? kmsan_get_metadata+0x13e/0x1c0 [ 222.872830][ T5820] ? kmsan_get_metadata+0x13e/0x1c0 [ 222.878171][ T5820] ? kmsan_get_shadow_origin_ptr+0x4d/0xb0 [ 222.884129][ T5820] __msan_warning+0x95/0x120 [ 222.888893][ T5820] bch2_btree_node_check_topology+0xf8a/0x2e70 [ 222.895333][ T5820] ? bch2_btree_bset_insert_key+0x10b1/0x2b60 [ 222.901616][ T5820] bch2_btree_insert_keys_interior+0x1dd4/0x2210 [ 222.908176][ T5820] ? kmsan_get_metadata+0x13e/0x1c0 [ 222.913549][ T5820] btree_split+0x36ce/0xc230 [ 222.918399][ T5820] ? filter_irq_stacks+0x60/0x1a0 [ 222.923622][ T5820] ? kmsan_report+0x280/0x2d0 [ 222.928520][ T5820] ? __bch2_bkey_cmp_packed_format_checked+0x91c/0x960 [ 222.935572][ T5820] ? kmsan_get_metadata+0x13e/0x1c0 [ 222.940997][ T5820] ? kmsan_internal_set_shadow_origin+0x69/0x100 [ 222.947539][ T5820] ? kmsan_get_metadata+0x13e/0x1c0 [ 222.953070][ T5820] ? kmsan_get_shadow_origin_ptr+0x4d/0xb0 [ 222.959137][ T5820] ? six_unlock_ip+0x4a6/0x510 [ 222.964157][ T5820] ? bch2_btree_node_unlock_write+0x784/0x7a0 [ 222.970457][ T5820] ? kmsan_get_metadata+0x13e/0x1c0 [ 222.975859][ T5820] ? kmsan_get_shadow_origin_ptr+0x4d/0xb0 [ 222.981875][ T5820] bch2_btree_insert_node+0xaba/0x27f0 [ 222.987592][ T5820] ? kmsan_get_shadow_origin_ptr+0x4d/0xb0 [ 222.993605][ T5820] ? bch2_btree_insert_node+0x55e/0x27f0 [ 222.999494][ T5820] bch2_btree_node_rewrite+0x10fc/0x1ae0 [ 223.005395][ T5820] ? bch2_btree_node_rewrite+0x306/0x1ae0 [ 223.011381][ T5820] bch2_move_btree+0xc3f/0x1410 [ 223.016457][ T5820] ? bch2_move_btree+0x23b/0x1410 [ 223.021741][ T5820] ? bch2_move_btree+0x5b8/0x1410 [ 223.026982][ T5820] ? __pfx_rewrite_old_nodes_pred+0x10/0x10 [ 223.033135][ T5820] ? kmsan_get_metadata+0x13e/0x1c0 [ 223.038541][ T5820] bch2_scan_old_btree_nodes+0x15f/0x580 [ 223.044448][ T5820] bch2_fs_recovery+0x5ccf/0x6230 [ 223.049731][ T5820] bch2_fs_start+0x7ca/0xc20 [ 223.054518][ T5820] bch2_fs_get_tree+0x143a/0x2330 [ 223.059805][ T5820] vfs_get_tree+0xb1/0x5a0 [ 223.064405][ T5820] ? mount_capable+0x97/0x120 [ 223.069302][ T5820] do_new_mount+0x71f/0x15e0 [ 223.074090][ T5820] ? kmsan_get_metadata+0x13e/0x1c0 [ 223.079599][ T5820] path_mount+0x742/0x1f10 [ 223.084202][ T5820] ? user_path_at+0x374/0x3e0 [ 223.089118][ T5820] __se_sys_mount+0x71f/0x800 [ 223.094003][ T5820] __x64_sys_mount+0xe4/0x150 [ 223.098891][ T5820] x64_sys_call+0x39bf/0x3c30 [ 223.103764][ T5820] do_syscall_64+0xcd/0x1e0 [ 223.108459][ T5820] ? clear_bhb_loop+0x25/0x80 [ 223.113366][ T5820] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 223.119490][ T5820] RIP: 0033:0x7fb088c745ea [ 223.124060][ T5820] Code: d8 64 89 02 48 c7 c0 ff ff ff ff eb a6 e8 7e 09 00 00 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 49 89 ca b8 a5 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 b0 ff ff ff f7 d8 64 89 01 48 [ 223.143880][ T5820] RSP: 002b:00007fff6d32ad78 EFLAGS: 00000282 ORIG_RAX: 00000000000000a5 [ 223.152489][ T5820] RAX: ffffffffffffffda RBX: 00007fff6d32ad90 RCX: 00007fb088c745ea [ 223.160626][ T5820] RDX: 00004000000000c0 RSI: 0000400000000000 RDI: 00007fff6d32ad90 [ 223.169193][ T5820] RBP: 0000400000000000 R08: 00007fff6d32add0 R09: 0000000000005944 [ 223.177325][ T5820] R10: 0000000000800002 R11: 0000000000000282 R12: 00004000000000c0 [ 223.185470][ T5820] R13: 00007fff6d32add0 R14: 0000000000000003 R15: 0000000000800002 [ 223.193617][ T5820] [ 223.197090][ T5820] Kernel Offset: disabled [ 223.201515][ T5820] Rebooting in 86400 seconds..