"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:432: Using remote "TestFTPProftpd:" 2024/04/26 01:00:59 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-suyacix1kabimar8rabegec2" 2024/04/26 01:00:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/26 01:00:59 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2024/04/26 01:00:59 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:00:59 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:00:59 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:00:59 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:00:59 DEBUG : TestFTPProftpd: detected overridden config - adding "{KcV1z}" suffix to name 2024/04/26 01:00:59 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:00:59 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:00:59 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:00:59 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: Connecting to FTP server 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:21") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE === RUN TestIntegration/FsCheckWrap fstests.go:473: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:501: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:19948") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:1810") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2024/04/26 01:00:59 DEBUG : dir/subdir: Making directory 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:20749") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:16613") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:4388") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 INFO : dir/subdir: Removing directory 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:43704") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:22100") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 INFO : dir: Removing directory 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:18888") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:44144") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:18558") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:393: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:24426") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:393: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:49026") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:701: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:62173") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:59713") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:41583") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:47080") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:701: testing "．" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:14357") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:7062") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:37723") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:62708") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:701: testing "．．" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:64969") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:42161") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:11863") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:45012") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:701: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:54520") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:59445") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:65333") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:53137") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:701: testing " leading space" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:18081") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:37585") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:3241") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:37005") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:701: testing "~leading tilde" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:38372") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:22966") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:52595") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:41736") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:701: testing "␍leading CR" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:1136") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:9160") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:59601") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:44256") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:701: testing "␊leading LF" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:26694") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:18492") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:27748") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:23332") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:701: testing "␉leading HT" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:12671") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:3796") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:4878") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:26004") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:701: testing "␋leading VT" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:9835") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:41577") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:23177") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:9269") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:701: testing ".leading dot" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:18204") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:34016") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:53258") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:7189") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:701: testing "trailing space " 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:27927") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:53057") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:59303") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:44985") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:701: testing "trailing CR␍" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:25107") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:61520") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:16455") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:62455") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:701: testing "trailing LF␊" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:18952") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:3515") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:38657") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:19064") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:701: testing "trailing HT␉" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:11652") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:32724") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:62297") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:37322") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:701: testing "trailing VT␋" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:50192") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:24510") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:59631") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:61839") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:701: testing "trailing dot." 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:27282") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:63485") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:22309") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:36093") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:701: testing "invalid utf-8\xfe" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:39528") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:44462") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:44339") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:56708") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:701: testing "test%46.txt" 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:12944") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:32062") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:62874") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:39848") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:19584") 2024/04/26 01:00:59 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: Connecting to FTP server 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred: * potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:41071") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:9772") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:774: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:807: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:856: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:54977") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:33031") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:28553") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:61767") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:41102") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:22392") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:27617") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:42867") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:393: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:23820") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:44626") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:9020") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:34368") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:24216") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:5944") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:16809") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:38080") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:53271") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2024/04/26 01:01:00 DEBUG : Creating backend with remote "TestFTPProftpd:" 2024/04/26 01:01:00 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : TestFTPProftpd: detected overridden config - adding "{KcV1z}" suffix to name 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21: Connecting to FTP server 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:12220") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:393: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:47073") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:52557") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:393: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:54627") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:63992") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:393: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:64350") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:11512") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:48874") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:30087") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:39739") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1109: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:54605") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:42631") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:14974") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:30141") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:51380") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge fstests.go:1141: FS has no Purge interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot fstests.go:1178: FS has no Purge interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2024/04/26 01:01:00 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé" 2024/04/26 01:01:00 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : TestFTPProftpd: detected overridden config - adding "{KcV1z}" suffix to name 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: Connecting to FTP server 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé" to be canonical "TestFTPProftpd{KcV1z}:rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé" 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:9519") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:4276") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: Connecting to FTP server 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:2998") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:7521") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:22885") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:4659") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:34943") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:37220") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : Waiting for deletions to finish 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:39281") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:45636") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:60312") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:15587") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 INFO : a/b/c/d/e.txt: Deleted 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:43389") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:52659") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:53629") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: dial("tcp","172.17.0.4:50215") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : removing 1 level 4 directories 2024/04/26 01:01:00 INFO : a/b/c/d: Removing directory 2024/04/26 01:01:00 DEBUG : removing 1 level 3 directories 2024/04/26 01:01:00 INFO : a/b/c: Removing directory 2024/04/26 01:01:00 DEBUG : removing 1 level 2 directories 2024/04/26 01:01:00 INFO : a/b: Removing directory 2024/04/26 01:01:00 DEBUG : removing 1 level 1 directories 2024/04/26 01:01:00 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1242: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:4417") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:62148") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:14398") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:65160") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:37716") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:35767") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:27242") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: Connecting to FTP server 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:31282") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:15049") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:59927") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:58564") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:55877") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:6334") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:11883") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:44325") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:23046") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:11532") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:27397") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:49135") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:29527") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:27966") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:7661") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:43183") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1386: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2024/04/26 01:01:00 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-fohigew7majorad9guforid5" 2024/04/26 01:01:00 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : TestFTPProftpd: detected overridden config - adding "{KcV1z}" suffix to name 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: Connecting to FTP server 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:11830") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:23217") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:59715") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:4135") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:2326") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:30238") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:6085") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:21773") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:2073") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:56313") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:15703") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:44191") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:24722") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:23666") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:61668") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:60258") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: dial("tcp","172.17.0.4:11324") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: Purge dir "new_name" 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-fohigew7majorad9guforid5: Purge dir "" === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1580: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1611: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:53606") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:4795") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:23179") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:36281") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:52422") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:64069") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:54494") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:30382") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:46377") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2024/04/26 01:01:00 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2024/04/26 01:01:00 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : TestFTPProftpd: detected overridden config - adding "{KcV1z}" suffix to name 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.4:49904") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2024/04/26 01:01:00 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-suyacix1kabimar8rabegec2/not found.txt" 2024/04/26 01:01:00 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : TestFTPProftpd: detected overridden config - adding "{KcV1z}" suffix to name 2024/04/26 01:01:00 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:00 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:00 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:00 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: Connecting to FTP server 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : pacer: low level retry 1/10 (error EOF) 2024/04/26 01:01:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : pacer: low level retry 2/10 (error EOF) 2024/04/26 01:01:00 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : pacer: low level retry 3/10 (error EOF) 2024/04/26 01:01:00 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:00 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:00 DEBUG : pacer: low level retry 4/10 (error EOF) 2024/04/26 01:01:00 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : pacer: low level retry 5/10 (error EOF) 2024/04/26 01:01:01 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : pacer: Reducing sleep to 240ms 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: dial("tcp","172.17.0.4:12950") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/not found.txt: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 ERROR : : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1838: Opening root remote "TestFTPProftpd:" path "rclone-test-suyacix1kabimar8rabegec2" from "TestFTPProftpd:rclone-test-suyacix1kabimar8rabegec2" 2024/04/26 01:01:01 DEBUG : Creating backend with remote "TestFTPProftpd:" 2024/04/26 01:01:01 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2024/04/26 01:01:01 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:01 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:01 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:01 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:01 DEBUG : TestFTPProftpd: detected overridden config - adding "{KcV1z}" suffix to name 2024/04/26 01:01:01 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:01 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:01 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:01 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: Connecting to FTP server 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:42919") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:41579") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:40475") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:6029") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:12192") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:48266") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1872: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:8612") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:4916") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:29463") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:50991") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:11422") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:14661") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:7860") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:32566") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:55100") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:13308") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: dial("tcp","172.17.0.4:33530") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:1931: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:406: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2036: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:15284") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:25394") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:6519") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:31741") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:16974") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout fstests.go:2070: FS does not support About === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:53999") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:59465") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2024/04/26 01:01:01 DEBUG : Creating backend with remote "TestFTPProftpd,concurrency='0',shut_timeout='1s':rclone-test-suyacix1kabimar8rabegec2" 2024/04/26 01:01:01 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2024/04/26 01:01:01 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:01 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:01 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:01 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:01 DEBUG : TestFTPProftpd: detected overridden config - adding "{mFAWF}" suffix to name 2024/04/26 01:01:01 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:01 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:01 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:01 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: Connecting to FTP server 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:7065") 2024/04/26 01:01:01 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2153: *ftp.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsCopyChunked fstests.go:2271: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:60100") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:58581") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:6673") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2024/04/26 01:01:02 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-suyacix1kabimar8rabegec2/deeper/nonexisting/directory" 2024/04/26 01:01:02 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2024/04/26 01:01:02 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:02 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:02 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:02 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:02 DEBUG : TestFTPProftpd: detected overridden config - adding "{KcV1z}" suffix to name 2024/04/26 01:01:02 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2024/04/26 01:01:02 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2024/04/26 01:01:02 DEBUG : Setting pass="6rgCcaL25L19W07b9HYhJJApAZq90XlrNGhWjgQrCjqKow" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2024/04/26 01:01:02 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/deeper/nonexisting/directory: Connecting to FTP server 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/deeper/nonexisting/directory: dial("tcp","172.17.0.4:21") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2/deeper/nonexisting/directory: > dial: conn=*fshttp.timeoutConn, err= === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:2420: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:2463: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory fstests.go:2520: FS has no Directory methods and doesn't Wrap 2024/04/26 01:01:02 DEBUG : Waiting for deletions to finish 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:3439") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:54119") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:24707") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:43077") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:44426") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:55676") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:6832") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:11723") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:21321") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:15714") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 DEBUG : removing 1 level 4 directories 2024/04/26 01:01:02 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2024/04/26 01:01:02 DEBUG : removing 1 level 3 directories 2024/04/26 01:01:02 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2024/04/26 01:01:02 DEBUG : removing 1 level 2 directories 2024/04/26 01:01:02 INFO : hello? sausage/êé: Removing directory 2024/04/26 01:01:02 DEBUG : removing 1 level 1 directories 2024/04/26 01:01:02 INFO : hello? sausage: Removing directory 2024/04/26 01:01:02 DEBUG : removing 1 level 0 directories 2024/04/26 01:01:02 INFO : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: Removing directory 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:25591") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 ERROR : : error listing: directory not found 2024/04/26 01:01:02 DEBUG : Waiting for deletions to finish 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:57801") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 ERROR : : error listing: directory not found 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: dial("tcp","172.17.0.4:18793") 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: > dial: conn=*fshttp.timeoutConn, err= 2024/04/26 01:01:02 ERROR : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: Failed to list "": directory not found 2024/04/26 01:01:02 DEBUG : removing 1 level 0 directories 2024/04/26 01:01:02 INFO : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: Removing directory 2024/04/26 01:01:02 ERROR : : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2024/04/26 01:01:02 DEBUG : ftp://172.17.0.4:21/rclone-test-suyacix1kabimar8rabegec2: closing 2 unused connections --- PASS: TestIntegration (3.83s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- SKIP: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.00s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir (3.69s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.01s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.00s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.00s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (0.13s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (0.01s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutError (1.02s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (0.01s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenChunkWriter (0.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (2.47s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.02s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPurge (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (0.11s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.06s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (0.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.16s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.48s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (0.26s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (0.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (1.09s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (1.09s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.01s) --- SKIP: TestIntegration/FsMkdir/FsDirSetModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsMkdirMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsDirectory (0.00s) --- PASS: TestIntegration/FsShutdown (0.00s) === RUN TestIntegrationProftpd ftp_test.go:23: skipping as -remote is set --- SKIP: TestIntegrationProftpd (0.00s) === RUN TestIntegrationPureftpd ftp_test.go:34: skipping as -remote is set --- SKIP: TestIntegrationPureftpd (0.00s) === RUN TestIntegrationVsftpd ftp_test.go:45: skipping as -remote is set --- SKIP: TestIntegrationVsftpd (0.00s) PASS ok github.com/rclone/rclone/backend/ftp 3.857s "go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Finished OK in 5.84265774s (try 1/5)