What is the problem you are having with rclone?
When using rclone to sync a local ATEM Mini Extreme ISO hardware device (FTP) to Google Drive, successful FTP downloads from ATEM to rsync report a "250 Requested file action okay, completed." rclone interprets this as an error.
(This has happened in other FTP apps, so I suspect something needs tweaked somewhere to include this 250 instead of what rclone is expecting).
Everything runs fine, except my monitoring interprets this as an error and so raises false alarms.
It only shows up when there's a new file that needs to be uploaded to Google Drive, so I suspect the 250 is being returned from the RETR command to send a file to rclone (ref: RFC959: FTP: Declarative Specifications)
Run the command 'rclone version' and share the full output of the command.
rclone v1.68.1
- os/version: darwin 13.3.1 (64 bit)
- os/kernel: 22.4.0 (arm64)
- os/type: darwin
- os/arch: arm64 (ARMv8 compatible)
- go/version: go1.23.1
- go/linking: dynamic
- go/tags: cmount
Which cloud storage system are you using? (eg Google Drive)
Google Drive
The command you were trying to run (eg rclone copy /tmp remote:tmp)
rclone sync ATEMFTP:"SSDROOT" GOOGLEDRIVE: --exclude ".*" --exclude ".Spotlight-V100/**" --exclude ".fseventsd/**" --exclude "System Volume Information/**" --exclude "$RECYCLE.BIN/**" --log-level=debug```
The rclone config contents with secrets removed.
[GOOGLEDRIVE]
type = drive
scope = drive.file
root_folder_id = <redacted>
token = <redacted>
client_id = <redacted>
client_secret = <redacted>
service_account_file = <redacted>
team_drive =
[ATEMFTP]
type = ftp
host = <redacted>
user = anonymous
pass = <redacted>
A log from the command with the -vv flag
Scenario: tst.mp4 is a new file that was created in between rclone runs.
2024/10/10 10:50:49 DEBUG : rclone: Version "v1.68.1" starting with parameters ["rclone" "-vv" "sync" "ATEMFTP:SSDROOT" "GOOGLEDRIVE:" "--exclude" ".*" "--exclude" ".Spotlight-V100/**" "--exclude" ".fseventsd/**" "--exclude" "System Volume Information/**" "--exclude" ".BIN/**"]
2024/10/10 10:50:49 DEBUG : Creating backend with remote "ATEMFTP:SSDROOT"
2024/10/10 10:50:49 DEBUG : Using config file from "<REDACTED>"
2024/10/10 10:50:49 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: Connecting to FTP server
2024/10/10 10:50:49 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: dial("tcp","<REDACTED IP>:21")
2024/10/10 10:50:49 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/10/10 10:50:49 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: dial("tcp","<REDACTED IP>:54134")
2024/10/10 10:50:49 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/10/10 10:50:49 DEBUG : Creating backend with remote "GOOGLEDRIVE:"
2024/10/10 10:50:49 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: dial("tcp","<REDACTED IP>:54137")
2024/10/10 10:50:49 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/10/10 10:50:49 DEBUG : System Volume Information: Excluded
2024/10/10 10:50:49 DEBUG : .Spotlight-V100: Exclude
2024/10/10 10:50:50 DEBUG : tst.mp4: Need to transfer - File not found at Destination
2024/10/10 10:50:50 DEBUG : Google drive root '': Waiting for checks to finish
2024/10/10 10:50:50 DEBUG : Google drive root '': Waiting for transfers to finish
2024/10/10 10:50:50 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: dial("tcp","<REDACTED IP>:54269")
2024/10/10 10:50:50 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/10/10 10:50:51 ERROR : tst.mp4: Failed to copy: 1 error occurred:
* 250 Requested file action okay, completed.
2024/10/10 10:50:51 ERROR : Google drive root '': not deleting files as there were IO errors
2024/10/10 10:50:51 ERROR : Google drive root '': not deleting directories as there were IO errors
2024/10/10 10:50:51 ERROR : Attempt 1/3 failed with 1 errors and: 1 error occurred:
* 250 Requested file action okay, completed.
2024/10/10 10:50:51 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: Connecting to FTP server
2024/10/10 10:50:51 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: dial("tcp","<REDACTED IP>:21")
2024/10/10 10:50:51 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/10/10 10:50:51 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: dial("tcp","<REDACTED IP>:55006")
2024/10/10 10:50:51 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/10/10 10:50:51 DEBUG : System Volume Information: Excluded
2024/10/10 10:50:51 DEBUG : .Spotlight-V100: Excluded
2024/10/10 10:50:51 DEBUG : .fseventsd: Excluded
2024/10/10 10:50:51 DEBUG : tst.mp4: Need to transfer - File not found at Destination
2024/10/10 10:50:51 DEBUG : Google drive root '': Waiting for checks to finish
2024/10/10 10:50:51 DEBUG : Google drive root '': Waiting for transfers to finish
2024/10/10 10:50:51 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: dial("tcp","<REDACTED IP>:55061")
2024/10/10 10:50:51 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/10/10 10:50:53 ERROR : tst.mp4: Failed to copy: 1 error occurred:
* 250 Requested file action okay, completed.
2024/10/10 10:50:53 ERROR : Google drive root '': not deleting files as there were IO errors
2024/10/10 10:50:53 ERROR : Google drive root '': not deleting directories as there were IO errors
2024/10/10 10:50:53 ERROR : Attempt 2/3 failed with 1 errors and: 1 error occurred:
* 250 Requested file action okay, completed.
2024/10/10 10:50:53 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: Connecting to FTP server
2024/10/10 10:50:53 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: dial("tcp","<REDACTED IP>:21")
2024/10/10 10:50:53 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/10/10 10:50:53 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: dial("tcp","<REDACTED IP>:55761")
2024/10/10 10:50:53 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: > dial: conn=*fshttp.timeoutConn, err=<nil>
2024/10/10 10:50:53 DEBUG : System Volume Information: Excluded
2024/10/10 10:50:53 DEBUG : .Spotlight-V100: Excluded
2024/10/10 10:50:53 DEBUG : .fseventsd: Excluded
2024/10/10 10:50:53 DEBUG : Google drive root '': Waiting for checks to finish
2024/10/10 10:50:53 DEBUG : tst.mp4: Sizes identical
2024/10/10 10:50:53 DEBUG : tst.mp4: Unchanged skipping
2024/10/10 10:50:53 DEBUG : Google drive root '': Waiting for transfers to finish
2024/10/10 10:50:53 DEBUG : Waiting for deletions to finish
2024/10/10 10:50:53 INFO : There was nothing to transfer
2024/10/10 10:50:53 ERROR : Attempt 3/3 succeeded
2024/10/10 10:50:53 INFO :
Transferred: 0 B / 0 B, -, 0 B/s, ETA -
Checks: 1 / 1, 100%
Elapsed time: 3.4s
2024/10/10 10:50:53 DEBUG : 10 go routines active
2024/10/10 10:50:53 DEBUG : ftp://<REDACTED IP>:21/SSDROOT: closing 1 unused connections