./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor3980161522 <...> Warning: Permanently added '10.128.10.46' (ED25519) to the list of known hosts. execve("./syz-executor3980161522", ["./syz-executor3980161522"], 0x7fff2e43c0c0 /* 10 vars */) = 0 brk(NULL) = 0x555585254000 brk(0x555585254d00) = 0x555585254d00 arch_prctl(ARCH_SET_FS, 0x555585254380) = 0 set_tid_address(0x555585254650) = 5842 set_robust_list(0x555585254660, 24) = 0 rseq(0x555585254ca0, 0x20, 0, 0x53053053) = 0 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 readlink("/proc/self/exe", "/root/syz-executor3980161522", 4096) = 28 getrandom("\xb8\x9f\x5a\xbf\x57\x16\x2d\xd3", 8, GRND_NONBLOCK) = 8 brk(NULL) = 0x555585254d00 brk(0x555585275d00) = 0x555585275d00 brk(0x555585276000) = 0x555585276000 mprotect(0x7fc02a000000, 16384, PROT_READ) = 0 mmap(0x1ffff000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x1ffff000 mmap(0x20000000, 16777216, PROT_READ|PROT_WRITE|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x20000000 mmap(0x21000000, 4096, PROT_NONE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x21000000 clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5843 attached , child_tidptr=0x555585254650) = 5843 [pid 5843] set_robust_list(0x555585254660, 24 [pid 5842] openat(AT_FDCWD, "/sys/kernel/debug/x86/nmi_longest_ns", O_WRONLY|O_CLOEXEC [pid 5843] <... set_robust_list resumed>) = 0 [pid 5842] <... openat resumed>) = 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.dbg2uU", 0700) = 0 chmod("./syzkaller.dbg2uU", 0777) = 0 chdir("./syzkaller.dbg2uU") = 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 5844 attached [pid 5844] set_robust_list(0x555585254660, 24) = 0 [pid 5842] <... clone resumed>, child_tidptr=0x555585254650) = 5844 [pid 5844] chdir("./0") = 0 [pid 5844] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5844] setpgid(0, 0) = 0 [pid 5844] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5844] write(3, "1000", 4) = 4 [pid 5844] close(3) = 0 [pid 5844] symlink("/dev/binderfs", "./binderfs") = 0 [pid 5844] write(1, "executing program\n", 18executing program ) = 18 [pid 5844] memfd_create("syzkaller", 0) = 3 [pid 5844] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc021a00000 [pid 5844] 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 5844] munmap(0x7fc021a00000, 138412032) = 0 [pid 5844] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [pid 5844] ioctl(4, LOOP_SET_FD, 3) = 0 [pid 5844] close(3) = 0 [pid 5844] close(4) = 0 [pid 5844] mkdir("./file1", 0777) = 0 [ 114.818953][ T5844] loop0: detected capacity change from 0 to 32768 [ 114.903780][ T5844] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=errors=continue,metadata_checksum=none,data_checksum=none,compression=lz4,str_hash=crc32c,no_splitbrain_check,norecovery,nojournal_transaction_names [ 114.925177][ T5844] bcachefs (loop0): recovering from clean shutdown, journal seq 13 [ 114.938343][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree extents level 0/0 [ 114.938364][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 4e0410879b0c2f04 written 16 min_key POS_MIN durability: 1 ptr: 0:27:0 gen 0 [ 114.938380][ T5844] node offset 8/16 bset u64s 51: checksum error, type chacha20_poly1305_128: got 66cec9b495be4cd96f1507bc9c29d09f should be 37f1d6087d67d21bebd469bc807a31f8, fixing [ 114.978777][ T5844] invalid bkey u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 232 size 118 offset 204 nonce 463 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0 [ 114.978799][ T5844] checksum offset + key size > uncompressed size: delete?, fixing [ 115.007417][ T5844] bcachefs (loop0): btree_node_read_work: rewriting btree node at btree=extents level=0 SPOS_MAX due to error [ 115.021506][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree inodes level 0/0 [ 115.021528][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 2a20405ac3f40602 written 24 min_key POS_MIN durability: 1 ptr: 0:38:0 gen 0 [ 115.021549][ T5844] node offset 16/24 bset u64s 110: checksum error, type chacha20_poly1305_128: got 942b33a01557211bfb8149198a26871f should be d1e256903dc89dd6436b0db8b45d2093, fixing [ 115.061963][ T5844] bcachefs (loop0): btree_node_read_work: rewriting btree node at btree=inodes level=0 SPOS_MAX due to error [ 115.074849][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree dirents level 0/0 [ 115.074865][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 267fcf747c875937 written 24 min_key POS_MIN durability: 1 ptr: 0:41:0 gen 0 [ 115.074880][ T5844] node offset 8/24 bset u64s 6: checksum error, type chacha20_poly1305_128: got abbf307d6f4195551a4398bf111cbb27 should be abbf307d6f4195801a4398bf111cbb27, fixing [ 115.114841][ T5844] bcachefs (loop0): btree_node_read_work: rewriting btree node at btree=dirents level=0 SPOS_MAX due to error [ 115.128159][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree xattrs level 0/0 [ 115.128180][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 6e2a6abe1 written 0 min_key POS_MIN durability: 1 ptr: 0:31:0 gen 0 [ 115.128195][ T5844] node offset 0/0: got wrong btree node: got [ 115.128206][ T5844] btree=(unknown) l=10 seq 1983862474614942689x [ 115.128217][ T5844] min: 10136881778836671448:14247707409477269879:3955422334 [ 115.128228][ T5844] max: 17598007053182812939:4727418054202166354:335032842 [ 115.178056][ T5844] bcachefs (loop0): flagging btree xattrs lost data [ 115.187263][ T5844] error reading btree root xattrs l=0: btree_node_read_error, fixing [ 115.195486][ T5844] bcachefs (loop0): will run btree node scan [ 115.202868][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree alloc level 0/0 [ 115.202884][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 1818ce08861e3527 written 40 min_key POS_MIN durability: 1 ptr: 0:26:0 gen 0 [ 115.202900][ T5844] node offset 8/40 bset u64s 375: checksum error, type chacha20_poly1305_128: got 593d35af9d84414ebf7567b4e52449cd should be 61ec379a8789477e76ff1a5280fd6dbd, fixing [ 115.243012][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree alloc level 0/0 [ 115.243029][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 1818ce08861e3527 written 40 min_key POS_MIN durability: 1 ptr: 0:26:0 gen 0 [ 115.243044][ T5844] node offset 24/40 bset u64s 10: found blacklisted bset (journal seq 8) in btree node at offset 24-32/40, fixing [ 115.278632][ T5844] bcachefs (loop0): btree_node_read_work: rewriting btree node at btree=alloc level=0 SPOS_MAX due to error [ 115.291028][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree subvolumes level 0/0 [ 115.291044][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq d682cebdf2a7eb26 written 16 min_key POS_MIN durability: 1 ptr: 0:35:0 gen 0 [ 115.291060][ T5844] node offset 0/16 bset u64s 0: checksum error, type chacha20_poly1305_128: got d724dbc049a39faf52c3e9104b7620a7 should be bff45ac871db9bfa3686500c30b7d82f, fixing [ 115.331780][ T5844] bcachefs (loop0): btree_node_read_work: rewriting btree node at btree=subvolumes level=0 SPOS_MAX due to error [ 115.345435][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree lru level 0/0 [ 115.345451][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 93dda84068e88b3f written 16 min_key POS_MIN durability: 1 ptr: 0:28:0 gen 0 [ 115.345466][ T5844] node offset 0/16 bset u64s 0: checksum error, type chacha20_poly1305_128: got 1f829eb714677b6cbdea0303dbb1279c should be 843f3fe64c82c51ff33e2c2018209523, fixing [ 115.385071][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree lru level 0/0 [ 115.385087][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 93dda84068e88b3f written 16 min_key POS_MIN durability: 1 ptr: 0:28:0 gen 0 [ 115.385102][ T5844] node offset 0/16 bset u64s 0: invalid bkey format: incorrect number of fields: got 231, should be 6 [ 115.385115][ T5844] u64s 80 fields 64:0, 64:0, 32:0, 0:0, 0:0, 0:0 [ 115.426131][ T5844] bcachefs (loop0): flagging btree lru lost data [ 115.433559][ T5844] error reading btree root lru l=0: btree_node_read_error, fixing [ 115.442388][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree freespace level 0/0 [ 115.442404][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq b6c44d07df4e9bb7 written 48 min_key POS_MIN durability: 1 ptr: 0:29:0 gen 0 [ 115.442420][ T5844] node offset 32/48 bset u64s 3: found blacklisted bset (journal seq 8) in btree node at offset 32-40/48, fixing [ 115.478258][ T5844] bcachefs (loop0): btree_node_read_work: rewriting btree node at btree=freespace level=0 SPOS_MAX due to error [ 115.491050][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree backpointers level 0/0 [ 115.491066][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 3b468546fb27822d written 24 min_key POS_MIN durability: 1 ptr: 0:36:0 gen 0 [ 115.491082][ T5844] node offset 8/24 bset u64s 35: checksum error, type chacha20_poly1305_128: got 2fa029e3f7fedc4b94f7dc5a22ca87a2 should be 230eae69ad0f1e91715326573b3e405a, fixing [ 115.531792][ T5844] bcachefs (loop0): error validating btree node on loop0 at btree backpointers level 0/0 [ 115.531810][ T5844] u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 3b468546fb27822d written 24 min_key POS_MIN durability: 1 ptr: 0:36:0 gen 0 [ 115.531825][ T5844] node offset 8/24 bset u64s 35: found blacklisted bset (journal seq 8) in btree node at offset 8-16/24, fixing [ 115.567620][ T5844] bcachefs (loop0): btree_node_read_work: rewriting btree node at btree=backpointers level=0 SPOS_MAX due to error [ 115.581691][ T5844] bcachefs (loop0): scan_for_btree_nodes... [ 115.586406][ T5844] bch2_scan_for_btree_nodes: nodes found after overwrites: [ 115.586426][ T5844] extents l=0 seq=1 journal_seq=5 cookie=4e0410879b0c2f04 POS_MIN-SPOS_MAX ptr: 0:27:0 gen 0 [ 115.586440][ T5844] xattrs l=0 seq=1 journal_seq=4 cookie=1b881868e2a6abe1 POS_MIN-SPOS_MAX ptr: 0:31:0 gen 0 [ 115.586452][ T5844] deleted_inodes l=0 seq=1 journal_seq=0 cookie=82036bda63714c10 POS_MIN-SPOS_MAX ptr: 0:40:0 gen 0 [ 115.586465][ T5844] [ 115.633655][ T5844] done [ 115.636408][ T5844] bcachefs (loop0): check_topology... [ 115.636480][ T5844] bcachefs (loop0): btree root xattrs unreadable, must recover from scan [ 115.650610][ T5844] bcachefs (loop0): bch2_get_scanned_nodes(): recovering xattrs l=0 POS_MIN - SPOS_MAX [ 115.660419][ T5844] bcachefs (loop0): bch2_get_scanned_nodes(): recovering u64s 11 type btree_ptr_v2 SPOS_MAX len 0 ver 0: seq 1b881868e2a6abe1 written 16 min_key POS_MIN durability: 1 ptr: 0:31:0 gen 0 [ 115.681012][ T5844] bcachefs (loop0): btree root lru unreadable, must recover from scan [ 115.689260][ T5844] no nodes found for btree lru, continuing [ 115.695285][ T5844] done [ 115.698072][ T5844] bcachefs (loop0): accounting_read... done [pid 5844] mount("/dev/loop0", "./file1", "bcachefs", 0, "\x73\x74\x72\x5f\x68\x61\x73\x68\x3d\x63\x72\x63\x33\x32\x63\x2c\x65\x72\x72\x6f\x72\x73\x3d\x63\x6f\x6e\x74\x69\x6e\x75\x65\x2c\x6d\x65\x74\x61\x64\x61\x74\x61\x5f\x63\x68\x65\x63\x6b\x73\x75\x6d\xa5\x1a\x8f\x9d\x3d\x78\x78\x68\x61\x73\x68\x2c\x73\x68\x61\x72\x64\x5f\x69\x6e\x6f\x64\x65\x5f\x6e\x75\x6d\x62\x65\x72\x73\x2c\x6e\x6f\x72\x65\x63\x6f\x76\x65\x72\x79\x2c\x6e\x6f\x5f\x73\x70\x6c\x69\x74"...) = 0 [pid 5844] openat(AT_FDCWD, "./file1", O_RDONLY|O_DIRECTORY) = 3 [pid 5844] chdir("./file1") = 0 [pid 5844] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [pid 5844] ioctl(4, LOOP_CLR_FD) = 0 [ 115.704856][ T5844] bcachefs (loop0): alloc_read... done [ 115.710432][ T5844] bcachefs (loop0): stripes_read... done [ 115.716389][ T5844] bcachefs (loop0): snapshots_read... done [ 115.724724][ T5844] bcachefs (loop0): going read-write [ 115.739307][ T5844] bcachefs (loop0): done starting filesystem [pid 5844] close(4) = 0 [pid 5844] exit_group(0) = ? [pid 5844] +++ exited with 0 +++ --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5844, si_uid=0, si_status=0, si_utime=2 /* 0.02 s */, si_stime=89 /* 0.89 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, 0x5555852556f0 /* 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 [ 115.854387][ T5842] bcachefs (loop0): shutting down [ 115.859592][ T5842] bcachefs (loop0): going read-only [ 146.380724][ T12] Allocator stuck? Waited for 30 seconds [ 146.380748][ T12] Allocator debug: [ 146.380758][ T12] capacity1536 [ 146.380768][ T12] reserved 31232 [ 146.380779][ T12] hidden 0 [ 146.380789][ T12] btree 0 [ 146.380800][ T12] data 0 [ 146.380810][ T12] cached 0 [ 146.380820][ T12] reserved 0 [ 146.380831][ T12] online_reserved 256 [ 146.380841][ T12] nr_inodes 0 [ 146.380852][ T12] [ 146.380861][ T12] freelist_wait waiting [ 146.380872][ T12] open buckets allocated1 [ 146.380882][ T12] open buckets total 1024 [ 146.380893][ T12] open_buckets_wait empty [ 146.380903][ T12] open_buckets_btree 0 [ 146.380913][ T12] open_buckets_user 0 [ 146.380924][ T12] btree reserve cache 0 [ 146.380934][ T12] [ 146.380943][ T12] Dev 0: [ 146.380953][ T12] buckets sectors fragmented [ 146.380965][ T12] free 0 0 0 [ 146.380978][ T12] sb 0 0 0 [ 146.380990][ T12] journal 0 0 0 [ 146.381002][ T12] btree 0 0 0 [ 146.381014][ T12] user 0 0 0 [ 146.381027][ T12] cached 0 0 0 [ 146.381039][ T12] parity 0 0 0 [ 146.381052][ T12] stripe 0 0 0 [ 146.381064][ T12] need_gc_gens 0 0 0 [ 146.381076][ T12] need_discard 0 0 0 [ 146.381089][ T12] unstriped 0 0 0 [ 146.381101][ T12] capacity 128 [ 146.381111][ T12] [ 146.381121][ T12] reserves: [ 146.381130][ T12] stripe 60 [ 146.381141][ T12] normal 58 [ 146.381151][ T12] copygc 56 [ 146.381162][ T12] btree 28 [ 146.381173][ T12] btree_copygc 0 [ 146.381183][ T12] reclaim 0 [ 146.381194][ T12] interior_updates 0 [ 146.381204][ T12] [ 146.381213][ T12] open buckets 0 [ 146.381224][ T12] buckets to invalidate 0 [ 146.381235][ T12] [ 146.381244][ T12] Copygc debug: [ 146.381253][ T12] running: 0 [ 146.381263][ T12] copygc_wait:0 [ 146.381273][ T12] copygc_wait_at:0 [ 146.381283][ T12] Currently waiting for:0 B [ 146.381295][ T12] Currently waiting since:640 KiB [ 146.381306][ T12] Currently calculated wait:0 B [ 146.381317][ T12] [ 146.381326][ T12] Journal debug: [ 146.381336][ T12] flags: running,may_skip_flush [ 146.381347][ T12] dirty journal entries: 0/32768 [ 146.381358][ T12] seq: 14 [ 146.381369][ T12] seq_ondisk: 14 [ 146.381380][ T12] last_seq: 15 [ 146.381390][ T12] last_seq_ondisk: 14 [ 146.381401][ T12] flushed_seq_ondisk: 14 [ 146.381412][ T12] watermark: stripe [ 146.381422][ T12] each entry reserved: 321 [ 146.381433][ T12] nr flush writes: 1 [ 146.381444][ T12] nr noflush writes: 0 [ 146.381454][ T12] average write size: 1.04 KiB [ 146.381465][ T12] nr direct reclaim: 0 [ 146.381476][ T12] nr background reclaim: 0 [ 146.381486][ T12] reclaim kicked: 0 [ 146.381497][ T12] reclaim runs in: 0 ms [ 146.381508][ T12] blocked: 0 [ 146.381518][ T12] current entry sectors: 256 [ 146.381538][ T12] current entry error: ok [ 146.381549][ T12] current entry: closed [ 146.381559][ T12] unwritten entries: [ 146.381569][ T12] last buf closed [ 146.381579][ T12] space: [ 146.381588][ T12] discarded 256:1536 [ 146.381598][ T12] clean ondisk 256:1536 [ 146.381609][ T12] clean 256:1536 [ 146.381619][ T12] total 256:2048 [ 146.381630][ T12] dev 0: [ 146.381639][ T12] nr 8 [ 146.381649][ T12] bucket size 256 [ 146.381660][ T12] available 6:248 [ 146.381670][ T12] discard_idx 0 [ 146.381680][ T12] dirty_ondisk 0 (seq 14) [ 146.381691][ T12] dirty_idx 0 (seq 14) [ 146.381701][ T12] cur_idx 0 (seq 14) [ 146.381712][ T12] [ 277.570526][ T30] INFO: task syz-executor398:5842 blocked for more than 143 seconds. [ 277.578706][ T30] Not tainted 6.13.0-rc2-syzkaller-00382-gdccbe2047a5b #0 [ 277.586522][ T30] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 277.595345][ T30] task:syz-executor398 state:D stack:23840 pid:5842 tgid:5842 ppid:5839 flags:0x00004002 [ 277.605670][ T30] Call Trace: [ 277.608980][ T30] [ 277.611983][ T30] __schedule+0x1850/0x4c30 [ 277.616559][ T30] ? __pfx___schedule+0x10/0x10 [ 277.621499][ T30] ? __pfx_lock_release+0x10/0x10 [ 277.626553][ T30] ? _raw_spin_unlock_irqrestore+0xdd/0x140 [ 277.632535][ T30] ? __pfx___bch2_print+0x10/0x10 [ 277.637608][ T30] ? schedule+0x90/0x320 [ 277.641934][ T30] schedule+0x14b/0x320 [ 277.646122][ T30] bch2_fs_read_only+0xa86/0x1200 [ 277.651233][ T30] ? __pfx_bch2_fs_read_only+0x10/0x10 [ 277.656743][ T30] ? __bch2_print+0x17a/0x220 [ 277.661507][ T30] ? __pfx_autoremove_wake_function+0x10/0x10 [ 277.667635][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 277.673326][ T30] ? down_write+0x18c/0x220 [ 277.677853][ T30] ? __pfx_down_write+0x10/0x10 [ 277.682757][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 277.688417][ T30] ? hook_sb_delete+0xaa0/0xbd0 [ 277.693367][ T30] __bch2_fs_stop+0x105/0x5c0 [ 277.698431][ T30] ? __pfx___bch2_fs_stop+0x10/0x10 [ 277.703759][ T30] ? __pfx_evict_inodes+0x10/0x10 [ 277.708819][ T30] ? bch2_sync_fs+0x155/0x300 [ 277.713562][ T30] ? __pfx_bch2_put_super+0x10/0x10 [ 277.718791][ T30] generic_shutdown_super+0x13b/0x2d0 [ 277.724271][ T30] bch2_kill_sb+0x41/0x50 [ 277.728634][ T30] deactivate_locked_super+0xc6/0x130 [ 277.734098][ T30] cleanup_mnt+0x41f/0x4b0 [ 277.738566][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 277.744287][ T30] ? lockdep_hardirqs_on+0x99/0x150 [ 277.749624][ T30] task_work_run+0x251/0x310 [ 277.754295][ T30] ? __pfx_task_work_run+0x10/0x10 [ 277.759439][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 277.765150][ T30] ? path_umount+0x284/0xf70 [ 277.769833][ T30] ptrace_notify+0x2d2/0x380 [ 277.774473][ T30] ? __pfx_ptrace_notify+0x10/0x10 [ 277.779592][ T30] ? kmem_cache_free+0x195/0x410 [ 277.784630][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 277.790323][ T30] ? __x64_sys_umount+0x123/0x170 [ 277.795360][ T30] syscall_exit_work+0xc7/0x1d0 [ 277.800309][ T30] syscall_exit_to_user_mode+0x24a/0x340 [ 277.805985][ T30] do_syscall_64+0x100/0x230 [ 277.810654][ T30] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 277.816583][ T30] RIP: 0033:0x7fc029f7e5f7 [ 277.821183][ T30] RSP: 002b:00007ffc2571c798 EFLAGS: 00000202 ORIG_RAX: 00000000000000a6 [ 277.829638][ T30] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007fc029f7e5f7 [ 277.837695][ T30] RDX: 0000000000000000 RSI: 0000000000000009 RDI: 00007ffc2571c850 [ 277.845741][ T30] RBP: 00007ffc2571c850 R08: 0000000000000000 R09: 0000000000000000 [ 277.853925][ T30] R10: 00000000ffffffff R11: 0000000000000202 R12: 00007ffc2571d8b0 [ 277.862066][ T30] R13: 00005555852556c0 R14: 0000000000000001 R15: 431bde82d7b634db [ 277.870150][ T30] [ 277.873213][ T30] [ 277.873213][ T30] Showing all locks held in the system: [ 277.881006][ T30] 4 locks held by kworker/u8:1/12: [ 277.886135][ T30] #0: ffff88807d19f948 ((wq_completion)btree_node_rewrite){+.+.}-{0:0}, at: process_scheduled_works+0x93b/0x1840 [ 277.898263][ T30] #1: ffffc90000117d00 ((work_completion)(&a->work)){+.+.}-{0:0}, at: process_scheduled_works+0x976/0x1840 [ 277.909907][ T30] #2: ffff888072704398 (&c->btree_trans_barrier){.+.+}-{0:0}, at: __bch2_trans_get+0x7e1/0xd30 [ 277.920472][ T30] #3: ffff8880727266d0 (&c->gc_lock){.+.+}-{4:4}, at: bch2_btree_update_start+0x682/0x14e0 [ 277.930690][ T30] 1 lock held by khungtaskd/30: [ 277.935535][ T30] #0: ffffffff8e937ae0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x55/0x2a0 [ 277.945539][ T30] 2 locks held by getty/5582: [ 277.950261][ T30] #0: ffff8880354aa0a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 [ 277.960118][ T30] #1: ffffc9000332b2f0 (&ldata->atomic_read_lock){+.+.}-{4:4}, at: n_tty_read+0x6a6/0x1e00 [ 277.970372][ T30] 2 locks held by syz-executor398/5842: [ 277.975933][ T30] #0: ffff88803119c0e0 (&type->s_umount_key#42){+.+.}-{4:4}, at: deactivate_super+0xb5/0xf0 [ 277.986247][ T30] #1: ffff888072700278 (&c->state_lock){+.+.}-{4:4}, at: __bch2_fs_stop+0xfd/0x5c0 [ 277.995931][ T30] [ 277.998268][ T30] ============================================= [ 277.998268][ T30] [ 278.006760][ T30] NMI backtrace for cpu 1 [ 278.011107][ T30] CPU: 1 UID: 0 PID: 30 Comm: khungtaskd Not tainted 6.13.0-rc2-syzkaller-00382-gdccbe2047a5b #0 [ 278.021622][ T30] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/25/2024 [ 278.031816][ T30] Call Trace: [ 278.035112][ T30] [ 278.038050][ T30] dump_stack_lvl+0x241/0x360 [ 278.042763][ T30] ? __pfx_dump_stack_lvl+0x10/0x10 [ 278.047984][ T30] ? __pfx__printk+0x10/0x10 [ 278.052604][ T30] nmi_cpu_backtrace+0x49c/0x4d0 [ 278.057566][ T30] ? __pfx_nmi_cpu_backtrace+0x10/0x10 [ 278.063040][ T30] ? _printk+0xd5/0x120 [ 278.067211][ T30] ? __pfx__printk+0x10/0x10 [ 278.071818][ T30] ? __wake_up_klogd+0xcc/0x110 [ 278.076679][ T30] ? __pfx__printk+0x10/0x10 [ 278.081288][ T30] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10 [ 278.087274][ T30] nmi_trigger_cpumask_backtrace+0x198/0x320 [ 278.093273][ T30] watchdog+0xff6/0x1040 [ 278.097537][ T30] ? watchdog+0x1ea/0x1040 [ 278.102115][ T30] ? __pfx_watchdog+0x10/0x10 [ 278.106808][ T30] kthread+0x2f2/0x390 [ 278.110899][ T30] ? __pfx_watchdog+0x10/0x10 [ 278.115593][ T30] ? __pfx_kthread+0x10/0x10 [ 278.120551][ T30] ret_from_fork+0x4d/0x80 [ 278.124981][ T30] ? __pfx_kthread+0x10/0x10 [ 278.129586][ T30] ret_from_fork_asm+0x1a/0x30 [ 278.134369][ T30] [ 278.137548][ T30] Sending NMI from CPU 1 to CPUs 0: [ 278.142826][ C0] NMI backtrace for cpu 0 [ 278.142840][ C0] CPU: 0 UID: 0 PID: 966 Comm: kworker/u8:5 Not tainted 6.13.0-rc2-syzkaller-00382-gdccbe2047a5b #0 [ 278.142865][ C0] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/25/2024 [ 278.142880][ C0] Workqueue: 0x0 (events_unbound) [ 278.142908][ C0] RIP: 0010:kthread_data+0x73/0xd0 [ 278.142935][ C0] Code: 21 eb 74 50 e8 ce 00 35 00 49 81 c4 a0 06 00 00 4c 89 e0 48 c1 e8 03 42 80 3c 38 00 74 08 4c 89 e7 e8 61 69 9b 00 49 8b 1c 24 <48> 83 c3 18 48 89 d8 48 c1 e8 03 42 80 3c 38 00 74 08 48 89 df e8 [ 278.142953][ C0] RSP: 0018:ffffc90003f77d20 EFLAGS: 00000246 [ 278.142971][ C0] RAX: 1ffff11004c7f854 RBX: ffff8880246f5400 RCX: ffff8880263fbc00 [ 278.142989][ C0] RDX: 0000000000000000 RSI: 0000000000200000 RDI: 0000000000000000 [ 278.143003][ C0] RBP: 0000000004208060 R08: ffffffff816a7319 R09: 1ffffffff2854312 [ 278.143020][ C0] R10: dffffc0000000000 R11: fffffbfff2854313 R12: ffff8880263fc2a0 [ 278.143036][ C0] R13: ffff88801ac98000 R14: ffff8880263fbc2c R15: dffffc0000000000 [ 278.143057][ C0] FS: 0000000000000000(0000) GS:ffff8880b8600000(0000) knlGS:0000000000000000 [ 278.143075][ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 278.143091][ C0] CR2: 0000564e1b88d600 CR3: 000000000e736000 CR4: 0000000000350ef0 [ 278.143109][ C0] Call Trace: [ 278.143117][ C0] [ 278.143125][ C0] ? nmi_cpu_backtrace+0x3c2/0x4d0 [ 278.143153][ C0] ? __pfx_lock_acquire+0x10/0x10 [ 278.143182][ C0] ? __pfx_nmi_cpu_backtrace+0x10/0x10 [ 278.143209][ C0] ? nmi_handle+0x2a/0x5a0 [ 278.143246][ C0] ? nmi_cpu_backtrace_handler+0xc/0x20 [ 278.143268][ C0] ? nmi_handle+0x151/0x5a0 [ 278.143295][ C0] ? nmi_handle+0x2a/0x5a0 [ 278.143324][ C0] ? kthread_data+0x73/0xd0 [ 278.143346][ C0] ? default_do_nmi+0x63/0x160 [ 278.143372][ C0] ? exc_nmi+0x123/0x1f0 [ 278.143396][ C0] ? end_repeat_nmi+0xf/0x53 [ 278.143420][ C0] ? kthread_data+0x49/0xd0 [ 278.143444][ C0] ? kthread_data+0x73/0xd0 [ 278.143468][ C0] ? kthread_data+0x73/0xd0 [ 278.143492][ C0] ? kthread_data+0x73/0xd0 [ 278.143515][ C0] [ 278.143522][ C0] [ 278.143531][ C0] ? schedule+0x90/0x320 [ 278.143558][ C0] wq_worker_sleeping+0x2c/0x240 [ 278.143584][ C0] ? schedule+0x90/0x320 [ 278.143611][ C0] schedule+0xe9/0x320 [ 278.143645][ C0] worker_thread+0xa30/0xd30 [ 278.143672][ C0] ? srso_alias_return_thunk+0x5/0xfbef5 [ 278.143697][ C0] ? srso_alias_return_thunk+0x5/0xfbef5 [ 278.143719][ C0] ? _raw_spin_unlock_irqrestore+0xdd/0x140 [ 278.143750][ C0] ? __kthread_parkme+0x169/0x1d0 [ 278.143777][ C0] ? __pfx_worker_thread+0x10/0x10 [ 278.143800][ C0] kthread+0x2f2/0x390 [ 278.143827][ C0] ? __pfx_worker_thread+0x10/0x10 [ 278.143850][ C0] ? __pfx_kthread+0x10/0x10 [ 278.143878][ C0] ret_from_fork+0x4d/0x80 [ 278.143901][ C0] ? __pfx_kthread+0x10/0x10 [ 278.143929][ C0] ret_from_fork_asm+0x1a/0x30 [ 278.143959][ C0] [ 278.144827][ T30] Kernel panic - not syncing: hung_task: blocked tasks [ 278.437267][ T30] CPU: 1 UID: 0 PID: 30 Comm: khungtaskd Not tainted 6.13.0-rc2-syzkaller-00382-gdccbe2047a5b #0 [ 278.447768][ T30] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 11/25/2024 [ 278.457812][ T30] Call Trace: [ 278.461082][ T30] [ 278.464004][ T30] dump_stack_lvl+0x241/0x360 [ 278.468688][ T30] ? __pfx_dump_stack_lvl+0x10/0x10 [ 278.473891][ T30] ? __pfx__printk+0x10/0x10 [ 278.478477][ T30] ? lockdep_hardirqs_on_prepare+0x43d/0x780 [ 278.484458][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 278.490085][ T30] ? vscnprintf+0x5d/0x90 [ 278.494413][ T30] panic+0x349/0x880 [ 278.498307][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 278.503935][ T30] ? nmi_trigger_cpumask_backtrace+0x244/0x320 [ 278.510175][ T30] ? __pfx_panic+0x10/0x10 [ 278.514593][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 278.520221][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 278.525846][ T30] ? preempt_schedule_thunk+0x1a/0x30 [ 278.531215][ T30] ? nmi_trigger_cpumask_backtrace+0x244/0x320 [ 278.537364][ T30] ? nmi_trigger_cpumask_backtrace+0x2d4/0x320 [ 278.543529][ T30] ? srso_alias_return_thunk+0x5/0xfbef5 [ 278.549193][ T30] ? nmi_trigger_cpumask_backtrace+0x2d9/0x320 [ 278.555433][ T30] watchdog+0x1035/0x1040 [ 278.559764][ T30] ? watchdog+0x1ea/0x1040 [ 278.564181][ T30] ? __pfx_watchdog+0x10/0x10 [ 278.568854][ T30] kthread+0x2f2/0x390 [ 278.572923][ T30] ? __pfx_watchdog+0x10/0x10 [ 278.577593][ T30] ? __pfx_kthread+0x10/0x10 [ 278.582353][ T30] ret_from_fork+0x4d/0x80 [ 278.586767][ T30] ? __pfx_kthread+0x10/0x10 [ 278.591355][ T30] ret_from_fork_asm+0x1a/0x30 [ 278.596126][ T30] [ 278.599429][ T30] Kernel Offset: disabled [ 278.603757][ T30] Rebooting in 86400 seconds..