Rclone pop up an I/O error when creating a folder in a mounted FTP drive

Suspected Bug
1

What is the problem you are having with rclone?

When I try to create a new folder in Windows 10 by the context menu "New" on a FTP drive mounted by rclone. It will pop up an error window (See image 1 and image 2):

An unexpected error is keeping you from creating the folder. If you continue to receive this error, you can use the error code to search for help with this problem.
Error 0x8007045D: The request could not be performed because of an I/O device error.

I have also tried the "mkdir Z:\Newfolder" command on the command prompt. It will also pop up the same error (See image 3).

However when I closed the error window and pressed the key F5 to refresh the list, in fact, the folder is created. But the error window is really annoying (See image 4).

I hope you can fix the bug and eliminate the error window when creating a folder. Thank you!

0
02000×1000 73.5 KB

Run the command 'rclone version' and share the full output of the command.

rclone v1.65.2

  • os/version: Microsoft Windows 10 Pro 22H2 (64 bit)
  • os/kernel: 10.0.19045.3996 (x86_64)
  • os/type: windows
  • os/arch: amd64
  • go/version: go1.21.6
  • go/linking: static
  • go/tags: cmount

Which cloud storage system are you using? (eg Google Drive)

FTP

The command you were trying to run (eg rclone copy /tmp remote:tmp)

rclone.exe mount "108 21sj:/" Z: -vv --vfs-cache-mode writes --dir-cache-time 0m1s

The rclone config contents with secrets removed.

[108 21sj]
type = ftp
host = 192.168.0.108
port = 2121

A log from the command with the -vv flag

(Because of the new user limit, the log is abbreviated)

