./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor2207037185
<...>
Warning: Permanently added '10.128.1.95' (ED25519) to the list of known hosts.
execve("./syz-executor2207037185", ["./syz-executor2207037185"], 0x7ffe629cd1d0 /* 10 vars */) = 0
brk(NULL) = 0x555563bfb000
brk(0x555563bfbd00) = 0x555563bfbd00
arch_prctl(ARCH_SET_FS, 0x555563bfb380) = 0
set_tid_address(0x555563bfb650) = 5838
set_robust_list(0x555563bfb660, 24) = 0
rseq(0x555563bfbca0, 0x20, 0, 0x53053053) = 0
prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
readlink("/proc/self/exe", "/root/syz-executor2207037185", 4096) = 28
getrandom("\x99\x31\x53\x55\x7a\xec\xa5\x3f", 8, GRND_NONBLOCK) = 8
brk(NULL) = 0x555563bfbd00
brk(0x555563c1cd00) = 0x555563c1cd00
brk(0x555563c1d000) = 0x555563c1d000
mprotect(0x7f6faf756000, 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
mkdir("./syzkaller.xyMT6x", 0700) = 0
chmod("./syzkaller.xyMT6x", 0777) = 0
chdir("./syzkaller.xyMT6x") = 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 5840 attached
, child_tidptr=0x555563bfb650) = 5840
[pid 5840] set_robust_list(0x555563bfb660, 24) = 0
[pid 5840] chdir("./0") = 0
[pid 5840] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0
[pid 5840] setpgid(0, 0) = 0
[pid 5840] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3
[pid 5840] write(3, "1000", 4) = 4
[pid 5840] close(3) = 0
[pid 5840] symlink("/dev/binderfs", "./binderfs") = 0
executing program
[pid 5840] write(1, "executing program\n", 18) = 18
[pid 5840] memfd_create("syzkaller", 0) = 3
[pid 5840] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f6fa7200000
[pid 5840] 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 5840] munmap(0x7f6fa7200000, 138412032) = 0
[pid 5840] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4
[pid 5840] ioctl(4, LOOP_SET_FD, 3) = 0
[pid 5840] close(3) = 0
[pid 5840] close(4) = 0
[pid 5840] mkdir("./file0", 0777) = 0
[ 131.777393][ T5840] loop0: detected capacity change from 0 to 32768
[ 131.848200][ T5840] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,nojournal_transaction_names
[ 131.863650][ T5840] bcachefs (loop0): recovering from clean shutdown, journal seq 13
[ 131.882543][ T5840] bcachefs (loop0): accounting_read... done
[ 131.889055][ T5840] bcachefs (loop0): alloc_read... done
[pid 5840] mount("/dev/loop0", "./file0", "bcachefs", 0, "") = 0
[pid 5840] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY) = 3
[pid 5840] chdir("./file0") = 0
[pid 5840] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4
[pid 5840] ioctl(4, LOOP_CLR_FD) = 0
[ 131.894553][ T5840] bcachefs (loop0): stripes_read... done
[ 131.900258][ T5840] bcachefs (loop0): snapshots_read... done
[ 131.908569][ T5840] bcachefs (loop0): journal_replay... done
[ 131.914429][ T5840] bcachefs (loop0): resume_logged_ops... done
[ 131.920617][ T5840] bcachefs (loop0): delete_dead_inodes... done
[ 131.929616][ T5840] bcachefs (loop0): going read-write
[ 131.937520][ T5840] bcachefs (loop0): done starting filesystem
[pid 5840] close(4) = 0
[pid 5840] exit_group(0) = ?
[pid 5840] +++ exited with 0 +++
--- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5840, si_uid=0, si_status=0, si_utime=2 /* 0.02 s */, si_stime=25 /* 0.25 s */} ---
restart_syscall(<... resuming interrupted clone ...>) = 0
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, 0x555563bfc6f0 /* 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
[ 132.011357][ T5838] bcachefs (loop0): shutting down
[ 132.016773][ T5838] bcachefs (loop0): going read-only
[ 132.022098][ T5838] bcachefs (loop0): finished waiting for writes to stop
[ 162.525990][ T5849] Allocator stuck? Waited for 30 seconds
[ 162.526003][ T5849] Allocator debug:
[ 162.526008][ T5849] capacity1536
[ 162.526013][ T5849] reserved 31232
[ 162.526018][ T5849] hidden 0
[ 162.526022][ T5849] btree 0
[ 162.526027][ T5849] data 0
[ 162.526032][ T5849] cached 0
[ 162.526037][ T5849] reserved 0
[ 162.526042][ T5849] online_reserved 24
[ 162.526046][ T5849] nr_inodes 0
[ 162.526051][ T5849]
[ 162.526056][ T5849] freelist_wait waiting
[ 162.526060][ T5849] open buckets allocated1
[ 162.526065][ T5849] open buckets total 1024
[ 162.526070][ T5849] open_buckets_wait empty
[ 162.526075][ T5849] open_buckets_btree 0
[ 162.526079][ T5849] open_buckets_user 0
[ 162.526084][ T5849] btree reserve cache 0
[ 162.526089][ T5849]
[ 162.526093][ T5849] Dev 0:
[ 162.526098][ T5849] buckets sectors fragmented
[ 162.526103][ T5849] free 0 0 0
[ 162.526108][ T5849] sb 0 0 0
[ 162.526114][ T5849] journal 0 0 0
[ 162.526119][ T5849] btree 0 0 0
[ 162.526124][ T5849] user 0 0 0
[ 162.526130][ T5849] cached 0 0 0
[ 162.526135][ T5849] parity 0 0 0
[ 162.526140][ T5849] stripe 0 0 0
[ 162.526146][ T5849] need_gc_gens 0 0 0
[ 162.526151][ T5849] need_discard 0 0 0
[ 162.526156][ T5849] unstriped 0 0 0
[ 162.526162][ T5849] capacity 128
[ 162.526166][ T5849]
[ 162.526171][ T5849] reserves:
[ 162.526175][ T5849] stripe 60
[ 162.526180][ T5849] normal 58
[ 162.526185][ T5849] copygc 56
[ 162.526189][ T5849] btree 28
[ 162.526194][ T5849] btree_copygc 0
[ 162.526199][ T5849] reclaim 0
[ 162.526204][ T5849] interior_updates 0
[ 162.526208][ T5849]
[ 162.526213][ T5849] open buckets 0
[ 162.526217][ T5849] buckets to invalidate 0
[ 162.526222][ T5849]
[ 162.526226][ T5849] Copygc debug:
[ 162.526231][ T5849] running: 0
[ 162.526235][ T5849] copygc_wait:0
[ 162.526240][ T5849] copygc_wait_at:0
[ 162.526245][ T5849] Currently waiting for:0 B
[ 162.526250][ T5849] Currently waiting since:640 KiB
[ 162.526254][ T5849] Currently calculated wait:0 B
[ 162.526259][ T5849]
[ 162.526264][ T5849] Journal debug:
[ 162.526268][ T5849] flags: replay_done,running,may_skip_flush,need_flush_write
[ 162.526274][ T5849] dirty journal entries: 0/32768
[ 162.526279][ T5849] seq: 13
[ 162.526283][ T5849] seq_ondisk: 13
[ 162.526288][ T5849] last_seq: 14
[ 162.526293][ T5849] last_seq_ondisk: 14
[ 162.526298][ T5849] flushed_seq_ondisk: 13
[ 162.526303][ T5849] watermark: stripe
[ 162.526307][ T5849] each entry reserved: 321
[ 162.526312][ T5849] nr flush writes: 0
[ 162.526317][ T5849] nr noflush writes: 0
[ 162.526322][ T5849] average write size: 0 B
[ 162.526327][ T5849] nr direct reclaim: 0
[ 162.526331][ T5849] nr background reclaim: 0
[ 162.526336][ T5849] reclaim kicked: 0
[ 162.526341][ T5849] reclaim runs in: 0 ms
[ 162.526346][ T5849] blocked: 0
[ 162.526350][ T5849] current entry sectors: 256
[ 162.526355][ T5849] current entry error: ok
[ 162.526360][ T5849] current entry: closed
[ 162.526365][ T5849] unwritten entries:
[ 162.526369][ T5849] last buf closed
[ 162.526374][ T5849] space:
[ 162.526378][ T5849] discarded 256:1792
[ 162.526383][ T5849] clean ondisk 256:1792
[ 162.526388][ T5849] clean 256:1792
[ 162.526393][ T5849] total 256:2048
[ 162.526398][ T5849] dev 0:
[ 162.526402][ T5849] nr 8
[ 162.526407][ T5849] bucket size 256
[ 162.526412][ T5849] available 6:256
[ 162.526417][ T5849] discard_idx 0
[ 162.526422][ T5849] dirty_ondisk 0 (seq 0)
[ 162.526427][ T5849] dirty_idx 0 (seq 0)
[ 162.526432][ T5849] cur_idx 0 (seq 0)
[ 162.526436][ T5849]
[ 286.677299][ T30] INFO: task syz-executor220:5838 blocked for more than 143 seconds.
[ 286.685647][ T30] Not tainted 6.13.0-rc1-syzkaller-00182-gb8f52214c61a #0
[ 286.693284][ T30] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 286.702088][ T30] task:syz-executor220 state:D stack:23840 pid:5838 tgid:5838 ppid:5835 flags:0x00004002
[ 286.712498][ T30] Call Trace:
[ 286.715853][ T30]
[ 286.718798][ T30] __schedule+0x17fb/0x4be0
[ 286.723365][ T30] ? __pfx___schedule+0x10/0x10
[ 286.728291][ T30] ? __pfx_lock_release+0x10/0x10
[ 286.733339][ T30] ? schedule+0x90/0x320
[ 286.737621][ T30] schedule+0x14b/0x320
[ 286.741969][ T30] schedule_timeout+0xb0/0x290
[ 286.746871][ T30] ? __pfx_schedule_timeout+0x10/0x10
[ 286.752256][ T30] ? lockdep_hardirqs_on_prepare+0x43d/0x780
[ 286.758298][ T30] ? wait_for_completion+0x2fe/0x620
[ 286.763606][ T30] ? wait_for_completion+0x2fe/0x620
[ 286.768927][ T30] ? _raw_spin_unlock_irq+0x23/0x50
[ 286.774147][ T30] ? lockdep_hardirqs_on+0x99/0x150
[ 286.779616][ T30] ? wait_for_completion+0x2fe/0x620
[ 286.784936][ T30] wait_for_completion+0x355/0x620
[ 286.790106][ T30] ? __pfx_wait_for_completion+0x10/0x10
[ 286.795797][ T30] ? synchronize_rcu+0x11b/0x360
[ 286.800746][ T30] ? kthread_unpark+0xd0/0x290
[ 286.805567][ T30] kthread_stop+0x19e/0x640
[ 286.810083][ T30] bch2_copygc_stop+0x4f/0x150
[ 286.814842][ T30] __bch2_fs_read_only+0x47/0x450
[ 286.820313][ T30] bch2_fs_read_only+0xb57/0x1200
[ 286.825422][ T30] ? __pfx_bch2_fs_read_only+0x10/0x10
[ 286.830913][ T30] ? __bch2_print+0x17a/0x220
[ 286.835634][ T30] ? down_write+0x18c/0x220
[ 286.840202][ T30] ? __pfx_down_write+0x10/0x10
[ 286.845049][ T30] ? hook_sb_delete+0xaa0/0xbd0
[ 286.849950][ T30] __bch2_fs_stop+0x105/0x5c0
[ 286.854659][ T30] ? __pfx___bch2_fs_stop+0x10/0x10
[ 286.859894][ T30] ? __pfx_evict_inodes+0x10/0x10
[ 286.864952][ T30] ? bch2_sync_fs+0x155/0x300
[ 286.869662][ T30] ? __pfx_bch2_put_super+0x10/0x10
[ 286.875061][ T30] generic_shutdown_super+0x139/0x2d0
[ 286.880469][ T30] bch2_kill_sb+0x41/0x50
[ 286.884820][ T30] deactivate_locked_super+0xc4/0x130
[ 286.890234][ T30] cleanup_mnt+0x41f/0x4b0
[ 286.894664][ T30] ? lockdep_hardirqs_on+0x99/0x150
[ 286.899902][ T30] task_work_run+0x24f/0x310
[ 286.904511][ T30] ? __pfx_task_work_run+0x10/0x10
[ 286.909679][ T30] ? path_umount+0x284/0xf70
[ 286.914279][ T30] ptrace_notify+0x2d2/0x380
[ 286.918969][ T30] ? __pfx_ptrace_notify+0x10/0x10
[ 286.924098][ T30] ? kmem_cache_free+0x195/0x410
[ 286.929296][ T30] ? __x64_sys_umount+0x123/0x170
[ 286.934343][ T30] syscall_exit_work+0xc7/0x1d0
[ 286.939318][ T30] syscall_exit_to_user_mode+0x24a/0x340
[ 286.944978][ T30] do_syscall_64+0x100/0x230
[ 286.949688][ T30] ? clear_bhb_loop+0x35/0x90
[ 286.954411][ T30] entry_SYSCALL_64_after_hwframe+0x77/0x7f
[ 286.960474][ T30] RIP: 0033:0x7f6faf6d4307
[ 286.964911][ T30] RSP: 002b:00007ffde19c1778 EFLAGS: 00000206 ORIG_RAX: 00000000000000a6
[ 286.973363][ T30] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007f6faf6d4307
[ 286.981943][ T30] RDX: 0000000000000000 RSI: 0000000000000009 RDI: 00007ffde19c1830
[ 286.989962][ T30] RBP: 00007ffde19c1830 R08: 0000000000000000 R09: 0000000000000000
[ 286.998465][ T30] R10: 00000000ffffffff R11: 0000000000000206 R12: 00007ffde19c2890
[ 287.006473][ T30] R13: 0000555563bfc6c0 R14: 0000000000000001 R15: 431bde82d7b634db
[ 287.014561][ T30]
[ 287.017687][ T30]
[ 287.017687][ T30] Showing all locks held in the system:
[ 287.025471][ T30] 1 lock held by khungtaskd/30:
[ 287.030308][ T30] #0: ffffffff8e937aa0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x55/0x2a0
[ 287.040256][ T30] 1 lock held by klogd/5185:
[ 287.044857][ T30] #0: ffff8880b873e758 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2a/0x140
[ 287.054828][ T30] 2 locks held by getty/5585:
[ 287.059544][ T30] #0: ffff88803249b0a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70
[ 287.069365][ T30] #1: ffffc9000332b2f0 (&ldata->atomic_read_lock){+.+.}-{4:4}, at: n_tty_read+0x6a6/0x1e00
[ 287.079601][ T30] 2 locks held by syz-executor220/5838:
[ 287.085166][ T30] #0: ffff88807bd340e0 (&type->s_umount_key#43){+.+.}-{4:4}, at: deactivate_super+0xb5/0xf0
[ 287.095472][ T30] #1: ffff888074700278 (&c->state_lock){+.+.}-{4:4}, at: __bch2_fs_stop+0xfd/0x5c0
[ 287.104912][ T30]
[ 287.107268][ T30] =============================================
[ 287.107268][ T30]
[ 287.115740][ T30] NMI backtrace for cpu 0
[ 287.120066][ T30] CPU: 0 UID: 0 PID: 30 Comm: khungtaskd Not tainted 6.13.0-rc1-syzkaller-00182-gb8f52214c61a #0
[ 287.130546][ T30] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
[ 287.140603][ T30] Call Trace:
[ 287.143875][ T30]
[ 287.146818][ T30] dump_stack_lvl+0x241/0x360
[ 287.151486][ T30] ? __pfx_dump_stack_lvl+0x10/0x10
[ 287.156668][ T30] ? __pfx__printk+0x10/0x10
[ 287.161250][ T30] nmi_cpu_backtrace+0x49c/0x4d0
[ 287.166231][ T30] ? __pfx_nmi_cpu_backtrace+0x10/0x10
[ 287.172103][ T30] ? _printk+0xd5/0x120
[ 287.176242][ T30] ? __pfx__printk+0x10/0x10
[ 287.180841][ T30] ? __wake_up_klogd+0xcc/0x110
[ 287.185685][ T30] ? __pfx__printk+0x10/0x10
[ 287.190261][ T30] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10
[ 287.196229][ T30] nmi_trigger_cpumask_backtrace+0x198/0x320
[ 287.202204][ T30] watchdog+0xff6/0x1040
[ 287.206440][ T30] ? watchdog+0x1ea/0x1040
[ 287.210849][ T30] ? __pfx_watchdog+0x10/0x10
[ 287.215511][ T30] kthread+0x2f0/0x390
[ 287.219562][ T30] ? __pfx_watchdog+0x10/0x10
[ 287.224238][ T30] ? __pfx_kthread+0x10/0x10
[ 287.228828][ T30] ret_from_fork+0x4b/0x80
[ 287.233231][ T30] ? __pfx_kthread+0x10/0x10
[ 287.237806][ T30] ret_from_fork_asm+0x1a/0x30
[ 287.242567][ T30]
[ 287.245727][ T30] Sending NMI from CPU 0 to CPUs 1:
[ 287.250983][ C1] NMI backtrace for cpu 1 skipped: idling at acpi_safe_halt+0x21/0x30
[ 287.251965][ T30] Kernel panic - not syncing: hung_task: blocked tasks
[ 287.267021][ T30] CPU: 0 UID: 0 PID: 30 Comm: khungtaskd Not tainted 6.13.0-rc1-syzkaller-00182-gb8f52214c61a #0
[ 287.277687][ T30] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
[ 287.287745][ T30] Call Trace:
[ 287.291018][ T30]
[ 287.293948][ T30] dump_stack_lvl+0x241/0x360
[ 287.298718][ T30] ? __pfx_dump_stack_lvl+0x10/0x10
[ 287.303917][ T30] ? __pfx__printk+0x10/0x10
[ 287.308588][ T30] ? lockdep_hardirqs_on_prepare+0x43d/0x780
[ 287.314566][ T30] ? vscnprintf+0x5d/0x90
[ 287.318890][ T30] panic+0x349/0x880
[ 287.322786][ T30] ? nmi_trigger_cpumask_backtrace+0x244/0x320
[ 287.328942][ T30] ? __pfx_panic+0x10/0x10
[ 287.333361][ T30] ? irq_work_queue+0xd1/0x150
[ 287.338117][ T30] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10
[ 287.344101][ T30] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10
[ 287.350078][ T30] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10
[ 287.356056][ T30] ? nmi_trigger_cpumask_backtrace+0x251/0x320
[ 287.362206][ T30] watchdog+0x1035/0x1040
[ 287.366534][ T30] ? watchdog+0x1ea/0x1040
[ 287.370947][ T30] ? __pfx_watchdog+0x10/0x10
[ 287.375615][ T30] kthread+0x2f0/0x390
[ 287.379682][ T30] ? __pfx_watchdog+0x10/0x10
[ 287.384353][ T30] ? __pfx_kthread+0x10/0x10
[ 287.388940][ T30] ret_from_fork+0x4b/0x80
[ 287.393348][ T30] ? __pfx_kthread+0x10/0x10
[ 287.397929][ T30] ret_from_fork_asm+0x1a/0x30
[ 287.402698][ T30]
[ 287.405979][ T30] Kernel Offset: disabled
[ 287.410291][ T30] Rebooting in 86400 seconds..