./strace-static-x86_64 -e \!wait4,clock_nanosleep,nanosleep -s 100 -x -f ./syz-executor2490072501 <...> Warning: Permanently added '10.128.0.252' (ED25519) to the list of known hosts. execve("./syz-executor2490072501", ["./syz-executor2490072501"], 0x7ffe5f1447f0 /* 10 vars */) = 0 brk(NULL) = 0x555565578000 brk(0x555565578d00) = 0x555565578d00 arch_prctl(ARCH_SET_FS, 0x555565578380) = 0 set_tid_address(0x555565578650) = 5096 set_robust_list(0x555565578660, 24) = 0 rseq(0x555565578ca0, 0x20, 0, 0x53053053) = 0 prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0 readlink("/proc/self/exe", "/root/syz-executor2490072501", 4096) = 28 getrandom("\xc9\x23\x8e\xcb\x71\xfe\xb7\x05", 8, GRND_NONBLOCK) = 8 brk(NULL) = 0x555565578d00 brk(0x555565599d00) = 0x555565599d00 brk(0x55556559a000) = 0x55556559a000 mprotect(0x7f46e5e3a000, 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, child_tidptr=0x555565578650) = 5097 ./strace-static-x86_64: Process 5097 attached [pid 5096] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5097] set_robust_list(0x555565578660, 24) = 0 ./strace-static-x86_64: Process 5098 attached [pid 5096] <... clone resumed>, child_tidptr=0x555565578650) = 5098 [pid 5097] mkdir("./syzkaller.lpVna6", 0700 [pid 5096] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5098] set_robust_list(0x555565578660, 24./strace-static-x86_64: Process 5099 attached [pid 5096] <... clone resumed>, child_tidptr=0x555565578650) = 5099 [pid 5099] set_robust_list(0x555565578660, 24 [pid 5098] <... set_robust_list resumed>) = 0 [pid 5096] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5099] <... set_robust_list resumed>) = 0 [pid 5098] mkdir("./syzkaller.fhJOiR", 0700 [pid 5097] <... mkdir resumed>) = 0 [pid 5099] mkdir("./syzkaller.VJcA2J", 0700 [pid 5098] <... mkdir resumed>) = 0 [pid 5097] chmod("./syzkaller.lpVna6", 0777) = 0 ./strace-static-x86_64: Process 5100 attached [pid 5099] <... mkdir resumed>) = 0 [pid 5098] chmod("./syzkaller.fhJOiR", 0777 [pid 5097] chdir("./syzkaller.lpVna6" [pid 5096] <... clone resumed>, child_tidptr=0x555565578650) = 5100 [pid 5100] set_robust_list(0x555565578660, 24 [pid 5096] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5100] <... set_robust_list resumed>) = 0 [pid 5099] chmod("./syzkaller.VJcA2J", 0777 [pid 5097] <... chdir resumed>) = 0 [pid 5100] mkdir("./syzkaller.VeXYIe", 0700 [pid 5099] <... chmod resumed>) = 0 [pid 5098] <... chmod resumed>) = 0 [pid 5097] mkdir("./0", 0777./strace-static-x86_64: Process 5101 attached [pid 5099] chdir("./syzkaller.VJcA2J" [pid 5098] chdir("./syzkaller.fhJOiR" [pid 5096] <... clone resumed>, child_tidptr=0x555565578650) = 5101 [pid 5101] set_robust_list(0x555565578660, 24 [pid 5099] <... chdir resumed>) = 0 [pid 5098] <... chdir resumed>) = 0 [pid 5097] <... mkdir resumed>) = 0 [pid 5101] <... set_robust_list resumed>) = 0 [pid 5099] mkdir("./0", 0777 [pid 5098] mkdir("./0", 0777 [pid 5101] mkdir("./syzkaller.gn9qRz", 0700 [pid 5100] <... mkdir resumed>) = 0 [pid 5099] <... mkdir resumed>) = 0 [pid 5097] openat(AT_FDCWD, "/dev/loop0", O_RDWR [pid 5098] <... mkdir resumed>) = 0 [pid 5099] openat(AT_FDCWD, "/dev/loop2", O_RDWR [pid 5101] <... mkdir resumed>) = 0 [pid 5100] chmod("./syzkaller.VeXYIe", 0777 [pid 5098] openat(AT_FDCWD, "/dev/loop1", O_RDWR [pid 5099] <... openat resumed>) = 3 [pid 5097] <... openat resumed>) = 3 [pid 5101] chmod("./syzkaller.gn9qRz", 0777 [pid 5100] <... chmod resumed>) = 0 [pid 5097] ioctl(3, LOOP_CLR_FD [pid 5101] <... chmod resumed>) = 0 [pid 5100] chdir("./syzkaller.VeXYIe" [pid 5099] ioctl(3, LOOP_CLR_FD [pid 5098] <... openat resumed>) = 3 [pid 5101] chdir("./syzkaller.gn9qRz" [pid 5100] <... chdir resumed>) = 0 [pid 5099] <... ioctl resumed>) = -1 ENXIO (No such device or address) [pid 5097] <... ioctl resumed>) = -1 ENXIO (No such device or address) [pid 5101] <... chdir resumed>) = 0 [pid 5100] mkdir("./0", 0777 [pid 5101] mkdir("./0", 0777 [pid 5099] close(3 [pid 5098] ioctl(3, LOOP_CLR_FD [pid 5097] close(3 [pid 5101] <... mkdir resumed>) = 0 [pid 5100] <... mkdir resumed>) = 0 [pid 5099] <... close resumed>) = 0 [pid 5098] <... ioctl resumed>) = -1 ENXIO (No such device or address) [pid 5097] <... close resumed>) = 0 [pid 5097] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5101] openat(AT_FDCWD, "/dev/loop4", O_RDWR [pid 5100] openat(AT_FDCWD, "/dev/loop3", O_RDWR [pid 5099] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5098] close(3 [pid 5101] <... openat resumed>) = 3 [pid 5100] <... openat resumed>) = 3 [pid 5098] <... close resumed>) = 0 [pid 5098] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5097] <... clone resumed>, child_tidptr=0x555565578650) = 5102 [pid 5101] ioctl(3, LOOP_CLR_FD) = -1 ENXIO (No such device or address) [pid 5101] close(3./strace-static-x86_64: Process 5104 attached ) = 0 [pid 5099] <... clone resumed>, child_tidptr=0x555565578650) = 5104 ./strace-static-x86_64: Process 5105 attached ./strace-static-x86_64: Process 5102 attached [pid 5100] ioctl(3, LOOP_CLR_FD [pid 5105] set_robust_list(0x555565578660, 24 [pid 5104] set_robust_list(0x555565578660, 24 [pid 5102] set_robust_list(0x555565578660, 24 [pid 5101] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5100] <... ioctl resumed>) = -1 ENXIO (No such device or address) [pid 5098] <... clone resumed>, child_tidptr=0x555565578650) = 5105 [pid 5105] <... set_robust_list resumed>) = 0 [pid 5102] <... set_robust_list resumed>) = 0 [pid 5104] <... set_robust_list resumed>) = 0 [pid 5104] chdir("./0") = 0 [pid 5104] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5104] setpgid(0, 0) = 0 [pid 5104] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 ./strace-static-x86_64: Process 5106 attached [pid 5101] <... clone resumed>, child_tidptr=0x555565578650) = 5106 [pid 5106] set_robust_list(0x555565578660, 24 [pid 5104] write(3, "1000", 4 [pid 5106] <... set_robust_list resumed>) = 0 [pid 5106] chdir("./0" [pid 5104] <... write resumed>) = 4 [pid 5102] chdir("./0" [pid 5100] close(3 [pid 5106] <... chdir resumed>) = 0 [pid 5105] chdir("./0" [pid 5104] close(3 [pid 5106] prctl(PR_SET_PDEATHSIG, SIGKILL [pid 5105] <... chdir resumed>) = 0 [pid 5104] <... close resumed>) = 0 [pid 5102] <... chdir resumed>) = 0 [pid 5100] <... close resumed>) = 0 [pid 5106] <... prctl resumed>) = 0 [pid 5105] prctl(PR_SET_PDEATHSIG, SIGKILL [pid 5104] symlink("/dev/binderfs", "./binderfs" [pid 5102] prctl(PR_SET_PDEATHSIG, SIGKILL [pid 5106] setpgid(0, 0 [pid 5105] <... prctl resumed>) = 0 [pid 5104] <... symlink resumed>) = 0 [pid 5102] <... prctl resumed>) = 0 [pid 5100] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD [pid 5105] setpgid(0, 0 [pid 5102] setpgid(0, 0 [pid 5106] <... setpgid resumed>) = 0 [pid 5106] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5105] <... setpgid resumed>) = 0 [pid 5102] <... setpgid resumed>) = 0 [pid 5105] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC [pid 5104] memfd_create("syzkaller", 0 [pid 5102] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC [pid 5100] <... clone resumed>, child_tidptr=0x555565578650) = 5107 ./strace-static-x86_64: Process 5107 attached [pid 5106] write(3, "1000", 4 [pid 5105] <... openat resumed>) = 3 [pid 5104] <... memfd_create resumed>) = 3 [pid 5102] <... openat resumed>) = 3 [pid 5106] <... write resumed>) = 4 [pid 5105] write(3, "1000", 4 [pid 5104] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 [pid 5102] write(3, "1000", 4 [pid 5107] set_robust_list(0x555565578660, 24 [pid 5106] close(3 [pid 5107] <... set_robust_list resumed>) = 0 [pid 5105] <... write resumed>) = 4 [pid 5102] <... write resumed>) = 4 [pid 5107] chdir("./0" [pid 5106] <... close resumed>) = 0 [pid 5105] close(3 [pid 5104] <... mmap resumed>) = 0x7f46dd800000 [pid 5102] close(3 [pid 5107] <... chdir resumed>) = 0 [pid 5106] symlink("/dev/binderfs", "./binderfs" [pid 5105] <... close resumed>) = 0 [pid 5102] <... close resumed>) = 0 [pid 5107] prctl(PR_SET_PDEATHSIG, SIGKILL [pid 5105] symlink("/dev/binderfs", "./binderfs" [pid 5102] symlink("/dev/binderfs", "./binderfs" [pid 5107] <... prctl resumed>) = 0 [pid 5106] <... symlink resumed>) = 0 [pid 5102] <... symlink resumed>) = 0 [pid 5105] <... symlink resumed>) = 0 [pid 5107] setpgid(0, 0) = 0 [pid 5106] memfd_create("syzkaller", 0 [pid 5105] memfd_create("syzkaller", 0 [pid 5102] memfd_create("syzkaller", 0 [pid 5107] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC [pid 5106] <... memfd_create resumed>) = 3 [pid 5105] <... memfd_create resumed>) = 3 [pid 5102] <... memfd_create resumed>) = 3 [pid 5107] <... openat resumed>) = 3 [pid 5106] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 [pid 5105] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 [pid 5102] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 [pid 5107] write(3, "1000", 4 [pid 5105] <... mmap resumed>) = 0x7f46dd800000 [pid 5107] <... write resumed>) = 4 [pid 5102] <... mmap resumed>) = 0x7f46dd800000 [pid 5107] close(3) = 0 [pid 5106] <... mmap resumed>) = 0x7f46dd800000 [pid 5107] symlink("/dev/binderfs", "./binderfs") = 0 [pid 5107] memfd_create("syzkaller", 0) = 3 [pid 5107] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f46dd800000 [pid 5104] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 [pid 5107] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 [pid 5102] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 [pid 5105] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 [pid 5106] write(3, "\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00"..., 16777216 [pid 5107] <... write resumed>) = 16777216 [pid 5107] munmap(0x7f46dd800000, 138412032) = 0 [pid 5107] openat(AT_FDCWD, "/dev/loop3", O_RDWR [pid 5102] <... write resumed>) = 16777216 [pid 5107] <... openat resumed>) = 4 [pid 5107] ioctl(4, LOOP_SET_FD, 3 [pid 5102] munmap(0x7f46dd800000, 138412032 [pid 5104] <... write resumed>) = 16777216 [pid 5107] <... ioctl resumed>) = 0 [pid 5104] munmap(0x7f46dd800000, 138412032 [pid 5107] close(3) = 0 [pid 5107] close(4) = 0 [pid 5107] mkdir("./file0", 0777 [pid 5104] <... munmap resumed>) = 0 [pid 5102] <... munmap resumed>) = 0 [ 77.505057][ T5107] loop3: detected capacity change from 0 to 32768 [pid 5105] <... write resumed>) = 16777216 [pid 5107] <... mkdir resumed>) = 0 [pid 5106] <... write resumed>) = 16777216 [pid 5105] munmap(0x7f46dd800000, 138412032 [pid 5104] openat(AT_FDCWD, "/dev/loop2", O_RDWR [pid 5102] openat(AT_FDCWD, "/dev/loop0", O_RDWR [pid 5107] mount("/dev/loop3", "./file0", "jfs", MS_NOSUID, "quota,iocharset=cp1250,nodiscard,gid=0x000000000000ee00,discard=0x0000000000000009,errors=continue,q"... [pid 5106] munmap(0x7f46dd800000, 138412032 [pid 5104] <... openat resumed>) = 4 [pid 5102] <... openat resumed>) = 4 [pid 5102] ioctl(4, LOOP_SET_FD, 3 [pid 5104] ioctl(4, LOOP_SET_FD, 3 [pid 5102] <... ioctl resumed>) = 0 [pid 5105] <... munmap resumed>) = 0 [pid 5105] openat(AT_FDCWD, "/dev/loop1", O_RDWR) = 4 [pid 5102] close(3 [pid 5105] ioctl(4, LOOP_SET_FD, 3 [pid 5102] <... close resumed>) = 0 [pid 5104] <... ioctl resumed>) = 0 [pid 5107] <... mount resumed>) = 0 [pid 5106] <... munmap resumed>) = 0 [pid 5105] <... ioctl resumed>) = 0 [pid 5102] close(4 [pid 5107] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY [pid 5106] openat(AT_FDCWD, "/dev/loop4", O_RDWR [pid 5105] close(3 [pid 5104] close(3 [pid 5102] <... close resumed>) = 0 [pid 5107] <... openat resumed>) = 3 [pid 5105] <... close resumed>) = 0 [pid 5104] <... close resumed>) = 0 [pid 5107] chdir("./file0" [pid 5106] <... openat resumed>) = 4 [pid 5102] mkdir("./file0", 0777 [pid 5107] <... chdir resumed>) = 0 [pid 5106] ioctl(4, LOOP_SET_FD, 3 [pid 5105] close(4 [pid 5104] close(4 [pid 5107] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = -1 EBUSY (Device or resource busy) [pid 5105] <... close resumed>) = 0 [pid 5104] <... close resumed>) = 0 [ 77.591994][ T5102] loop0: detected capacity change from 0 to 32768 [ 77.592624][ T5104] loop2: detected capacity change from 0 to 32768 [ 77.616703][ T5105] loop1: detected capacity change from 0 to 32768 [pid 5107] chdir("./file0" [pid 5105] mkdir("./file0", 0777 [pid 5104] mkdir("./file0", 0777 [pid 5102] <... mkdir resumed>) = 0 [pid 5107] <... chdir resumed>) = 0 [pid 5105] <... mkdir resumed>) = 0 [pid 5104] <... mkdir resumed>) = 0 [pid 5102] mount("/dev/loop0", "./file0", "jfs", MS_NOSUID, "quota,iocharset=cp1250,nodiscard,gid=0x000000000000ee00,discard=0x0000000000000009,errors=continue,q"... [pid 5107] rename("./file0", "./file3" [pid 5106] <... ioctl resumed>) = 0 [pid 5106] close(3) = 0 [pid 5105] mount("/dev/loop1", "./file0", "jfs", MS_NOSUID, "quota,iocharset=cp1250,nodiscard,gid=0x000000000000ee00,discard=0x0000000000000009,errors=continue,q"... [pid 5104] mount("/dev/loop2", "./file0", "jfs", MS_NOSUID, "quota,iocharset=cp1250,nodiscard,gid=0x000000000000ee00,discard=0x0000000000000009,errors=continue,q"... [pid 5106] close(4) = 0 [pid 5106] mkdir("./file0", 0777 [pid 5105] <... mount resumed>) = 0 [pid 5105] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY) = 3 [pid 5105] chdir("./file0" [pid 5104] <... mount resumed>) = 0 [pid 5105] <... chdir resumed>) = 0 [pid 5104] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY [pid 5106] <... mkdir resumed>) = 0 [pid 5105] openat(AT_FDCWD, "/dev/loop1", O_RDWR [pid 5104] <... openat resumed>) = 3 [pid 5105] <... openat resumed>) = -1 EBUSY (Device or resource busy) [pid 5106] mount("/dev/loop4", "./file0", "jfs", MS_NOSUID, "quota,iocharset=cp1250,nodiscard,gid=0x000000000000ee00,discard=0x0000000000000009,errors=continue,q"... [ 77.650527][ T5106] loop4: detected capacity change from 0 to 32768 [ 77.662911][ T5107] find_entry called with index = 0 [ 77.668847][ T5107] read_mapping_page failed! [pid 5105] chdir("./file0") = 0 [pid 5104] chdir("./file0" [pid 5105] rename("./file0", "./file3" [pid 5104] <... chdir resumed>) = 0 [pid 5104] openat(AT_FDCWD, "/dev/loop2", O_RDWR [pid 5107] <... rename resumed>) = -1 EIO (Input/output error) [pid 5104] <... openat resumed>) = -1 EBUSY (Device or resource busy) [pid 5107] openat(AT_FDCWD, "./file0", O_RDONLY [pid 5104] chdir("./file0" [pid 5102] <... mount resumed>) = 0 [pid 5102] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY [pid 5104] <... chdir resumed>) = 0 [pid 5102] <... openat resumed>) = 3 [pid 5104] rename("./file0", "./file3" [pid 5102] chdir("./file0") = 0 [pid 5102] openat(AT_FDCWD, "/dev/loop0", O_RDWR [pid 5107] <... openat resumed>) = 4 [pid 5102] <... openat resumed>) = -1 EBUSY (Device or resource busy) [pid 5102] chdir("./file0" [pid 5107] exit_group(0 [pid 5102] <... chdir resumed>) = 0 [pid 5102] rename("./file0", "./file3" [pid 5107] <... exit_group resumed>) = ? [ 77.701123][ T5107] ERROR: (device loop3): txCommit: [ 77.701123][ T5107] [ 77.703380][ T5105] find_entry called with index = 0 [ 77.726835][ T5104] find_entry called with index = 0 [ 77.738385][ T5102] find_entry called with index = 0 [pid 5107] +++ exited with 0 +++ [pid 5100] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5107, si_uid=0, si_status=0, si_utime=4 /* 0.04 s */, si_stime=35 /* 0.35 s */} --- [pid 5100] restart_syscall(<... resuming interrupted clone ...>) = 0 [pid 5104] <... rename resumed>) = -1 EIO (Input/output error) [pid 5106] <... mount resumed>) = 0 [pid 5105] <... rename resumed>) = -1 EIO (Input/output error) [pid 5100] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5106] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY [pid 5100] <... umount2 resumed>) = -1 EINVAL (Invalid argument) [pid 5100] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY [pid 5105] openat(AT_FDCWD, "./file0", O_RDONLY [pid 5106] <... openat resumed>) = 3 [pid 5100] <... openat resumed>) = 3 [pid 5106] chdir("./file0" [pid 5100] newfstatat(3, "", [pid 5104] openat(AT_FDCWD, "./file0", O_RDONLY [pid 5106] <... chdir resumed>) = 0 [pid 5100] <... newfstatat resumed>{st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5106] openat(AT_FDCWD, "/dev/loop4", O_RDWR) = -1 EBUSY (Device or resource busy) [pid 5100] getdents64(3, [pid 5106] chdir("./file0" [pid 5104] <... openat resumed>) = 4 [pid 5102] <... rename resumed>) = -1 EIO (Input/output error) [pid 5104] exit_group(0 [pid 5100] <... getdents64 resumed>0x5555655796f0 /* 4 entries */, 32768) = 112 [pid 5102] openat(AT_FDCWD, "./file0", O_RDONLY [pid 5104] <... exit_group resumed>) = ? [pid 5106] <... chdir resumed>) = 0 [pid 5104] +++ exited with 0 +++ [pid 5100] umount2("./0/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5099] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5104, si_uid=0, si_status=0, si_utime=8 /* 0.08 s */, si_stime=32 /* 0.32 s */} --- [pid 5102] <... openat resumed>) = 4 [pid 5105] <... openat resumed>) = 4 [pid 5100] <... umount2 resumed>) = -1 EINVAL (Invalid argument) [pid 5105] exit_group(0) = ? [pid 5106] rename("./file0", "./file3" [pid 5105] +++ exited with 0 +++ [pid 5102] exit_group(0 [pid 5100] newfstatat(AT_FDCWD, "./0/binderfs", [pid 5099] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5098] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5105, si_uid=0, si_status=0, si_utime=7 /* 0.07 s */, si_stime=32 /* 0.32 s */} --- [pid 5099] <... umount2 resumed>) = -1 EINVAL (Invalid argument) [ 77.751878][ T5104] read_mapping_page failed! [ 77.756463][ T5104] ERROR: (device loop2): txCommit: [ 77.756463][ T5104] [ 77.758788][ T5102] read_mapping_page failed! [ 77.764587][ T5105] read_mapping_page failed! [ 77.782411][ T5105] ERROR: (device loop1): txCommit: [ 77.782411][ T5105] [ 77.791106][ T5102] ERROR: (device loop0): txCommit: [ 77.791106][ T5102] [pid 5099] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY [pid 5100] <... newfstatat resumed>{st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5102] <... exit_group resumed>) = ? [pid 5099] <... openat resumed>) = 3 [pid 5098] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5100] unlink("./0/binderfs" [pid 5099] newfstatat(3, "", [pid 5098] <... umount2 resumed>) = -1 EINVAL (Invalid argument) [pid 5098] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5102] +++ exited with 0 +++ [pid 5100] <... unlink resumed>) = 0 [pid 5099] <... newfstatat resumed>{st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5098] newfstatat(3, "", [pid 5100] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5099] getdents64(3, [pid 5098] <... newfstatat resumed>{st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5106] <... rename resumed>) = -1 EIO (Input/output error) [pid 5099] <... getdents64 resumed>0x5555655796f0 /* 4 entries */, 32768) = 112 [pid 5106] openat(AT_FDCWD, "./file0", O_RDONLY [pid 5098] getdents64(3, [pid 5097] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5102, si_uid=0, si_status=0, si_utime=10 /* 0.10 s */, si_stime=24 /* 0.24 s */} --- [pid 5097] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5099] umount2("./0/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5098] <... getdents64 resumed>0x5555655796f0 /* 4 entries */, 32768) = 112 [pid 5097] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY [pid 5099] <... umount2 resumed>) = -1 EINVAL (Invalid argument) [pid 5097] <... openat resumed>) = 3 [pid 5099] newfstatat(AT_FDCWD, "./0/binderfs", [pid 5097] newfstatat(3, "", [pid 5099] <... newfstatat resumed>{st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5097] <... newfstatat resumed>{st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5099] unlink("./0/binderfs" [pid 5098] umount2("./0/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5097] getdents64(3, [pid 5106] <... openat resumed>) = 4 [pid 5106] exit_group(0 [pid 5099] <... unlink resumed>) = 0 [pid 5098] <... umount2 resumed>) = -1 EINVAL (Invalid argument) [pid 5106] <... exit_group resumed>) = ? [pid 5106] +++ exited with 0 +++ [pid 5099] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5098] newfstatat(AT_FDCWD, "./0/binderfs", [pid 5097] <... getdents64 resumed>0x5555655796f0 /* 4 entries */, 32768) = 112 [pid 5098] <... newfstatat resumed>{st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5097] umount2("./0/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5101] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5106, si_uid=0, si_status=0, si_utime=12 /* 0.12 s */, si_stime=32 /* 0.32 s */} --- [pid 5098] unlink("./0/binderfs" [pid 5097] newfstatat(AT_FDCWD, "./0/binderfs", {st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5098] <... unlink resumed>) = 0 [pid 5097] unlink("./0/binderfs") = 0 [ 77.825351][ T5106] find_entry called with index = 0 [ 77.834999][ T5106] read_mapping_page failed! [ 77.840095][ T5106] ERROR: (device loop4): txCommit: [ 77.840095][ T5106] [pid 5101] umount2("./0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5098] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5097] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5101] <... umount2 resumed>) = -1 EINVAL (Invalid argument) [pid 5101] openat(AT_FDCWD, "./0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5101] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5101] getdents64(3, 0x5555655796f0 /* 4 entries */, 32768) = 112 [pid 5101] umount2("./0/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5101] newfstatat(AT_FDCWD, "./0/binderfs", {st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5101] unlink("./0/binderfs") = 0 [ 87.153096][ T9] cfg80211: failed to load regulatory.db [ 131.870547][ T5100] jfs_flush_journal: synclist not empty [ 131.876490][ T5100] metapage: ffff88807c9d2d90: 00001000 00000000 00003a48 00000000 [ 131.884476][ T5098] jfs_flush_journal: synclist not empty [ 131.885733][ T5100] metapage: ffff88807c9d2da0: 2ba13228 ffff8880 2ba13228 ffff8880 [ 131.890058][ T5098] metapage: ffff88802258cba0: 00001000 00000000 00003a48 00000000 [ 131.898714][ T5100] metapage: ffff88807c9d2db0: 00000004 00000000 00000000 00000000 [ 131.913700][ T5097] jfs_flush_journal: synclist not empty [ 131.913725][ T5097] metapage: ffff88801d3ab3e0: 00001000 00000000 00003a48 00000000 [ 131.913750][ T5097] metapage: ffff88801d3ab3f0: 21939a28 ffff8880 21939a28 ffff8880 [ 131.920119][ T5100] metapage: ffff88807c9d2dc0: 2be23000 ffff8880 00000024 00000000 [ 131.934960][ T5099] jfs_flush_journal: synclist not empty [ 131.934981][ T5099] metapage: ffff88802258caa8: 00001000 00000000 00003a48 00000000 [ 131.935006][ T5099] metapage: ffff88802258cab8: 21bf6228 ffff8880 21bf6228 ffff8880 [ 131.943640][ T5100] metapage: ffff88807c9d2dd0: 00000000 dead4ead ffffffff 00000000 [ 131.948530][ T5101] jfs_flush_journal: synclist not empty [ 131.956965][ T5100] metapage: ffff88807c9d2de0: ffffffff ffffffff 948f4f80 ffffffff [ 131.972238][ T5099] metapage: ffff88802258cac8: 00000004 00000000 00000000 00000000 [ 131.972267][ T5099] metapage: ffff88802258cad8: 7929d000 ffff8880 00000024 00000000 [ 131.972290][ T5099] metapage: ffff88802258cae8: 00000000 dead4ead ffffffff 00000000 [ 131.978171][ T5097] metapage: ffff88801d3ab400: 00000004 00000000 00000000 00000000 [ 131.985698][ T5101] metapage: ffff88801d3abc98: 00001000 00000000 00003a48 00000000 [ 131.993524][ T5098] metapage: ffff88802258cbb0: 21bf6a28 ffff8880 21bf6a28 ffff8880 [ 132.002217][ T5099] metapage: ffff88802258caf8: ffffffff ffffffff 948f4f80 ffffffff [ 132.009102][ T5097] metapage: ffff88801d3ab410: 2a9cc000 ffff8880 00000024 00000000 [ 132.017405][ T5101] metapage: ffff88801d3abca8: 2193e228 ffff8880 2193e228 ffff8880 [ 132.024835][ T5100] metapage: ffff88807c9d2df0: 92ceb818 ffffffff 00000000 00000000 [ 132.033071][ T5099] metapage: ffff88802258cb08: 92ceb818 ffffffff 00000000 00000000 [ 132.040493][ T5098] metapage: ffff88802258cbc0: 00000004 00000000 00000000 00000000 [ 132.048524][ T5099] metapage: ffff88802258cb18: 8c02a660 ffffffff 00000200 00000000 [ 132.056184][ T5097] metapage: ffff88801d3ab420: 00000000 dead4ead ffffffff 00000000 [ 132.063974][ T5101] metapage: ffff88801d3abcb8: 00000004 00000000 00000000 00000000 [ 132.071789][ T5100] metapage: ffff88807c9d2e00: 8c02a660 ffffffff 00000200 00000000 [ 132.080423][ T5099] metapage: ffff88802258cb28: 2258cb28 ffff8880 2258cb28 ffff8880 [ 132.087441][ T5098] metapage: ffff88802258cbd0: 2090f000 ffff8880 00000024 00000000 [ 132.103030][ T5101] metapage: ffff88801d3abcc8: 744b5000 ffff8880 00000024 00000000 [ 132.103056][ T5101] metapage: ffff88801d3abcd8: 00000000 dead4ead ffffffff 00000000 [ 132.103079][ T5101] metapage: ffff88801d3abce8: ffffffff ffffffff 948f4f80 ffffffff [ 132.110934][ T5097] metapage: ffff88801d3ab430: ffffffff ffffffff 948f4f80 ffffffff [ 132.134389][ T5099] metapage: ffff88802258cb38: 01e4a740 ffffea00 75bf0000 ffff8880 [ 132.134417][ T5099] metapage: ffff88802258cb48: 00001000 00003b14 00000001 00000000 [ 132.142272][ T5100] metapage: ffff88807c9d2e10: 7c9d2e10 ffff8880 7c9d2e10 ffff8880 [ 132.150490][ T5101] metapage: ffff88801d3abcf8: 92ceb818 ffffffff 00000000 00000000 [ 132.157916][ T5098] metapage: ffff88802258cbe0: 00000000 dead4ead ffffffff 00000000 [ 132.174212][ T5099] metapage: ffff88802258cb58: 21bf6000 ffff8880 [ 132.181585][ T5100] metapage: ffff88807c9d2e20: 00af88c0 ffffea00 7adac000 ffff8880 [ 132.181609][ T5100] metapage: ffff88807c9d2e30: 00001000 00003b14 00000001 00000000 [ 132.197944][ T5099] page: ffffea0001e4a740: 00fff5000000422c ffffea0001e4a708 [ 132.203754][ T5097] metapage: ffff88801d3ab440: 92ceb818 ffffffff 00000000 00000000 [ 132.211617][ T5101] metapage: ffff88801d3abd08: 8c02a660 ffffffff 00000200 00000000 [ 132.219452][ T5098] metapage: ffff88802258cbf0: ffffffff ffffffff 948f4f80 ffffffff [ 132.227586][ T5099] page: ffffea0001e4a750: ffffea0001e4a788 ffff8880781bc718 [ 132.234663][ T5100] metapage: ffff88807c9d2e40: 2ba13000 ffff8880 [ 132.242986][ T5101] metapage: ffff88801d3abd18: 1d3abd18 ffff8880 1d3abd18 ffff8880 [ 132.250456][ T5097] metapage: ffff88801d3ab450: 8c02a660 ffffffff 00000200 00000000 [ 132.258065][ T5101] metapage: ffff88801d3abd28: 01d12d40 ffffea00 23dc2000 ffff8880 [ 132.264063][ T5098] metapage: ffff88802258cc00: 92ceb818 ffffffff 00000000 00000000 [ 132.272550][ T5099] page: ffffea0001e4a760: 0000000000000024 ffff88802258caa8 [ 132.279692][ T5100] page: ffffea0000af88c0: 00fff5000000422c ffffea0001bd38c8 [ 132.288121][ T5101] metapage: ffff88801d3abd38: 00001000 00003b14 00000001 00000000 [ 132.295421][ T5097] metapage: ffff88801d3ab460: 1d3ab460 ffff8880 1d3ab460 ffff8880 [ 132.302783][ T5099] page: ffffea0001e4a770: 00000002ffffffff ffff8880162e4000 [ 132.310105][ T5100] page: ffffea0000af88d0: ffffea0001bd3908 ffff888078158658 [ 132.318575][ T5098] metapage: ffff88802258cc10: 8c02a660 ffffffff 00000200 00000000 [ 132.325804][ T5097] metapage: ffff88801d3ab470: 00aa7300 ffffea00 20d38000 ffff8880 [ 132.333054][ T5101] metapage: ffff88801d3abd48: 2193e000 ffff8880 [ 132.341087][ T5100] page: ffffea0000af88e0: 0000000000000024 ffff88807c9d2d90 [ 132.349616][ T5098] metapage: ffff88802258cc20: 2258cc20 ffff8880 2258cc20 ffff8880 [ 132.358481][ T5097] metapage: ffff88801d3ab480: 00001000 00003b14 00000001 00000000 [ 132.363152][ T5101] page: ffffea0001d12d40: 00fff5000000422c ffffea0001d12d08 [ 132.370257][ T5097] metapage: ffff88801d3ab490: 21939800 ffff8880 [ 132.377664][ T5098] metapage: ffff88802258cc30: 008243c0 ffffea00 74512000 ffff8880 [ 132.392851][ T5100] page: ffffea0000af88f0: 00000002ffffffff ffff8880162e4000 [ 132.400984][ T5101] page: ffffea0001d12d50: ffffea0001d10548 ffff88807815bdd8 [ 132.414116][ T5097] page: ffffea0000aa7300: 00fff5000000422c ffffea0000a949c8 [ 132.414143][ T5097] page: ffffea0000aa7310: ffffea0000aa7388 ffff8880781bb498 [ 132.414163][ T5097] page: ffffea0000aa7320: 0000000000000024 ffff88801d3ab3e0 [ 132.414182][ T5097] page: ffffea0000aa7330: 00000002ffffffff ffff8880162e4000 [ 132.421573][ T5098] metapage: ffff88802258cc40: 00001000 00003b14 00000001 00000000 [ 132.442777][ T5101] page: ffffea0001d12d60: 0000000000000024 ffff88801d3abc98 [ 132.445165][ T5098] metapage: ffff88802258cc50: 21bf6800 ffff8880 [ 132.451275][ T5101] page: ffffea0001d12d70: 00000002ffffffff ffff8880162e4000 [ 132.459863][ T5098] page: ffffea00008243c0: 00fff5000000422c ffffea0000824388 [ 132.487713][ T5098] page: ffffea00008243d0: ffffea0001e4a708 ffff8880781bd998 [ 132.495121][ T5098] page: ffffea00008243e0: 0000000000000024 ffff88802258cba0 [ 132.502513][ T5098] page: ffffea00008243f0: 00000002ffffffff ffff8880162e4000 [ 186.430453][ T5100] jfs_flush_journal: synclist not empty [ 186.436274][ T5100] metapage: ffff88807c9d2d90: 00001000 00000000 00003a48 00000000 [ 186.444296][ T5100] metapage: ffff88807c9d2da0: 2ba13228 ffff8880 2ba13228 ffff8880 [ 186.452183][ T5100] metapage: ffff88807c9d2db0: 00000004 00000000 00000000 00000000 [ 186.460030][ T5100] metapage: ffff88807c9d2dc0: 2be23000 ffff8880 00000024 00000000 [ 186.467946][ T5100] metapage: ffff88807c9d2dd0: 00000000 dead4ead ffffffff 00000000 [ 186.475863][ T5100] metapage: ffff88807c9d2de0: ffffffff ffffffff 948f4f80 ffffffff [ 186.483789][ T5100] metapage: ffff88807c9d2df0: 92ceb818 ffffffff 00000000 00000000 [ 186.491705][ T5100] metapage: ffff88807c9d2e00: 8c02a660 ffffffff 00000200 00000000 [ 186.499543][ T5100] metapage: ffff88807c9d2e10: 7c9d2e10 ffff8880 7c9d2e10 ffff8880 [ 186.507490][ T5100] metapage: ffff88807c9d2e20: 00af88c0 ffffea00 7adac000 ffff8880 [ 186.515364][ T5100] metapage: ffff88807c9d2e30: 00001000 00003b14 00000001 00000000 [ 186.523252][ T5100] metapage: ffff88807c9d2e40: 2ba13000 ffff8880 [pid 5101] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5100] <... umount2 resumed>) = 0 [pid 5100] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5100] newfstatat(AT_FDCWD, "./0/file0", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5100] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5100] openat(AT_FDCWD, "./0/file0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 4 [pid 5100] newfstatat(4, "", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5100] getdents64(4, 0x555565581730 /* 2 entries */, 32768) = 48 [pid 5100] getdents64(4, 0x555565581730 /* 0 entries */, 32768) = 0 [ 186.529519][ T5100] page: ffffea0000af88c0: 00fff5000000422c ffffea0001bd38c8 [ 186.536894][ T5100] page: ffffea0000af88d0: ffffea0001bd3908 ffff888078158658 [ 186.544249][ T5100] page: ffffea0000af88e0: 0000000000000024 ffff88807c9d2d90 [ 186.551621][ T5100] page: ffffea0000af88f0: 00000002ffffffff ffff8880162e4000 [pid 5100] close(4) = 0 [pid 5100] rmdir("./0/file0") = 0 [pid 5100] getdents64(3, 0x5555655796f0 /* 0 entries */, 32768) = 0 [pid 5100] close(3) = 0 [pid 5100] rmdir("./0") = 0 [pid 5100] mkdir("./1", 0777) = 0 [pid 5100] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = 3 [pid 5100] ioctl(3, LOOP_CLR_FD) = 0 [pid 5100] close(3) = 0 [pid 5100] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5129 attached [pid 5129] set_robust_list(0x555565578660, 24 [pid 5100] <... clone resumed>, child_tidptr=0x555565578650) = 5129 [pid 5129] <... set_robust_list resumed>) = 0 [pid 5129] chdir("./1") = 0 [pid 5129] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5129] setpgid(0, 0) = 0 [pid 5129] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5129] write(3, "1000", 4) = 4 [pid 5129] close(3) = 0 [pid 5129] symlink("/dev/binderfs", "./binderfs") = 0 [pid 5129] memfd_create("syzkaller", 0) = 3 [pid 5129] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f46dd800000 [pid 5129] 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 5129] munmap(0x7f46dd800000, 138412032) = 0 [pid 5129] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = 4 [pid 5129] ioctl(4, LOOP_SET_FD, 3) = 0 [pid 5129] close(3) = 0 [pid 5129] close(4) = 0 [pid 5129] mkdir("./file0", 0777) = 0 [pid 5129] mount("/dev/loop3", "./file0", "jfs", MS_NOSUID, "quota,iocharset=cp1250,nodiscard,gid=0x000000000000ee00,discard=0x0000000000000009,errors=continue,q"...) = 0 [pid 5129] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY) = 3 [pid 5129] chdir("./file0") = 0 [pid 5129] openat(AT_FDCWD, "/dev/loop3", O_RDWR) = -1 EBUSY (Device or resource busy) [pid 5129] chdir("./file0") = 0 [ 187.094931][ T5129] loop3: detected capacity change from 0 to 32768 [pid 5129] rename("./file0", "./file3") = -1 EIO (Input/output error) [pid 5129] openat(AT_FDCWD, "./file0", O_RDONLY) = 4 [pid 5129] exit_group(0) = ? [pid 5129] +++ exited with 0 +++ [pid 5100] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5129, si_uid=0, si_status=0, si_utime=2 /* 0.02 s */, si_stime=19 /* 0.19 s */} --- [pid 5100] umount2("./1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5100] openat(AT_FDCWD, "./1", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5100] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5100] getdents64(3, 0x5555655796f0 /* 4 entries */, 32768) = 112 [pid 5100] umount2("./1/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5100] newfstatat(AT_FDCWD, "./1/binderfs", {st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [ 187.153013][ T5129] find_entry called with index = 0 [ 187.158393][ T5129] read_mapping_page failed! [ 187.163319][ T5129] ERROR: (device loop3): txCommit: [ 187.163319][ T5129] [pid 5100] unlink("./1/binderfs") = 0 [ 240.570411][ T5097] jfs_flush_journal: synclist not empty [ 240.576013][ T5097] metapage: ffff88801d3ab3e0: 00001000 00000000 00003a48 00000000 [ 240.583944][ T5097] metapage: ffff88801d3ab3f0: 21939a28 ffff8880 21939a28 ffff8880 [ 240.591866][ T5097] metapage: ffff88801d3ab400: 00000004 00000000 00000000 00000000 [ 240.599733][ T5097] metapage: ffff88801d3ab410: 2a9cc000 ffff8880 00000024 00000000 [ 240.607693][ T5097] metapage: ffff88801d3ab420: 00000000 dead4ead ffffffff 00000000 [ 240.615594][ T5097] metapage: ffff88801d3ab430: ffffffff ffffffff 948f4f80 ffffffff [ 240.623538][ T5097] metapage: ffff88801d3ab440: 92ceb818 ffffffff 00000000 00000000 [ 240.631433][ T5097] metapage: ffff88801d3ab450: 8c02a660 ffffffff 00000200 00000000 [ 240.639240][ T5097] metapage: ffff88801d3ab460: 1d3ab460 ffff8880 1d3ab460 ffff8880 [ 240.647129][ T5097] metapage: ffff88801d3ab470: 00aa7300 ffffea00 20d38000 ffff8880 [ 240.655046][ T5097] metapage: ffff88801d3ab480: 00001000 00003b14 00000001 00000000 [ 240.663273][ T5097] metapage: ffff88801d3ab490: 21939800 ffff8880 [pid 5100] umount2("./1/file0", MNT_FORCE|UMOUNT_NOFOLLOW [pid 5097] <... umount2 resumed>) = 0 [pid 5097] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5097] newfstatat(AT_FDCWD, "./0/file0", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5097] umount2("./0/file0", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5097] openat(AT_FDCWD, "./0/file0", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 4 [pid 5097] newfstatat(4, "", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5097] getdents64(4, 0x555565581730 /* 2 entries */, 32768) = 48 [pid 5097] getdents64(4, 0x555565581730 /* 0 entries */, 32768) = 0 [pid 5097] close(4) = 0 [pid 5097] rmdir("./0/file0") = 0 [pid 5097] getdents64(3, 0x5555655796f0 /* 0 entries */, 32768) = 0 [pid 5097] close(3) = 0 [pid 5097] rmdir("./0") = 0 [pid 5097] mkdir("./1", 0777) = 0 [pid 5097] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 3 [ 240.669610][ T5097] page: ffffea0000aa7300: 00fff5000000422c ffffea0000a949c8 [ 240.677065][ T5097] page: ffffea0000aa7310: ffffea0000aa7388 ffff8880781bb498 [ 240.684478][ T5097] page: ffffea0000aa7320: 0000000000000024 ffff88801d3ab3e0 [ 240.691894][ T5097] page: ffffea0000aa7330: 00000002ffffffff ffff8880162e4000 [pid 5097] ioctl(3, LOOP_CLR_FD) = 0 [pid 5097] close(3) = 0 [pid 5097] clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD./strace-static-x86_64: Process 5137 attached , child_tidptr=0x555565578650) = 5137 [pid 5137] set_robust_list(0x555565578660, 24) = 0 [pid 5137] chdir("./1") = 0 [pid 5137] prctl(PR_SET_PDEATHSIG, SIGKILL) = 0 [pid 5137] setpgid(0, 0) = 0 [pid 5137] openat(AT_FDCWD, "/proc/self/oom_score_adj", O_WRONLY|O_CLOEXEC) = 3 [pid 5137] write(3, "1000", 4) = 4 [pid 5137] close(3) = 0 [pid 5137] symlink("/dev/binderfs", "./binderfs") = 0 [pid 5137] memfd_create("syzkaller", 0) = 3 [pid 5137] mmap(NULL, 138412032, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f46dd800000 [pid 5137] 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 5137] munmap(0x7f46dd800000, 138412032) = 0 [pid 5137] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = 4 [pid 5137] ioctl(4, LOOP_SET_FD, 3) = 0 [pid 5137] close(3) = 0 [pid 5137] close(4) = 0 [pid 5137] mkdir("./file0", 0777) = 0 [pid 5137] mount("/dev/loop0", "./file0", "jfs", MS_NOSUID, "quota,iocharset=cp1250,nodiscard,gid=0x000000000000ee00,discard=0x0000000000000009,errors=continue,q"...) = 0 [pid 5137] openat(AT_FDCWD, "./file0", O_RDONLY|O_DIRECTORY) = 3 [ 241.213935][ T5137] loop0: detected capacity change from 0 to 32768 [ 241.230438][ T5100] jfs_flush_journal: synclist not empty [ 241.236089][ T5100] metapage: ffff888022589aa8: 00001000 00000000 00003a48 00000000 [ 241.244901][ T5100] metapage: ffff888022589ab8: 23c11228 ffff8880 23c11228 ffff8880 [ 241.252949][ T5100] metapage: ffff888022589ac8: 00000004 00000000 00000000 00000000 [pid 5137] chdir("./file0") = 0 [pid 5137] openat(AT_FDCWD, "/dev/loop0", O_RDWR) = -1 EBUSY (Device or resource busy) [pid 5137] chdir("./file0") = 0 [ 241.261213][ T5100] metapage: ffff888022589ad8: 22945000 ffff8880 00000024 00000000 [ 241.269112][ T5100] metapage: ffff888022589ae8: 00000000 dead4ead ffffffff 00000000 [ 241.277200][ T5100] metapage: ffff888022589af8: ffffffff ffffffff 948f4f80 ffffffff [ 241.285501][ T5100] metapage: ffff888022589b08: 92ceb818 ffffffff 00000000 00000000 [ 241.291725][ T5137] find_entry called with index = 0 [ 241.293471][ T5100] metapage: ffff888022589b18: 8c02a660 ffffffff 00000200 00000000 [ 241.299976][ T5137] read_mapping_page failed! [pid 5137] rename("./file0", "./file3") = -1 EIO (Input/output error) [pid 5137] openat(AT_FDCWD, "./file0", O_RDONLY) = 4 [pid 5137] exit_group(0) = ? [pid 5137] +++ exited with 0 +++ [pid 5097] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=5137, si_uid=0, si_status=0, si_utime=4 /* 0.04 s */, si_stime=23 /* 0.23 s */} --- [pid 5097] umount2("./1", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5097] openat(AT_FDCWD, "./1", O_RDONLY|O_NONBLOCK|O_CLOEXEC|O_DIRECTORY) = 3 [pid 5097] newfstatat(3, "", {st_mode=S_IFDIR|0700, st_size=4096, ...}, AT_EMPTY_PATH) = 0 [pid 5097] getdents64(3, 0x5555655796f0 /* 4 entries */, 32768) = 112 [pid 5097] umount2("./1/binderfs", MNT_FORCE|UMOUNT_NOFOLLOW) = -1 EINVAL (Invalid argument) [pid 5097] newfstatat(AT_FDCWD, "./1/binderfs", {st_mode=S_IFLNK|0777, st_size=13, ...}, AT_SYMLINK_NOFOLLOW) = 0 [pid 5097] unlink("./1/binderfs") = 0 [ 241.311711][ T5137] ERROR: (device loop0): txCommit: [ 241.311711][ T5137] [ 241.319409][ T5100] metapage: ffff888022589b28: 22589b28 ffff8880 22589b28 ffff8880 [ 241.328234][ T5100] metapage: ffff888022589b38: 008a5140 ffffea00 260f2000 ffff8880 [ 241.337225][ T5100] metapage: ffff888022589b48: 00001000 00003b14 00000001 00000000 [ 241.345893][ T5100] metapage: ffff888022589b58: 23c11000 ffff8880 [ 241.352516][ T5100] page: ffffea00008a5140: 00fff5000000422c ffffea000079c088 [ 241.359821][ T5100] page: ffffea00008a5150: ffffea00008bfb48 ffff888078122b58 [ 241.367571][ T5100] page: ffffea00008a5160: 0000000000000024 ffff888022589aa8 [ 241.375192][ T5100] page: ffffea00008a5170: 00000002ffffffff ffff8880162e4000 [ 286.830490][ T30] INFO: task syz-executor249:5098 blocked for more than 143 seconds. [ 286.838736][ T30] Not tainted 6.9.0-next-20240513-syzkaller #0 [ 286.845553][ T30] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 286.854320][ T30] task:syz-executor249 state:D stack:24912 pid:5098 tgid:5098 ppid:5096 flags:0x00004002 [ 286.864601][ T30] Call Trace: [ 286.867991][ T30] [ 286.871073][ T30] __schedule+0x17e8/0x4a50 [ 286.875789][ T30] ? __pfx___schedule+0x10/0x10 [ 286.880817][ T30] ? __pfx_lock_release+0x10/0x10 [ 286.885952][ T30] ? __mutex_trylock_common+0x92/0x2e0 [ 286.891594][ T30] ? schedule+0x90/0x320 [ 286.895990][ T30] schedule+0x14b/0x320 [ 286.900170][ T30] schedule_preempt_disabled+0x13/0x30 [ 286.905766][ T30] __mutex_lock+0x6a4/0xd70 [ 286.910364][ T30] ? __mutex_lock+0x527/0xd70 [ 286.915089][ T30] ? lmLogClose+0xb2/0x530 [ 286.919631][ T30] ? __pfx___mutex_lock+0x10/0x10 [ 286.924937][ T30] ? updateSuper+0x13d/0x760 [ 286.929668][ T30] lmLogClose+0xb2/0x530 [ 286.934010][ T30] jfs_umount+0x2ce/0x3a0 [ 286.938388][ T30] jfs_put_super+0x8a/0x190 [ 286.942997][ T30] ? __pfx_jfs_put_super+0x10/0x10 [ 286.948144][ T30] generic_shutdown_super+0x136/0x2d0 [ 286.953656][ T30] kill_block_super+0x44/0x90 [ 286.958390][ T30] deactivate_locked_super+0xc4/0x130 [ 286.964000][ T30] cleanup_mnt+0x426/0x4c0 [ 286.968494][ T30] ? _raw_spin_unlock_irq+0x23/0x50 [ 286.973900][ T30] task_work_run+0x24f/0x310 [ 286.978575][ T30] ? __pfx_task_work_run+0x10/0x10 [ 286.983993][ T30] ? path_umount+0x284/0xf80 [ 286.988630][ T30] ptrace_notify+0x2d2/0x380 [ 286.993304][ T30] ? __pfx_path_umount+0x10/0x10 [ 286.998252][ T30] ? user_path_at_empty+0x4c/0x60 [ 287.003387][ T30] ? __pfx_ptrace_notify+0x10/0x10 [ 287.008600][ T30] ? __x64_sys_umount+0x126/0x170 [ 287.013736][ T30] ? __pfx___x64_sys_umount+0x10/0x10 [ 287.019149][ T30] syscall_exit_work+0xc6/0x190 [ 287.024115][ T30] syscall_exit_to_user_mode+0x273/0x370 [ 287.029897][ T30] do_syscall_64+0x102/0x240 [ 287.034564][ T30] ? clear_bhb_loop+0x35/0x90 [ 287.039354][ T30] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 287.045422][ T30] RIP: 0033:0x7f46e5dc15b7 [ 287.049871][ T30] RSP: 002b:00007fff5e771d28 EFLAGS: 00000206 ORIG_RAX: 00000000000000a6 [ 287.058426][ T30] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007f46e5dc15b7 [ 287.066489][ T30] RDX: 0000000000000000 RSI: 0000000000000009 RDI: 00007fff5e771de0 [ 287.074651][ T30] RBP: 00007fff5e771de0 R08: 0000000000000000 R09: 0000000000000000 [ 287.082703][ T30] R10: 00000000ffffffff R11: 0000000000000206 R12: 00007fff5e772e50 [ 287.090754][ T30] R13: 00005555655796c0 R14: 431bde82d7b634db R15: 00007fff5e772e70 [ 287.098783][ T30] [ 287.102123][ T30] INFO: task syz-executor249:5099 blocked for more than 143 seconds. [ 287.110273][ T30] Not tainted 6.9.0-next-20240513-syzkaller #0 [ 287.116987][ T30] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 287.125776][ T30] task:syz-executor249 state:D stack:24816 pid:5099 tgid:5099 ppid:5096 flags:0x00004002 [ 287.136044][ T30] Call Trace: [ 287.139344][ T30] [ 287.142446][ T30] __schedule+0x17e8/0x4a50 [ 287.147026][ T30] ? __pfx___schedule+0x10/0x10 [ 287.152028][ T30] ? __pfx_lock_release+0x10/0x10 [ 287.157092][ T30] ? __mutex_trylock_common+0x92/0x2e0 [ 287.162695][ T30] ? schedule+0x90/0x320 [ 287.166972][ T30] schedule+0x14b/0x320 [ 287.171213][ T30] schedule_preempt_disabled+0x13/0x30 [ 287.176706][ T30] __mutex_lock+0x6a4/0xd70 [ 287.181380][ T30] ? __mutex_lock+0x527/0xd70 [ 287.186093][ T30] ? lmLogClose+0xb2/0x530 [ 287.190607][ T30] ? __pfx___mutex_lock+0x10/0x10 [ 287.195703][ T30] ? updateSuper+0x13d/0x760 [ 287.200429][ T30] lmLogClose+0xb2/0x530 [ 287.204739][ T30] jfs_umount+0x2ce/0x3a0 [ 287.209124][ T30] jfs_put_super+0x8a/0x190 [ 287.213821][ T30] ? __pfx_jfs_put_super+0x10/0x10 [ 287.218982][ T30] generic_shutdown_super+0x136/0x2d0 [ 287.224488][ T30] kill_block_super+0x44/0x90 [ 287.229377][ T30] deactivate_locked_super+0xc4/0x130 [ 287.234904][ T30] cleanup_mnt+0x426/0x4c0 [ 287.239375][ T30] ? _raw_spin_unlock_irq+0x23/0x50 [ 287.244803][ T30] task_work_run+0x24f/0x310 [ 287.249452][ T30] ? __pfx_task_work_run+0x10/0x10 [ 287.254657][ T30] ? path_umount+0x284/0xf80 [ 287.259368][ T30] ptrace_notify+0x2d2/0x380 [ 287.264077][ T30] ? __pfx_path_umount+0x10/0x10 [ 287.269071][ T30] ? user_path_at_empty+0x4c/0x60 [ 287.274211][ T30] ? __pfx_ptrace_notify+0x10/0x10 [ 287.279376][ T30] ? __x64_sys_umount+0x126/0x170 [ 287.284504][ T30] ? __pfx___x64_sys_umount+0x10/0x10 [ 287.289914][ T30] syscall_exit_work+0xc6/0x190 [ 287.294930][ T30] syscall_exit_to_user_mode+0x273/0x370 [ 287.300685][ T30] do_syscall_64+0x102/0x240 [ 287.305377][ T30] ? clear_bhb_loop+0x35/0x90 [ 287.310063][ T30] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 287.316041][ T30] RIP: 0033:0x7f46e5dc15b7 [ 287.320547][ T30] RSP: 002b:00007fff5e771d28 EFLAGS: 00000206 ORIG_RAX: 00000000000000a6 [ 287.328989][ T30] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 00007f46e5dc15b7 [ 287.337029][ T30] RDX: 0000000000000000 RSI: 0000000000000009 RDI: 00007fff5e771de0 [ 287.345094][ T30] RBP: 00007fff5e771de0 R08: 0000000000000000 R09: 0000000000000000 [ 287.353227][ T30] R10: 00000000ffffffff R11: 0000000000000206 R12: 00007fff5e772e50 [ 287.361319][ T30] R13: 00005555655796c0 R14: 431bde82d7b634db R15: 00007fff5e772e70 [ 287.369339][ T30] [ 287.372489][ T30] [ 287.372489][ T30] Showing all locks held in the system: [ 287.380351][ T30] 5 locks held by kworker/u8:0/11: [ 287.385586][ T30] 1 lock held by khungtaskd/30: [ 287.390565][ T30] #0: ffffffff8e333e60 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x55/0x2a0 [ 287.400608][ T30] 2 locks held by getty/4849: [ 287.405300][ T30] #0: ffff88802a84f0a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 [ 287.415307][ T30] #1: ffffc900031332f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x6b5/0x1e10 [ 287.425519][ T30] 1 lock held by syz-executor249/5097: [ 287.431050][ T30] #0: ffff888079e2a0e0 (&type->s_umount_key#43){+.+.}-{3:3}, at: deactivate_super+0xb5/0xf0 [ 287.441550][ T30] 2 locks held by syz-executor249/5098: [ 287.447116][ T30] #0: ffff8880745120e0 (&type->s_umount_key#43){+.+.}-{3:3}, at: deactivate_super+0xb5/0xf0 [ 287.457706][ T30] #1: ffffffff8e7412e8 (jfs_log_mutex){+.+.}-{3:3}, at: lmLogClose+0xb2/0x530 [ 287.466856][ T30] 2 locks held by syz-executor249/5099: [ 287.472593][ T30] #0: ffff888075bf00e0 (&type->s_umount_key#43){+.+.}-{3:3}, at: deactivate_super+0xb5/0xf0 [ 287.483012][ T30] #1: ffffffff8e7412e8 (jfs_log_mutex){+.+.}-{3:3}, at: lmLogClose+0xb2/0x530 [ 287.492184][ T30] 2 locks held by syz-executor249/5100: [ 287.497791][ T30] #0: ffff8880260f20e0 (&type->s_umount_key#43){+.+.}-{3:3}, at: deactivate_super+0xb5/0xf0 [ 287.508157][ T30] #1: ffffffff8e7412e8 (jfs_log_mutex){+.+.}-{3:3}, at: lmLogClose+0xb2/0x530 [ 287.517312][ T30] 2 locks held by syz-executor249/5101: [ 287.523050][ T30] #0: ffff888023dc20e0 (&type->s_umount_key#43){+.+.}-{3:3}, at: deactivate_super+0xb5/0xf0 [ 287.533320][ T30] #1: ffffffff8e7412e8 (jfs_log_mutex){+.+.}-{3:3}, at: lmLogClose+0xb2/0x530 [ 287.542439][ T30] [ 287.544775][ T30] ============================================= [ 287.544775][ T30] [ 287.553391][ T30] NMI backtrace for cpu 1 [ 287.557741][ T30] CPU: 1 PID: 30 Comm: khungtaskd Not tainted 6.9.0-next-20240513-syzkaller #0 [ 287.566770][ T30] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/02/2024 [ 287.577052][ T30] Call Trace: [ 287.580347][ T30] [ 287.583281][ T30] dump_stack_lvl+0x241/0x360 [ 287.588060][ T30] ? __pfx_dump_stack_lvl+0x10/0x10 [ 287.593260][ T30] ? __pfx__printk+0x10/0x10 [ 287.597927][ T30] ? vprintk_emit+0x631/0x770 [ 287.602615][ T30] ? __pfx_vprintk_emit+0x10/0x10 [ 287.607650][ T30] nmi_cpu_backtrace+0x49c/0x4d0 [ 287.612640][ T30] ? __pfx_nmi_cpu_backtrace+0x10/0x10 [ 287.618099][ T30] ? _printk+0xd5/0x120 [ 287.622274][ T30] ? __pfx__printk+0x10/0x10 [ 287.626893][ T30] ? __wake_up_klogd+0xcc/0x110 [ 287.631753][ T30] ? __pfx__printk+0x10/0x10 [ 287.636353][ T30] ? __rcu_read_unlock+0xa1/0x110 [ 287.641485][ T30] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10 [ 287.647481][ T30] nmi_trigger_cpumask_backtrace+0x198/0x320 [ 287.653466][ T30] watchdog+0xfde/0x1020 [ 287.657736][ T30] ? watchdog+0x1ea/0x1020 [ 287.662159][ T30] ? __pfx_watchdog+0x10/0x10 [ 287.666839][ T30] kthread+0x2f0/0x390 [ 287.670913][ T30] ? __pfx_watchdog+0x10/0x10 [ 287.675590][ T30] ? __pfx_kthread+0x10/0x10 [ 287.680196][ T30] ret_from_fork+0x4b/0x80 [ 287.684618][ T30] ? __pfx_kthread+0x10/0x10 [ 287.689218][ T30] ret_from_fork_asm+0x1a/0x30 [ 287.694009][ T30] [ 287.697168][ T30] Sending NMI from CPU 1 to CPUs 0: [ 287.702459][ C0] NMI backtrace for cpu 0 [ 287.702471][ C0] CPU: 0 PID: 11 Comm: kworker/u8:0 Not tainted 6.9.0-next-20240513-syzkaller #0 [ 287.702490][ C0] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/02/2024 [ 287.702501][ C0] Workqueue: events_unbound toggle_allocation_gate [ 287.702529][ C0] RIP: 0010:flush_tlb_mm_range+0x107/0x5c0 [ 287.702560][ C0] Code: 48 89 44 24 18 48 8b 5c 24 10 48 8d bb 18 06 00 00 be 08 00 00 00 e8 b8 78 b9 00 41 bd 01 00 00 00 f0 4c 0f c1 ab 18 06 00 00 44 9c 48 0a 89 c3 83 f8 08 0f 83 6e 04 00 00 48 8d 1c dd e0 99 [ 287.702574][ C0] RSP: 0018:ffffc90000107660 EFLAGS: 00000002 [ 287.702588][ C0] RAX: 0000000000000001 RBX: ffff888015070000 RCX: ffffffff81429008 [ 287.702601][ C0] RDX: 0000000000000001 RSI: 0000000000000008 RDI: ffff888015070618 [ 287.702612][ C0] RBP: ffffc90000107730 R08: ffff88801507061f R09: 1ffff11002a0e0c3 [ 287.702625][ C0] R10: dffffc0000000000 R11: ffffed1002a0e0c4 R12: 0000000000000000 [ 287.702637][ C0] R13: 0000000000009cf1 R14: 000000000000000c R15: 000000000000000c [ 287.702648][ C0] FS: 0000000000000000(0000) GS:ffff8880b9400000(0000) knlGS:0000000000000000 [ 287.702662][ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 287.702674][ C0] CR2: 00005555655816f8 CR3: 000000000e132000 CR4: 00000000003506f0 [ 287.702695][ C0] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [ 287.702705][ C0] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [ 287.702716][ C0] Call Trace: [ 287.702723][ C0] [ 287.702729][ C0] ? nmi_cpu_backtrace+0x3c2/0x4d0 [ 287.702753][ C0] ? __pfx_lock_acquire+0x10/0x10 [ 287.702775][ C0] ? __pfx_nmi_cpu_backtrace+0x10/0x10 [ 287.702796][ C0] ? nmi_handle+0x2a/0x5a0 [ 287.702844][ C0] ? nmi_cpu_backtrace_handler+0xc/0x20 [ 287.702865][ C0] ? nmi_handle+0x14f/0x5a0 [ 287.702881][ C0] ? nmi_handle+0x2a/0x5a0 [ 287.702898][ C0] ? flush_tlb_mm_range+0x107/0x5c0 [ 287.702925][ C0] ? default_do_nmi+0x63/0x160 [ 287.702947][ C0] ? exc_nmi+0x123/0x1f0 [ 287.702968][ C0] ? end_repeat_nmi+0xf/0x53 [ 287.702992][ C0] ? flush_tlb_mm_range+0xf8/0x5c0 [ 287.703019][ C0] ? flush_tlb_mm_range+0x107/0x5c0 [ 287.703046][ C0] ? flush_tlb_mm_range+0x107/0x5c0 [ 287.703074][ C0] ? flush_tlb_mm_range+0x107/0x5c0 [ 287.703101][ C0] [ 287.703106][ C0] [ 287.703116][ C0] ? __pfx_flush_tlb_mm_range+0x10/0x10 [ 287.703143][ C0] ? text_poke_memcpy+0x25/0x30 [ 287.703163][ C0] ? __asan_memcpy+0x40/0x70 [ 287.703183][ C0] ? __kmalloc_noprof+0xf5/0x410 [ 287.703213][ C0] __text_poke+0x955/0xd30 [ 287.703231][ C0] ? __pfx_do_sync_core+0x10/0x10 [ 287.703252][ C0] ? __kmalloc_noprof+0xf5/0x410 [ 287.703278][ C0] ? __pfx_text_poke_memcpy+0x10/0x10 [ 287.703300][ C0] ? __pfx___text_poke+0x10/0x10 [ 287.703318][ C0] ? kmem_cache_alloc_bulk_noprof+0x146/0x770 [ 287.703349][ C0] ? __pfx_smp_call_function_many_cond+0x10/0x10 [ 287.703377][ C0] ? __pfx_perf_event_text_poke+0x10/0x10 [ 287.703407][ C0] ? __mutex_trylock_common+0x183/0x2e0 [ 287.703432][ C0] ? __pfx___might_resched+0x10/0x10 [ 287.703467][ C0] text_poke_bp_batch+0x8cd/0xb30 [ 287.703494][ C0] ? kmem_cache_alloc_bulk_noprof+0x147/0x770 [ 287.703521][ C0] ? __pfx_text_poke_bp_batch+0x10/0x10 [ 287.703546][ C0] ? arch_jump_label_transform_queue+0x9b/0x100 [ 287.703575][ C0] text_poke_finish+0x30/0x50 [ 287.703594][ C0] arch_jump_label_transform_apply+0x1c/0x30 [ 287.703618][ C0] static_key_enable_cpuslocked+0x136/0x260 [ 287.703646][ C0] static_key_enable+0x1a/0x20 [ 287.703671][ C0] toggle_allocation_gate+0xb5/0x250 [ 287.703698][ C0] ? __pfx_toggle_allocation_gate+0x10/0x10 [ 287.703720][ C0] ? __pfx_lockdep_hardirqs_on_prepare+0x10/0x10 [ 287.703747][ C0] ? process_scheduled_works+0x945/0x1830 [ 287.703765][ C0] process_scheduled_works+0xa2c/0x1830 [ 287.703800][ C0] ? __pfx_process_scheduled_works+0x10/0x10 [ 287.703824][ C0] ? assign_work+0x364/0x3d0 [ 287.703845][ C0] worker_thread+0x86d/0xd70 [ 287.703873][ C0] ? __kthread_parkme+0x169/0x1d0 [ 287.703895][ C0] ? __pfx_worker_thread+0x10/0x10 [ 287.703915][ C0] kthread+0x2f0/0x390 [ 287.703936][ C0] ? __pfx_worker_thread+0x10/0x10 [ 287.703955][ C0] ? __pfx_kthread+0x10/0x10 [ 287.703977][ C0] ret_from_fork+0x4b/0x80 [ 287.704002][ C0] ? __pfx_kthread+0x10/0x10 [ 287.704024][ C0] ret_from_fork_asm+0x1a/0x30 [ 287.704059][ C0] [ 287.704066][ C0] INFO: NMI handler (nmi_cpu_backtrace_handler) took too long to run: 1.607 msecs [ 287.704458][ T30] Kernel panic - not syncing: hung_task: blocked tasks [ 287.704473][ T30] CPU: 1 PID: 30 Comm: khungtaskd Not tainted 6.9.0-next-20240513-syzkaller #0 [ 287.704497][ T30] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 04/02/2024 [ 287.704510][ T30] Call Trace: [ 287.704519][ T30] [ 287.704530][ T30] dump_stack_lvl+0x241/0x360 [ 287.704559][ T30] ? __pfx_dump_stack_lvl+0x10/0x10 [ 287.704585][ T30] ? __pfx__printk+0x10/0x10 [ 287.704627][ T30] ? vscnprintf+0x5d/0x90 [ 287.704658][ T30] panic+0x349/0x860 [ 287.704694][ T30] ? nmi_trigger_cpumask_backtrace+0x244/0x320 [ 287.704725][ T30] ? __pfx_panic+0x10/0x10 [ 287.704757][ T30] ? tick_nohz_tick_stopped+0x82/0xb0 [ 287.704793][ T30] ? __irq_work_queue_local+0x137/0x410 [ 287.704846][ T30] ? irq_work_queue+0xca/0x150 [ 287.704881][ T30] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10 [ 287.704911][ T30] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10 [ 287.704940][ T30] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10 [ 287.704968][ T30] ? nmi_trigger_cpumask_backtrace+0x251/0x320 [ 287.705001][ T30] watchdog+0x101d/0x1020 [ 287.705031][ T30] ? watchdog+0x1ea/0x1020 [ 287.705064][ T30] ? __pfx_watchdog+0x10/0x10 [ 287.705090][ T30] kthread+0x2f0/0x390 [ 287.705119][ T30] ? __pfx_watchdog+0x10/0x10 [ 287.705145][ T30] ? __pfx_kthread+0x10/0x10 [ 287.705182][ T30] ret_from_fork+0x4b/0x80 [ 287.705214][ T30] ? __pfx_kthread+0x10/0x10 [ 287.705244][ T30] ret_from_fork_asm+0x1a/0x30 [ 287.705293][ T30] [ 287.708848][ T30] Kernel Offset: disabled [ 288.294377][ T30] Rebooting in 86400 seconds..