...........
2024/02/01 13:56:37 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:56:37 DEBUG : /: Statfs: 
2024/02/01 13:56:37 DEBUG : /: >Statfs: stat={Bsize:4096 Frsize:4096 Blocks:274877906944 Bfree:274877906944 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Favail:0 Fsid:0 Flag:0 Namemax:255}, errc=0
2024/02/01 13:56:37 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:56:37 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:06 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/02/01 13:57:08 DEBUG : ftp://192.168.0.108:2121: closing 1 unused connections
2024/02/01 13:57:13 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:13 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:13 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:13 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:13 DEBUG : /: Opendir: 
2024/02/01 13:57:13 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:13 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:13 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:13 DEBUG : /: Statfs: 
2024/02/01 13:57:13 DEBUG : /: >Statfs: stat={Bsize:4096 Frsize:4096 Blocks:274877906944 Bfree:274877906944 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Favail:0 Fsid:0 Flag:0 Namemax:255}, errc=0
2024/02/01 13:57:13 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:13 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:13 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:13 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:13 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:13 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:13 DEBUG : /: Opendir: 
2024/02/01 13:57:13 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:13 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:13 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:13 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:13 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Opendir: 
2024/02/01 13:57:15 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:15 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:15 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:15 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Opendir: 
2024/02/01 13:57:15 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:15 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:15 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:15 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:15 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Opendir: 
2024/02/01 13:57:15 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:15 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:15 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:15 DEBUG : /: Statfs: 
2024/02/01 13:57:15 DEBUG : /: >Statfs: stat={Bsize:4096 Frsize:4096 Blocks:274877906944 Bfree:274877906944 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Favail:0 Fsid:0 Flag:0 Namemax:255}, errc=0
2024/02/01 13:57:15 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:15 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Opendir: 
2024/02/01 13:57:15 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:15 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:15 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:15 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:15 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Opendir: 
2024/02/01 13:57:15 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:15 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:15 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:15 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:15 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:15 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:15 DEBUG : /: Opendir: 
2024/02/01 13:57:15 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:15 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:15 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:15 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:15 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:17 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:17 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:17 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:17 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:17 DEBUG : /: Opendir: 
2024/02/01 13:57:17 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:17 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:17 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:17 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:17 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:17 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:17 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:17 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:17 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:17 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:17 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:17 DEBUG : /: Opendir: 
2024/02/01 13:57:17 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:17 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:17 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:17 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:17 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:18 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:18 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:18 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:18 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:18 DEBUG : /: Opendir: 
2024/02/01 13:57:18 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:18 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:18 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:18 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:18 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:18 DEBUG : /: Readdir: ofst=0, fh=0x1
2024/02/01 13:57:18 DEBUG : ftp://192.168.0.108:2121: Connecting to FTP server
2024/02/01 13:57:18 DEBUG : ftp://192.168.0.108:2121: dial("tcp","192.168.0.108:2121")
2024/02/01 13:57:18 DEBUG : ftp://192.168.0.108:2121: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/02/01 13:57:18 DEBUG : ftp://192.168.0.108:2121: dial("tcp","192.168.0.108:46473")
2024/02/01 13:57:18 DEBUG : ftp://192.168.0.108:2121: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/02/01 13:57:18 DEBUG : /: >Readdir: items=0, errc=0
2024/02/01 13:57:18 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:18 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:18 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:18 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:18 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:18 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:18 DEBUG : /: Opendir: 
2024/02/01 13:57:18 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:18 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:18 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:18 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:18 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:18 DEBUG : /New folder: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:18 DEBUG : /New folder: >Getattr: errc=-2
2024/02/01 13:57:18 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:18 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:18 DEBUG : /New folder: Mkdir: mode=0700
2024/02/01 13:57:18 ERROR : /: Dir.Mkdir failed to create directory: 250 Directory created
2024/02/01 13:57:18 ERROR : IO error: 250 Directory created
2024/02/01 13:57:18 DEBUG : /New folder: >Mkdir: errc=-5
2024/02/01 13:57:18 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:18 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:18 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:18 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:18 DEBUG : /: Opendir: 
2024/02/01 13:57:18 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:18 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:18 DEBUG : /: >Opendir: errc=0, fh=0x1
............
2024/02/01 13:57:50 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:50 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:50 DEBUG : /: Opendir: 
2024/02/01 13:57:50 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:50 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:50 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:50 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:57:50 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:57:50 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:50 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:50 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:50 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:50 DEBUG : /: Opendir: 
2024/02/01 13:57:50 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:57:50 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:57:50 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:57:55 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:57:55 DEBUG : /: >Getattr: errc=0
2024/02/01 13:57:55 DEBUG : /Newfolder: Mkdir: mode=0700
2024/02/01 13:57:55 DEBUG : ftp://192.168.0.108:2121: dial("tcp","192.168.0.108:33815")
2024/02/01 13:57:55 DEBUG : ftp://192.168.0.108:2121: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/02/01 13:57:55 ERROR : /: Dir.Mkdir failed to create directory: 250 Directory created
2024/02/01 13:57:55 ERROR : IO error: 250 Directory created
2024/02/01 13:57:55 DEBUG : /Newfolder: >Mkdir: errc=-5
2024/02/01 13:57:57 DEBUG : : forgetting directory cache
2024/02/01 13:57:57 DEBUG : New folder: forgetting directory cache
2024/02/01 13:57:57 DEBUG : New folder: forgetting directory cache
2024/02/01 13:58:06 INFO  : vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2024/02/01 13:58:13 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:58:13 DEBUG : /: >Getattr: errc=0
2024/02/01 13:58:13 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:58:13 DEBUG : /: >Getattr: errc=0
2024/02/01 13:58:13 DEBUG : /: Opendir: 
2024/02/01 13:58:13 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:58:13 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:58:13 DEBUG : /: >Opendir: errc=0, fh=0x3
2024/02/01 13:58:13 DEBUG : /: Statfs: 
2024/02/01 13:58:13 DEBUG : /: >Statfs: stat={Bsize:4096 Frsize:4096 Blocks:274877906944 Bfree:274877906944 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Favail:0 Fsid:0 Flag:0 Namemax:255}, errc=0
2024/02/01 13:58:13 DEBUG : /: Releasedir: fh=0x3
2024/02/01 13:58:13 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:58:17 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:58:17 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:58:17 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:58:17 DEBUG : /: >Getattr: errc=0
2024/02/01 13:58:17 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:58:17 DEBUG : /: >Getattr: errc=0
2024/02/01 13:58:17 DEBUG : /: Opendir: 
2024/02/01 13:58:17 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:58:17 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:58:17 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:58:17 DEBUG : /: Statfs: 
2024/02/01 13:58:17 DEBUG : /: >Statfs: stat={Bsize:4096 Frsize:4096 Blocks:274877906944 Bfree:274877906944 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Favail:0 Fsid:0 Flag:0 Namemax:255}, errc=0
2024/02/01 13:58:17 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:58:17 DEBUG : /: >Releasedir: errc=0
2024/02/01 13:58:19 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:58:19 DEBUG : /: >Getattr: errc=0
2024/02/01 13:58:19 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2024/02/01 13:58:19 DEBUG : /: >Getattr: errc=0
2024/02/01 13:58:19 DEBUG : /: Opendir: 
2024/02/01 13:58:19 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2024/02/01 13:58:19 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2024/02/01 13:58:19 DEBUG : /: >Opendir: errc=0, fh=0x1
2024/02/01 13:58:19 DEBUG : /: Statfs: 
2024/02/01 13:58:19 DEBUG : /: >Statfs: stat={Bsize:4096 Frsize:4096 Blocks:274877906944 Bfree:274877906944 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Favail:0 Fsid:0 Flag:0 Namemax:255}, errc=0
2024/02/01 13:58:19 DEBUG : /: Releasedir: fh=0x1
2024/02/01 13:58:19 DEBUG : /: >Releasedir: errc=0
The service rclone has been stopped.
2024/02/01 13:58:21 DEBUG : ftp://192.168.0.108:2121: Destroy: 
2024/02/01 13:58:21 DEBUG : ftp://192.168.0.108:2121: >Destroy: 
2024/02/01 13:58:21 DEBUG : Not calling host.Unmount as mount already Destroyed
2024/02/01 13:58:21 DEBUG : Unmounted successfully
2024/02/01 13:58:21 DEBUG : vfs cache: cleaner exiting
2024/02/01 13:58:21 ERROR : Z:: Unmounted rclone mount
2024/02/01 13:58:21 DEBUG : rclone: Version "v1.65.2" finishing with parameters ["D:\\Scoop\\apps\\rclone\\current\\rclone.exe" "mount" "108 21sj:/" "Z:" "-vv" "--vfs-cache-mode" "writes" "--dir-cache-time" "0m1s"]
^C

