./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor1561452892 <...> Warning: Permanently added '10.128.1.130' (ED25519) to the list of known hosts. execve("./syz-executor1561452892", ["./syz-executor1561452892"], 0x7ffcae9d6260 /* 10 vars */) = 0 brk(NULL) = 0x5555618f3000 brk(0x5555618f3d00) = 0x5555618f3d00 arch_prctl(ARCH_SET_FS, 0x5555618f3380) = 0 set_tid_address(0x5555618f3650) = 5842 set_robust_list(0x5555618f3660, 24) = 0 rseq(0x5555618f3ca0, 0x20, 0, 0x53053053) = 0 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 readlink("/proc/self/exe", "/root/syz-executor1561452892", 4096) = 28 getrandom("\x66\xb0\x4b\xd8\x2d\xf0\x1e\x5c", 8, GRND_NONBLOCK) = 8 brk(NULL) = 0x5555618f3d00 brk(0x555561914d00) = 0x555561914d00 brk(0x555561915000) = 0x555561915000 mprotect(0x7ff8d9433000, 16384, PROT_READ) = 0 mmap(0x1ffffffff000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x1ffffffff000 mmap(0x200000000000, 16777216, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x200000000000 mmap(0x200001000000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x200001000000 clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5843 attached , child_tidptr=0x5555618f3650) = 5843 [pid 5843] set_robust_list(0x5555618f3660, 24) = 0 [pid 5842] openat(AT_FDCWD, "/sys/kernel/debug/x86/nmi_longest_ns", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "10000000000", 11) = 11 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/kernel/hung_task_check_interval_secs", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "20", 2) = 2 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/net/core/bpf_jit_kallsyms", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "1", 1) = 1 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/net/core/bpf_jit_harden", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "0", 1) = 1 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/kernel/kptr_restrict", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "0", 1) = 1 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/kernel/softlockup_all_cpu_backtrace", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "1", 1) = 1 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/fs/mount-max", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "100", 3) = 3 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/vm/oom_dump_tasks", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "0", 1) = 1 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/debug/exception-trace", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "0", 1) = 1 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/kernel/printk", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "7 4 1 3", 7) = 7 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/kernel/keys/gc_delay", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "1", 1) = 1 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/vm/oom_kill_allocating_task", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "1", 1) = 1 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/kernel/ctrl-alt-del", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "0", 1) = 1 [pid 5842] close(3) = 0 [pid 5842] openat(AT_FDCWD, "/proc/sys/kernel/cad_pid", O_WRONLY|O_CLOEXEC) = 3 [pid 5842] write(3, "5843", 4) = 4 [pid 5842] close(3) = 0 [pid 5842] kill(5843, SIGKILL) = 0 [pid 5843] +++ killed by SIGKILL +++ --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_KILLED, si_pid=5843, si_uid=0, si_status=SIGKILL, si_utime=0, si_stime=0} --- mkdir("./syzkaller.FyLw1D", 0700) = 0 chmod("./syzkaller.FyLw1D", 0777) = 0 chdir("./syzkaller.FyLw1D") = 0 mkdir("./0", 0777) = 0 openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 ioctl(3, LOOP_CLR_FD) = -1 ENXIO (No such device or address) close(3) = 0 clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5846 attached [pid 5846] set_robust_list(0x5555618f3660, 24) = 0 [pid 5846] chdir("./0" [pid 5842] <... clone resumed>, child_tidptr=0x5555618f3650) = 5846 [pid 5846] <... chdir resumed>) = 0 [pid 5846] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5846] setpgid(0, 0) = 0 [pid 5846] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5846] write(3, "1000", 4) = 4 [pid 5846] close(3) = 0 [pid 5846] symlink("/dev/binderfs", "./binderfs") = 0 [pid 5846] write(1, "executing program\n", 18executing program ) = 18 [pid 5846] memfd_create("syzkaller", 0) = 3 [pid 5846] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff8d0e00000 [pid 5846] 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 5846] munmap(0x7ff8d0e00000, 138412032) = 0 [pid 5846] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [pid 5846] ioctl(4, LOOP_SET_FD, 3) = 0 [pid 5846] close(3) = 0 [pid 5846] close(4) = 0 [pid 5846] mkdir("./file0", 0777) = 0 [ 252.539194][ T5846] loop0: detected capacity change from 0 to 32768 [ 252.630957][ T5846] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none [ 252.630957][ T5846] allowing incompatible features above 0.0: (unknown version) [ 252.658315][ T5846] bcachefs (loop0): recovering from clean shutdown, journal seq 13 [ 252.680995][ T5846] bcachefs (loop0): accounting_read... done [ 252.687913][ T5846] bcachefs (loop0): alloc_read... done [ 252.693594][ T5846] bcachefs (loop0): snapshots_read... done [ 252.702475][ T5846] bcachefs (loop0): going read-write [ 252.713127][ T5846] bcachefs (loop0): journal_replay... done [ 252.719185][ T5846] bcachefs (loop0): resume_logged_ops... done [ 252.725968][ T5846] bcachefs (loop0): delete_dead_inodes... done [ 252.733018][ T5855] bcachefs (loop0): backpointer doesn't match extent it points to: [ 252.733050][ T5855] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX [ 252.733065][ T5855] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0 [pid 5846] mount("/dev/loop0", "./file0", "bcachefs", 0, "version_upgrade=none,background_compression=lz4,") = 0 [pid 5846] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY) = 3 [pid 5846] chdir("./file0") = 0 [pid 5846] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [ 252.733081][ T5855] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing [ 252.793036][ T5846] bcachefs (loop0): done starting filesystem [pid 5846] ioctl(4, LOOP_CLR_FD) = 0 [pid 5846] close(4) = 0 [pid 5846] pwrite64(-1, NULL, 0, 134220897) = -1 EBADF (Bad file descriptor) [pid 5846] mkdirat(AT_FDCWD, NULL, 000) = -1 EFAULT (Bad address) [pid 5846] mount(NULL, NULL, NULL, MS_RDONLY|MS_SYNCHRONOUS|MS_DIRSYNC|MS_BIND|MS_SHARED, NULL) = -1 EFAULT (Bad address) [pid 5846] mount("./file0/file0", "./file0/file0", NULL, MS_DIRSYNC|MS_NOATIME|MS_BIND, NULL) = 0 [pid 5846] mount(NULL, NULL, NULL, MS_NOEXEC|MS_BIND|MS_MOVE|MS_REC|MS_SLAVE|MS_I_VERSION, NULL) = -1 EFAULT (Bad address) [pid 5846] read(-1, NULL, 0) = -1 EBADF (Bad file descriptor) [pid 5846] exit_group(0) = ? [pid 5846] +++ exited with 0 +++ --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5846, si_uid=0, si_status=0, si_utime=2 /* 0.02 s */, si_stime=25 /* 0.25 s */} --- umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 getdents64(3, 0x5555618f46f0 /* 4 entries */, 32768) = 112 umount2("./0/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) newfstatat(AT_FDCWD, "./0/binderfs", {st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 unlink("./0/binderfs") = 0 umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EBUSY (Device or resource busy) newfstatat(AT_FDCWD, "./0/file0", {st_mode=S_IFDIR|0755, st_size=0, ...}, AT_SYMLINK_NOFOLLOW) = 0 umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EBUSY (Device or resource busy) openat(AT_FDCWD, "./0/file0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 4 newfstatat(4, "", {st_mode=S_IFDIR|0755, st_size=0, ...}, AT_EMPTY_PATH) = 0 getdents64(4, 0x5555618fc730 /* 3 entries */, 32768) = 80 umount2("./0/file0/lost+found", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) newfstatat(AT_FDCWD, "./0/file0/lost+found", {st_mode=S_IFDIR|0700, st_size=0, ...}, AT_SYMLINK_NOFOLLOW) = 0 umount2("./0/file0/lost+found", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) openat(AT_FDCWD, "./0/file0/lost+found", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 5 newfstatat(5, "", {st_mode=S_IFDIR|0700, st_size=0, ...}, AT_EMPTY_PATH) = 0 getdents64(5, 0x555561904770 /* 2 entries */, 32768) = 48 getdents64(5, 0x555561904770 /* 0 entries */, 32768) = 0 close(5) = 0 rmdir("./0/file0/lost+found") = 0 getdents64(4, 0x5555618fc730 /* 1 entries */, 32768) = 32 umount2("./0/file0/file3", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) newfstatat(AT_FDCWD, "./0/file0/file3", {st_mode=S_IFREG|0755, st_size=9000, ...}, AT_SYMLINK_NOFOLLOW) = 0 unlink("./0/file0/file3") = 0 getdents64(4, 0x5555618fc730 /* 1 entries */, 32768) = 32 umount2("./0/file0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) newfstatat(AT_FDCWD, "./0/file0/file0", {st_mode=S_IFDIR|0755, st_size=0, ...}, AT_SYMLINK_NOFOLLOW) = 0 umount2("./0/file0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) openat(AT_FDCWD, "./0/file0/file0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 5 newfstatat(5, "", {st_mode=S_IFDIR|0755, st_size=0, ...}, AT_EMPTY_PATH) = 0 getdents64(5, 0x555561904770 /* 3 entries */, 32768) = 80 umount2("./0/file0/file0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = 0 umount2("./0/file0/file0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) newfstatat(AT_FDCWD, "./0/file0/file0/file0", {st_mode=S_IFREG|0755, st_size=1050, ...}, AT_SYMLINK_NOFOLLOW) = 0 unlink("./0/file0/file0/file0") = 0 getdents64(5, 0x555561904770 /* 1 entries */, 32768) = 32 umount2("./0/file0/file0/file1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) newfstatat(AT_FDCWD, "./0/file0/file0/file1", {st_mode=S_IFLNK|0777, st_size=39, ...}, AT_SYMLINK_NOFOLLOW) = 0 unlink("./0/file0/file0/file1") = 0 getdents64(5, 0x555561904770 /* 0 entries */, 32768) = 0 close(5) = 0 rmdir("./0/file0/file0") = 0 getdents64(4, 0x5555618fc730 /* 1 entries */, 32768) = 32 umount2("./0/file0/file2", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) newfstatat(AT_FDCWD, "./0/file0/file2", {st_mode=S_IFREG|0755, st_size=9000, ...}, AT_SYMLINK_NOFOLLOW) = 0 [ 314.788926][ T5842] Allocator stuck? Waited for 30 seconds [ 314.788950][ T5842] Allocator debug: [ 314.788959][ T5842] capacity1536 [ 314.788968][ T5842] reserved 31232 [ 314.788976][ T5842] hidden 0 [ 314.788983][ T5842] btree 0 [ 314.788990][ T5842] data 18446744073709551608 [ 314.788999][ T5842] cached 0 [ 314.789007][ T5842] reserved 0 [ 314.789014][ T5842] online_reserved 1536 [ 314.789022][ T5842] nr_inodes 0 [ 314.789029][ T5842] [ 314.789036][ T5842] freelist_wait waiting [ 314.789043][ T5842] open buckets allocated1 [ 314.789050][ T5842] open buckets total 1024 [ 314.789057][ T5842] open_buckets_wait empty [ 314.789064][ T5842] open_buckets_btree 0 [ 314.789072][ T5842] open_buckets_user 0 [ 314.789079][ T5842] btree reserve cache 0 [ 314.789086][ T5842] [ 314.789092][ T5842] Dev 0: [ 314.789099][ T5842] buckets sectors fragmented [ 314.789107][ T5842] free 0 0 0 [ 314.789116][ T5842] sb 0 0 0 [ 314.789124][ T5842] journal 0 0 0 [ 314.789132][ T5842] btree 0 0 0 [ 314.789142][ T5842] user 018446744073709551608 8 [ 314.789152][ T5842] cached 0 0 0 [ 314.789163][ T5842] parity 0 0 0 [ 314.789172][ T5842] stripe 0 0 0 [ 314.789183][ T5842] need_gc_gens 0 0 0 [ 314.789192][ T5842] need_discard 0 0 0 [ 314.789201][ T5842] unstriped 0 0 0 [ 314.789210][ T5842] capacity 128 [ 314.789217][ T5842] [ 314.789224][ T5842] reserves: [ 314.789231][ T5842] stripe 60 [ 314.789239][ T5842] normal 58 [ 314.789247][ T5842] copygc 56 [ 314.789255][ T5842] btree 28 [ 314.789263][ T5842] btree_copygc 0 [ 314.789288][ T5842] reclaim 0 [ 314.789296][ T5842] interior_updates 0 [ 314.789305][ T5842] [ 314.789313][ T5842] open buckets 0 [ 314.789322][ T5842] buckets to invalidate 0 [ 314.789331][ T5842] [ 314.789338][ T5842] Copygc debug: [ 314.789347][ T5842] running: 1 [ 314.789354][ T5842] copygc_wait:0 [ 314.789362][ T5842] copygc_wait_at:0 [ 314.789370][ T5842] Currently waiting for:0 B [ 314.789379][ T5842] Currently waiting since:640 KiB [ 314.789388][ T5842] Currently calculated wait:0 B [ 314.789397][ T5842] [ 314.789405][ T5842] Journal debug: [ 314.789414][ T5842] flags: replay_done,running,may_skip_flush [ 314.789424][ T5842] dirty journal entries: 4/32768 [ 314.789433][ T5842] seq: 17 [ 314.789441][ T5842] seq_ondisk: 17 [ 314.789450][ T5842] last_seq: 14 [ 314.789466][ T5842] last_seq_ondisk: 14 [ 314.789475][ T5842] flushed_seq_ondisk: 17 [ 314.789484][ T5842] watermark: stripe [ 314.789493][ T5842] each entry reserved: 321 [ 314.789502][ T5842] nr flush writes: 4 [ 314.789510][ T5842] nr noflush writes: 0 [ 314.789520][ T5842] average write size: 2.19 KiB [ 314.789530][ T5842] free buf: 65536 [ 314.789540][ T5842] nr direct reclaim: 0 [ 314.789550][ T5842] nr background reclaim: 20 [ 314.789558][ T5842] reclaim kicked: 0 [ 314.789567][ T5842] reclaim runs in: 0 ms [ 314.789576][ T5842] blocked: 0 [ 314.789586][ T5842] current entry sectors: 256 [ 314.789595][ T5842] current entry error: (No error) [ 314.789610][ T5842] current entry: closed [ 314.789620][ T5842] unwritten entries: [ 314.789629][ T5842] last buf closed [ 314.789638][ T5842] space: [ 314.789647][ T5842] discarded 256:1536 [ 314.789660][ T5842] clean ondisk 256:1536 [ 314.789670][ T5842] clean 256:1536 [ 314.789680][ T5842] total 256:2048 [ 314.789690][ T5842] dev 0: [ 314.789701][ T5842] durability 1: [ 314.789709][ T5842] nr 8 [ 314.789718][ T5842] bucket size 256 [ 314.789728][ T5842] available 6:216 [ 314.789737][ T5842] discard_idx 0 [ 314.789746][ T5842] dirty_ondisk 0 (seq 17) [ 314.789755][ T5842] dirty_idx 0 (seq 17) [ 314.789765][ T5842] cur_idx 0 (seq 17) [ 314.789774][ T5842] replicas want 1 need 1 [ 315.254126][ T5855] Allocator stuck? Waited for 30 seconds [ 315.254149][ T5855] Allocator debug: [ 315.254155][ T5855] capacity1536 [ 315.254162][ T5855] reserved 31232 [ 315.254168][ T5855] hidden 0 [ 315.254175][ T5855] btree 0 [ 315.254182][ T5855] data 18446744073709551608 [ 315.254189][ T5855] cached 0 [ 315.254195][ T5855] reserved 0 [ 315.254202][ T5855] online_reserved 1536 [ 315.254208][ T5855] nr_inodes 0 [ 315.254215][ T5855] [ 315.254220][ T5855] freelist_wait waiting [ 315.254227][ T5855] open buckets allocated1 [ 315.254233][ T5855] open buckets total 1024 [ 315.254240][ T5855] open_buckets_wait empty [ 315.254247][ T5855] open_buckets_btree 0 [ 315.254253][ T5855] open_buckets_user 0 [ 315.254259][ T5855] btree reserve cache 0 [ 315.254266][ T5855] [ 315.254272][ T5855] Dev 0: [ 315.254277][ T5855] buckets sectors fragmented [ 315.254285][ T5855] free 0 0 0 [ 315.254293][ T5855] sb 0 0 0 [ 315.254301][ T5855] journal 0 0 0 [ 315.254309][ T5855] btree 0 0 0 [ 315.254317][ T5855] user 018446744073709551608 8 [ 315.254324][ T5855] cached 0 0 0 [ 315.254332][ T5855] parity 0 0 0 [ 315.254340][ T5855] stripe 0 0 0 [ 315.254348][ T5855] need_gc_gens 0 0 0 [ 315.254356][ T5855] need_discard 0 0 0 [ 315.254363][ T5855] unstriped 0 0 0 [ 315.254371][ T5855] capacity 128 [ 315.254378][ T5855] [ 315.254383][ T5855] reserves: [ 315.254389][ T5855] stripe 60 [ 315.254396][ T5855] normal 58 [ 315.254402][ T5855] copygc 56 [ 315.254409][ T5855] btree 28 [ 315.254415][ T5855] btree_copygc 0 [ 315.254422][ T5855] reclaim 0 [ 315.254429][ T5855] interior_updates 0 [ 315.254435][ T5855] [ 315.254441][ T5855] open buckets 0 [ 315.254447][ T5855] buckets to invalidate 0 [ 315.254454][ T5855] [ 315.254459][ T5855] Copygc debug: [ 315.254465][ T5855] running: 1 [ 315.254471][ T5855] copygc_wait:0 [ 315.254477][ T5855] copygc_wait_at:0 [ 315.254484][ T5855] Currently waiting for:0 B [ 315.254490][ T5855] Currently waiting since:640 KiB [ 315.254497][ T5855] Currently calculated wait:0 B [ 315.254504][ T5855] [<0>] bch2_copygc_wait_to_text+0x4ab/0x600 [ 315.254511][ T5855] [<0>] bch2_print_allocator_stuck+0xae2/0xbd0 [ 315.254518][ T5855] [<0>] __bch2_wait_on_allocator+0x17f/0x240 [ 315.254525][ T5855] [<0>] bch2_btree_update_start+0x1193/0x14c0 [ 315.254533][ T5855] [<0>] bch2_btree_split_leaf+0x13c/0x8f0 [ 315.254540][ T5855] [<0>] bch2_trans_commit_error+0x1a8/0x1100 [ 315.254547][ T5855] [<0>] __bch2_trans_commit+0x779c/0x8ae0 [ 315.254554][ T5855] [<0>] bch2_btree_write_buffer_flush_locked+0x3dce/0x45a0 [ 315.254562][ T5855] [<0>] btree_write_buffer_flush_seq+0x1833/0x19a0 [ 315.254569][ T5855] [<0>] bch2_btree_write_buffer_flush_sync+0x143/0x2c0 [ 315.254577][ T5855] [<0>] bch2_btree_write_buffer_maybe_flush+0x774/0x1130 [ 315.254585][ T5855] [<0>] backpointer_target_not_found+0x12b/0xca0 [ 315.254592][ T5855] [<0>] bch2_backpointer_get_key+0x328/0x480 [ 315.254599][ T5855] [<0>] __bch2_move_data_phys+0xd14/0x1b10 [ 315.254606][ T5855] [<0>] bch2_evacuate_bucket+0x1b8/0x2b0 [ 315.254613][ T5855] [<0>] bch2_copygc+0x323b/0x3cf0 [ 315.254620][ T5855] [<0>] bch2_copygc_thread+0x8c9/0xd40 [ 315.254627][ T5855] [<0>] kthread+0x70e/0x8a0 [ 315.254634][ T5855] [<0>] ret_from_fork+0x4b/0x80 [ 315.254640][ T5855] [<0>] ret_from_fork_asm+0x1a/0x30 [ 315.254647][ T5855] [ 315.254653][ T5855] Journal debug: [ 315.254659][ T5855] flags: replay_done,running,may_skip_flush [ 315.254667][ T5855] dirty journal entries: 4/32768 [ 315.254673][ T5855] seq: 17 [ 315.254680][ T5855] seq_ondisk: 17 [ 315.254687][ T5855] last_seq: 14 [ 315.254693][ T5855] last_seq_ondisk: 14 [ 315.254700][ T5855] flushed_seq_ondisk: 17 [ 315.254707][ T5855] watermark: stripe [ 315.254713][ T5855] each entry reserved: 321 [ 315.254720][ T5855] nr flush writes: 4 [ 315.254727][ T5855] nr noflush writes: 0 [ 315.254733][ T5855] average write size: 2.19 KiB [ 315.254740][ T5855] free buf: 65536 [ 315.254747][ T5855] nr direct reclaim: 0 [ 315.254753][ T5855] nr background reclaim: 20 [ 315.254760][ T5855] reclaim kicked: 0 [ 315.254767][ T5855] reclaim runs in: 0 ms [ 315.254773][ T5855] blocked: 0 [ 315.254780][ T5855] current entry sectors: 256 [ 315.254786][ T5855] current entry error: (No error) [ 315.254793][ T5855] current entry: closed [ 315.254800][ T5855] unwritten entries: [ 315.254806][ T5855] last buf closed [ 315.254813][ T5855] space: [ 315.254818][ T5855] discarded 256:1536 [ 315.254825][ T5855] clean ondisk 256:1536 [ 315.254834][ T5855] clean 256:1536 [ 315.254841][ T5855] total 256:2048 [ 315.254848][ T5855] dev 0: [ 315.254854][ T5855] durability 1: [ 315.254860][ T5855] nr 8 [ 315.254866][ T5855] bucket size 256 [ 315.254873][ T5855] available 6:216 [ 315.254880][ T5855] discard_idx 0 [ 315.254886][ T5855] dirty_ondisk 0 (seq 17) [ 315.254893][ T5855] dirty_idx 0 (seq 17) [ 315.254900][ T5855] cur_idx 0 (seq 17) [ 315.254907][ T5855] replicas want 1 need 1 [ 415.257597][ T31] INFO: task bch-reclaim/loo:5854 blocked for more than 143 seconds. [ 415.265724][ T31] Not tainted 6.15.0-rc4-syzkaller-00011-gf15d97df5afa #0 [ 415.273555][ T31] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 415.282416][ T31] task:bch-reclaim/loo state:D stack:26552 pid:5854 tgid:5854 ppid:2 task_flags:0x200840 flags:0x00004000 [ 415.294467][ T31] Call Trace: [ 415.297819][ T31] [ 415.300766][ T31] __schedule+0x168f/0x4c70 [ 415.305295][ T31] ? __lock_acquire+0xaac/0xd20 [ 415.310197][ T31] ? schedule+0x165/0x360 [ 415.314570][ T31] ? __pfx___schedule+0x10/0x10 [ 415.319492][ T31] ? schedule+0x91/0x360 [ 415.323770][ T31] schedule+0x165/0x360 [ 415.327987][ T31] schedule_preempt_disabled+0x13/0x30 [ 415.333480][ T31] __mutex_lock+0x724/0xe80 [ 415.338033][ T31] ? __mutex_lock+0x51b/0xe80 [ 415.342753][ T31] ? btree_write_buffer_flush_seq+0x1829/0x19a0 [ 415.349077][ T31] ? __pfx___mutex_lock+0x10/0x10 [ 415.354135][ T31] ? __pfx_bch2_next_write_buffer_flush_journal_buf+0x10/0x10 [ 415.361680][ T31] ? bch2_trans_unlock+0x3a0/0x4b0 [ 415.366844][ T31] btree_write_buffer_flush_seq+0x1829/0x19a0 [ 415.372988][ T31] ? look_up_lock_class+0x74/0x170 [ 415.378165][ T31] ? register_lock_class+0x51/0x320 [ 415.383378][ T31] ? __lock_acquire+0xaac/0xd20 [ 415.388320][ T31] ? __bch2_trans_get+0x806/0xda0 [ 415.393396][ T31] ? __pfx_btree_write_buffer_flush_seq+0x10/0x10 [ 415.399899][ T31] ? __bch2_trans_get+0x9d7/0xda0 [ 415.404963][ T31] bch2_btree_write_buffer_journal_flush+0x69/0xb0 [ 415.411540][ T31] ? __pfx_bch2_btree_write_buffer_journal_flush+0x10/0x10 [ 415.418793][ T31] journal_flush_pins+0x8e0/0xe90 [ 415.423854][ T31] __bch2_journal_reclaim+0x781/0xd10 [ 415.429304][ T31] bch2_journal_reclaim_thread+0x177/0x4f0 [ 415.435146][ T31] ? bch2_journal_reclaim_thread+0x223/0x4f0 [ 415.441190][ T31] kthread+0x70e/0x8a0 [ 415.445285][ T31] ? __pfx_bch2_journal_reclaim_thread+0x10/0x10 [ 415.451684][ T31] ? __pfx_kthread+0x10/0x10 [ 415.456300][ T31] ? __pfx_kthread+0x10/0x10 [ 415.460939][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 415.466173][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 415.471435][ T31] ? __pfx_kthread+0x10/0x10 [ 415.476070][ T31] ret_from_fork+0x4b/0x80 [ 415.480539][ T31] ? __pfx_kthread+0x10/0x10 [ 415.485155][ T31] ret_from_fork_asm+0x1a/0x30 [ 415.490026][ T31] [ 415.493141][ T31] [ 415.493141][ T31] Showing all locks held in the system: [ 415.500940][ T31] 1 lock held by khungtaskd/31: [ 415.505809][ T31] #0: ffffffff8df3b860 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x2e/0x180 [ 415.515769][ T31] 1 lock held by klogd/5180: [ 415.520428][ T31] #0: ffff8880b89399d8 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2a/0x140 [ 415.530442][ T31] 2 locks held by getty/5570: [ 415.535142][ T31] #0: ffff8880346da0a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 [ 415.545022][ T31] #1: ffffc9000333b2f0 (&ldata->atomic_read_lock){+.+.}-{4:4}, at: n_tty_read+0x43e/0x1400 [ 415.555194][ T31] 3 locks held by syz-executor156/5842: [ 415.560780][ T31] #0: ffff88807d5b8420 (sb_writers#9){.+.+}-{0:0}, at: mnt_want_write+0x41/0x90 [ 415.569975][ T31] #1: ffff888075c84228 (&c->btree_trans_barrier){.+.+}-{0:0}, at: bch2_trans_srcu_lock+0xaf/0x220 [ 415.580771][ T31] #2: ffff888075ca6590 (&c->gc_lock){.+.+}-{4:4}, at: bch2_btree_update_start+0x68f/0x14c0 [ 415.590965][ T31] 3 locks held by bch-reclaim/loo/5854: [ 415.596524][ T31] #0: ffff888075ccad28 (&j->reclaim_lock){+.+.}-{4:4}, at: bch2_journal_reclaim_thread+0x16b/0x4f0 [ 415.607446][ T31] #1: ffff888075c84228 (&c->btree_trans_barrier){.+.+}-{0:0}, at: __bch2_trans_get+0x806/0xda0 [ 415.617968][ T31] #2: ffff888075c845d0 (&wb->flushing.lock){+.+.}-{4:4}, at: btree_write_buffer_flush_seq+0x1829/0x19a0 [ 415.629272][ T31] 3 locks held by bch-copygc/loop/5855: [ 415.634832][ T31] #0: ffff888075c84228 (&c->btree_trans_barrier){.+.+}-{0:0}, at: bch2_trans_srcu_lock+0xaf/0x220 [ 415.645625][ T31] #1: ffff888075c845d0 (&wb->flushing.lock){+.+.}-{4:4}, at: btree_write_buffer_flush_seq+0x1829/0x19a0 [ 415.656963][ T31] #2: ffff888075ca6590 (&c->gc_lock){.+.+}-{4:4}, at: bch2_btree_update_start+0x68f/0x14c0 [ 415.667167][ T31] [ 415.669555][ T31] ============================================= [ 415.669555][ T31] [ 415.678017][ T31] NMI backtrace for cpu 0 [ 415.678045][ T31] CPU: 0 UID: 0 PID: 31 Comm: khungtaskd Not tainted 6.15.0-rc4-syzkaller-00011-gf15d97df5afa #0 PREEMPT(full) [ 415.678073][ T31] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/19/2025 [ 415.678085][ T31] Call Trace: [ 415.678093][ T31] [ 415.678101][ T31] dump_stack_lvl+0x189/0x250 [ 415.678131][ T31] ? __wake_up_klogd+0xd9/0x110 [ 415.678156][ T31] ? __pfx_dump_stack_lvl+0x10/0x10 [ 415.678183][ T31] ? __pfx__printk+0x10/0x10 [ 415.678226][ T31] nmi_cpu_backtrace+0x39e/0x3d0 [ 415.678257][ T31] ? __pfx_nmi_cpu_backtrace+0x10/0x10 [ 415.678279][ T31] ? _printk+0xcf/0x120 [ 415.678313][ T31] ? __pfx__printk+0x10/0x10 [ 415.678344][ T31] ? debug_show_all_locks+0x2e/0x180 [ 415.678370][ T31] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10 [ 415.678403][ T31] nmi_trigger_cpumask_backtrace+0x17a/0x300 [ 415.678434][ T31] watchdog+0xfee/0x1030 [ 415.678459][ T31] ? watchdog+0x1de/0x1030 [ 415.678489][ T31] kthread+0x70e/0x8a0 [ 415.678514][ T31] ? __pfx_watchdog+0x10/0x10 [ 415.678535][ T31] ? __pfx_kthread+0x10/0x10 [ 415.678558][ T31] ? __pfx_kthread+0x10/0x10 [ 415.678577][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 415.678607][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 415.678638][ T31] ? __pfx_kthread+0x10/0x10 [ 415.678659][ T31] ret_from_fork+0x4b/0x80 [ 415.678676][ T31] ? __pfx_kthread+0x10/0x10 [ 415.678698][ T31] ret_from_fork_asm+0x1a/0x30 [ 415.678743][ T31] [ 415.678751][ T31] Sending NMI from CPU 0 to CPUs 1: [ 415.830913][ C1] NMI backtrace for cpu 1 [ 415.830930][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Not tainted 6.15.0-rc4-syzkaller-00011-gf15d97df5afa #0 PREEMPT(full) [ 415.830951][ C1] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/19/2025 [ 415.830961][ C1] RIP: 0010:pv_native_safe_halt+0x13/0x20 [ 415.831025][ C1] Code: cc cc cc cc cc cc cc 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 66 90 0f 00 2d 03 7b 19 00 f3 0f 1e fa fb f4 cc cc cc cc cc cc cc cc cc cc cc cc 90 90 90 90 90 90 90 90 90 [ 415.831039][ C1] RSP: 0018:ffffc90000197de0 EFLAGS: 000002c6 [ 415.831054][ C1] RAX: 6b8de21d5304c900 RBX: ffffffff81973cc8 RCX: 6b8de21d5304c900 [ 415.831067][ C1] RDX: 0000000000000001 RSI: ffffffff8d73579d RDI: ffffffff8bc0fa60 [ 415.831078][ C1] RBP: ffffc90000197f20 R08: ffff8880b8932b5b R09: 1ffff1101712656b [ 415.831091][ C1] R10: dffffc0000000000 R11: ffffed101712656c R12: ffffffff8f7d9f70 [ 415.831103][ C1] R13: 0000000000000001 R14: 0000000000000001 R15: 1ffff110038d9b40 [ 415.831113][ C1] FS: 0000000000000000(0000) GS:ffff888126202000(0000) knlGS:0000000000000000 [ 415.831127][ C1] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 415.831138][ C1] CR2: 000055c2b31e0600 CR3: 000000000dd36000 CR4: 00000000003526f0 [ 415.831152][ C1] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [ 415.831162][ C1] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [ 415.831172][ C1] Call Trace: [ 415.831180][ C1] [ 415.831187][ C1] default_idle+0x13/0x20 [ 415.831205][ C1] default_idle_call+0x74/0xb0 [ 415.831225][ C1] do_idle+0x1e8/0x510 [ 415.831254][ C1] ? __pfx_do_idle+0x10/0x10 [ 415.831276][ C1] ? lockdep_hardirqs_on+0x9c/0x150 [ 415.831310][ C1] cpu_startup_entry+0x44/0x60 [ 415.831334][ C1] start_secondary+0x101/0x110 [ 415.831358][ C1] common_startup_64+0x13e/0x147 [ 415.831388][ C1] [ 415.838458][ T31] Kernel panic - not syncing: hung_task: blocked tasks [ 415.838478][ T31] CPU: 0 UID: 0 PID: 31 Comm: khungtaskd Not tainted 6.15.0-rc4-syzkaller-00011-gf15d97df5afa #0 PREEMPT(full) [ 415.838505][ T31] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/19/2025 [ 415.838519][ T31] Call Trace: [ 415.838528][ T31] [ 415.838537][ T31] dump_stack_lvl+0x99/0x250 [ 415.838572][ T31] ? __asan_memcpy+0x40/0x70 [ 415.838600][ T31] ? __pfx_dump_stack_lvl+0x10/0x10 [ 415.838631][ T31] ? __pfx__printk+0x10/0x10 [ 415.838678][ T31] panic+0x2db/0x790 [ 415.838712][ T31] ? __pfx_panic+0x10/0x10 [ 415.838740][ T31] ? nmi_backtrace_stall_check+0x433/0x440 [ 415.838771][ T31] ? preempt_schedule_thunk+0x16/0x30 [ 415.838795][ T31] ? nmi_trigger_cpumask_backtrace+0x2b6/0x300 [ 415.838834][ T31] watchdog+0x102d/0x1030 [ 415.838862][ T31] ? watchdog+0x1de/0x1030 [ 415.838896][ T31] kthread+0x70e/0x8a0 [ 415.838924][ T31] ? __pfx_watchdog+0x10/0x10 [ 415.838961][ T31] ? __pfx_kthread+0x10/0x10 [ 415.838986][ T31] ? __pfx_kthread+0x10/0x10 [ 415.839009][ T31] ? _raw_spin_unlock_irq+0x23/0x50 [ 415.839040][ T31] ? lockdep_hardirqs_on+0x9c/0x150 [ 415.839073][ T31] ? __pfx_kthread+0x10/0x10 [ 415.839095][ T31] ret_from_fork+0x4b/0x80 [ 415.839114][ T31] ? __pfx_kthread+0x10/0x10 [ 415.839137][ T31] ret_from_fork_asm+0x1a/0x30 [ 415.839186][ T31] [ 416.152240][ T31] Kernel Offset: disabled [ 416.156574][ T31] Rebooting in 86400 seconds..