The Error when creating folder by context menu is below:

2024/02/01 13:57:18 ERROR : /: Dir.Mkdir failed to create directory: 250 Directory created
2024/02/01 13:57:18 ERROR : IO error: 250 Directory created

The Error when creating folder by Command Prompt is below:

2024/02/01 13:57:55 ERROR : /: Dir.Mkdir failed to create directory: 250 Directory created
2024/02/01 13:57:55 ERROR : IO error: 250 Directory created
2

That is strange!

It looks like rclone and the FTP server have got confused somehow and are reporting a directory creation to have failed when it actually succeeded.

What is the FTP server? I would suspect this is something odd the FTP server is doing.

Can you try

rclone.exe mkdir "108 21sj:/testdirctoryname" -vv --dump bodies

And post the output please?

You might need to run it twice to get the ERROR or change the /testdirctoryname but it should reproduce the ERROR hopefully.

3

Hello! Here is the running output:
First time:

Z:\>rclone.exe mkdir "108 21sj:/testdirctoryname" -vv --dump bodies
2024/02/02 09:34:38 DEBUG : rclone: Version "v1.65.2" starting with parameters ["D:\\Scoop\\apps\\rclone\\current\\rclone.exe" "mkdir" "108 21sj:/testdirctoryname" "-vv" "--dump" "bodies"]
2024/02/02 09:34:38 DEBUG : Creating backend with remote "108 21sj:/testdirctoryname"
2024/02/02 09:34:38 DEBUG : Using config file from "D:\\Scoop\\apps\\rclone\\current\\rclone.conf"
2024/02/02 09:34:38 DEBUG : ftp://192.168.0.108:2121/testdirctoryname: Connecting to FTP server
2024/02/02 09:34:38 DEBUG : ftp://192.168.0.108:2121/testdirctoryname: dial("tcp","192.168.0.108:2121")
2024/02/02 09:34:38 DEBUG : ftp://192.168.0.108:2121/testdirctoryname: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/02/02 09:34:38 DEBUG : FTP Rx: "220 rsftp 1.6.30 ready"
2024/02/02 09:34:38 DEBUG : FTP Tx: "USER ftp"
2024/02/02 09:34:38 DEBUG : FTP Rx: "331 Send password"
2024/02/02 09:34:38 DEBUG : FTP Tx: PASS *****
2024/02/02 09:34:38 DEBUG : FTP Rx: "230 Access granted"
2024/02/02 09:34:38 DEBUG : FTP Tx: "FEAT"
2024/02/02 09:34:38 DEBUG : FTP Rx: "211-Features supported by FTP Server"
2024/02/02 09:34:39 DEBUG : FTP Rx: " UTF8"
2024/02/02 09:34:39 DEBUG : FTP Rx: " MDTM"
2024/02/02 09:34:39 DEBUG : FTP Rx: " MFMT"
2024/02/02 09:34:39 DEBUG : FTP Rx: " MLST Type*;Size*;Modify*;Perm"
2024/02/02 09:34:39 DEBUG : FTP Rx: " HASH MD5;SHA-1;SHA-256;SHA-384;SHA-512"
2024/02/02 09:34:39 DEBUG : FTP Rx: " REST STREAM"
2024/02/02 09:34:39 DEBUG : FTP Rx: " RANG STREAM"
2024/02/02 09:34:39 DEBUG : FTP Rx: "211 End"
2024/02/02 09:34:39 DEBUG : FTP Tx: "TYPE I"
2024/02/02 09:34:39 DEBUG : FTP Rx: "200 Binary type set"
2024/02/02 09:34:39 DEBUG : FTP Tx: "OPTS UTF8 ON"
2024/02/02 09:34:39 DEBUG : FTP Rx: "200 OPTS accepted"
2024/02/02 09:34:39 DEBUG : FTP Tx: "MLST /testdirctoryname"
2024/02/02 09:34:39 DEBUG : FTP Rx: "550 file does not exist"
2024/02/02 09:34:39 DEBUG : FTP Tx: "NOOP"
2024/02/02 09:34:39 DEBUG : FTP Rx: "200 NOOP ok"
2024/02/02 09:34:39 DEBUG : ftp://192.168.0.108:2121/testdirctoryname: Making directory
2024/02/02 09:34:39 DEBUG : FTP Tx: "MLST /testdirctoryname"
2024/02/02 09:34:39 DEBUG : FTP Rx: "550 file does not exist"
2024/02/02 09:34:39 DEBUG : FTP Tx: "NOOP"
2024/02/02 09:34:39 DEBUG : FTP Rx: "200 NOOP ok"
2024/02/02 09:34:39 DEBUG : FTP Tx: "MKD /testdirctoryname"
2024/02/02 09:34:39 DEBUG : FTP Rx: "250 Directory created"
2024/02/02 09:34:39 DEBUG : FTP Tx: "NOOP"
2024/02/02 09:34:39 DEBUG : FTP Rx: "200 NOOP ok"
2024/02/02 09:34:39 ERROR : Attempt 1/3 failed with 1 errors and: 250 Directory created
2024/02/02 09:34:39 DEBUG : ftp://192.168.0.108:2121/testdirctoryname: Making directory
2024/02/02 09:34:39 DEBUG : FTP Tx: "MLST /testdirctoryname"
2024/02/02 09:34:39 DEBUG : FTP Rx: "250- Listing /testdirctoryname"
2024/02/02 09:34:39 DEBUG : FTP Rx: "Size=3488;Modify=20240202013439;Type=dir;Perm=elfpcm; testdirctoryname"
2024/02/02 09:34:39 DEBUG : FTP Rx: ""
2024/02/02 09:34:39 DEBUG : FTP Rx: "250 End"
2024/02/02 09:34:39 ERROR : Attempt 2/3 succeeded
2024/02/02 09:34:39 DEBUG : 2 go routines active
2024/02/02 09:34:39 DEBUG : ftp://192.168.0.108:2121/testdirctoryname: closing 1 unused connections
2024/02/02 09:34:39 DEBUG : FTP Tx: "QUIT"

Second time:

Z:\>rclone.exe mkdir "108 21sj:/testdirctoryname1" -vv --dump bodies
2024/02/02 09:34:46 DEBUG : rclone: Version "v1.65.2" starting with parameters ["D:\\Scoop\\apps\\rclone\\current\\rclone.exe" "mkdir" "108 21sj:/testdirctoryname1" "-vv" "--dump" "bodies"]
2024/02/02 09:34:46 DEBUG : Creating backend with remote "108 21sj:/testdirctoryname1"
2024/02/02 09:34:46 DEBUG : Using config file from "D:\\Scoop\\apps\\rclone\\current\\rclone.conf"
2024/02/02 09:34:46 DEBUG : ftp://192.168.0.108:2121/testdirctoryname1: Connecting to FTP server
2024/02/02 09:34:46 DEBUG : ftp://192.168.0.108:2121/testdirctoryname1: dial("tcp","192.168.0.108:2121")
2024/02/02 09:34:46 DEBUG : ftp://192.168.0.108:2121/testdirctoryname1: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/02/02 09:34:46 DEBUG : FTP Rx: "220 rsftp 1.6.30 ready"
2024/02/02 09:34:46 DEBUG : FTP Tx: "USER ftp"
2024/02/02 09:34:46 DEBUG : FTP Rx: "331 Send password"
2024/02/02 09:34:46 DEBUG : FTP Tx: PASS *****
2024/02/02 09:34:46 DEBUG : FTP Rx: "230 Access granted"
2024/02/02 09:34:46 DEBUG : FTP Tx: "FEAT"
2024/02/02 09:34:46 DEBUG : FTP Rx: "211-Features supported by FTP Server"
2024/02/02 09:34:46 DEBUG : FTP Rx: " UTF8"
2024/02/02 09:34:46 DEBUG : FTP Rx: " MDTM"
2024/02/02 09:34:46 DEBUG : FTP Rx: " MFMT"
2024/02/02 09:34:46 DEBUG : FTP Rx: " MLST Type*;Size*;Modify*;Perm"
2024/02/02 09:34:46 DEBUG : FTP Rx: " HASH MD5;SHA-1;SHA-256;SHA-384;SHA-512"
2024/02/02 09:34:46 DEBUG : FTP Rx: " REST STREAM"
2024/02/02 09:34:46 DEBUG : FTP Rx: " RANG STREAM"
2024/02/02 09:34:46 DEBUG : FTP Rx: "211 End"
2024/02/02 09:34:46 DEBUG : FTP Tx: "TYPE I"
2024/02/02 09:34:46 DEBUG : FTP Rx: "200 Binary type set"
2024/02/02 09:34:46 DEBUG : FTP Tx: "OPTS UTF8 ON"
2024/02/02 09:34:46 DEBUG : FTP Rx: "200 OPTS accepted"
2024/02/02 09:34:46 DEBUG : FTP Tx: "MLST /testdirctoryname1"
2024/02/02 09:34:46 DEBUG : FTP Rx: "550 file does not exist"
2024/02/02 09:34:46 DEBUG : FTP Tx: "NOOP"
2024/02/02 09:34:46 DEBUG : FTP Rx: "200 NOOP ok"
2024/02/02 09:34:46 DEBUG : ftp://192.168.0.108:2121/testdirctoryname1: Making directory
2024/02/02 09:34:46 DEBUG : FTP Tx: "MLST /testdirctoryname1"
2024/02/02 09:34:46 DEBUG : FTP Rx: "550 file does not exist"
2024/02/02 09:34:46 DEBUG : FTP Tx: "NOOP"
2024/02/02 09:34:46 DEBUG : FTP Rx: "200 NOOP ok"
2024/02/02 09:34:46 DEBUG : FTP Tx: "MKD /testdirctoryname1"
2024/02/02 09:34:46 DEBUG : FTP Rx: "250 Directory created"
2024/02/02 09:34:46 DEBUG : FTP Tx: "NOOP"
2024/02/02 09:34:46 DEBUG : FTP Rx: "200 NOOP ok"
2024/02/02 09:34:46 ERROR : Attempt 1/3 failed with 1 errors and: 250 Directory created
2024/02/02 09:34:46 DEBUG : ftp://192.168.0.108:2121/testdirctoryname1: Making directory
2024/02/02 09:34:46 DEBUG : FTP Tx: "MLST /testdirctoryname1"
2024/02/02 09:34:46 DEBUG : FTP Rx: "250- Listing /testdirctoryname1"
2024/02/02 09:34:46 DEBUG : FTP Rx: "Size=3488;Modify=20240202013447;Type=dir;Perm=elfpcm; testdirctoryname1"
2024/02/02 09:34:46 DEBUG : FTP Rx: ""
2024/02/02 09:34:46 DEBUG : FTP Rx: "250 End"
2024/02/02 09:34:46 ERROR : Attempt 2/3 succeeded
2024/02/02 09:34:46 DEBUG : 2 go routines active
2024/02/02 09:34:46 DEBUG : ftp://192.168.0.108:2121/testdirctoryname1: closing 1 unused connections
2024/02/02 09:34:46 DEBUG : FTP Tx: "QUIT"

The two folders are created in fact, but it still have error and need to press F5 to refresh. then we can see the folders.

4

I think what is happening here is that according to the RFC the MKD command is supposed to return status code 257 however this FTP server is returning 250. Now 250 is still a success code but the library rclone is using for FTP is expecting 257.

I made a workaround for this here - please give it a go.

v1.66.0-beta.7678.51dd3df04.fix-ftp-mkdir on branch fix-ftp-mkdir (uploaded in 15-30 mins)

I think you should report this as a bug on the ftp server (rsftp) if you can work out how to.

1 Like
5

Thank you! This workaround really solves this problem.
By the way, I hope you can enhance the compatibility of Rclone itself to adapt to different kinds of status codes when creating a folder in a mounted FTP drive, not just 250. After all, FTP servers are different.
Looking forward to an even more enhanced version of the software.

6

Thanks for testing

I've merged this to master now which means it will be in the latest beta in 15-30 minutes and released in v1.66

7

This topic was automatically closed 3 days after the last reply. New replies are no longer allowed.