"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Starting (try 1/5) === RUN TestCommandEncodingNeutralisesCRLF --- PASS: TestCommandEncodingNeutralisesCRLF (0.00s) === RUN TestPutFtpConnectionDiscardsDeadConnection === RUN TestPutFtpConnectionDiscardsDeadConnection/network_error_discards === RUN TestPutFtpConnectionDiscardsDeadConnection/FTP_reply_error_with_live_connection_pools === RUN TestPutFtpConnectionDiscardsDeadConnection/FTP_reply_error_with_dead_connection_discards --- PASS: TestPutFtpConnectionDiscardsDeadConnection (0.11s) --- PASS: TestPutFtpConnectionDiscardsDeadConnection/network_error_discards (0.01s) --- PASS: TestPutFtpConnectionDiscardsDeadConnection/FTP_reply_error_with_live_connection_pools (0.00s) --- PASS: TestPutFtpConnectionDiscardsDeadConnection/FTP_reply_error_with_dead_connection_discards (0.10s) === RUN TestIntegration fstests.go:464: Using remote "TestFTPProftpd:" 2026/10/23 02:21:01 NOTICE: TestFTPProftpd: Starting server 2026/10/23 02:21:02 NOTICE: TestFTPProftpd: Attempting to connect to "172.17.0.3:21" try 1/100 2026/10/23 02:21:02 DEBUG : TestFTPProftpd: Read 1, error: 2026/10/23 02:21:02 NOTICE: TestFTPProftpd: Started server and connected to "172.17.0.3:21" 2026/10/23 02:21:02 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-cupoxew3zore" 2026/10/23 02:21:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/23 02:21:02 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:02 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:02 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:02 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:02 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:02 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:02 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:02 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:02 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:02 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:02 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: Connecting to FTP server 2026/10/23 02:21:02 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:02 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47362->172.17.0.3:21, err= 2026/10/23 02:21:03 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE === RUN TestIntegration/FsCheckWrap fstests.go:505: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:533: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:61939") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:50492->172.17.0.3:61939, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRootCannotChangeHost 2026/10/23 02:21:03 DEBUG : Creating backend with remote "TestFTPProftpd://127.0.0.1:43993/" 2026/10/23 02:21:03 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:03 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:03 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:03 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:03 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:03 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:03 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993: Connecting to FTP server 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993: > dial: conn=172.17.0.1:47370->172.17.0.3:21, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993: dial("tcp","172.17.0.3:39203") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993: > dial: conn=172.17.0.1:59586->172.17.0.3:39203, err= 2026/10/23 02:21:03 DEBUG : Creating backend with remote "TestFTPProftpd://127.0.0.1:43993/rclone-test-cupoxew3zore/" 2026/10/23 02:21:03 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:03 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:03 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:03 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:03 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:03 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:03 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993/rclone-test-cupoxew3zore: Connecting to FTP server 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47376->172.17.0.3:21, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:29025") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/127.0.0.1:43993/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:48940->172.17.0.3:29025, err= 2026/10/23 02:21:03 DEBUG : Creating backend with remote "TestFTPProftpd://user:pass@127.0.0.1:43993/" 2026/10/23 02:21:03 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:03 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:03 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:03 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:03 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:03 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:03 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/user:pass@127.0.0.1:43993: Connecting to FTP server 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/user:pass@127.0.0.1:43993: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/user:pass@127.0.0.1:43993: > dial: conn=172.17.0.1:47390->172.17.0.3:21, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/user:pass@127.0.0.1:43993: dial("tcp","172.17.0.3:42080") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/user:pass@127.0.0.1:43993: > dial: conn=172.17.0.1:48986->172.17.0.3:42080, err= === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:37658") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:36280->172.17.0.3:37658, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/10/23 02:21:03 INFO : dir/subdir: Making directory 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:1222") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:34884->172.17.0.3:1222, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:17921") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:38162->172.17.0.3:17921, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:17974") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:34318->172.17.0.3:17974, err= 2026/10/23 02:21:03 INFO : dir/subdir: Removing directory 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:31889") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:44866->172.17.0.3:31889, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:31949") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:53792->172.17.0.3:31949, err= 2026/10/23 02:21:03 INFO : dir: Removing directory 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:49095") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56000->172.17.0.3:49095, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:49135") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35590->172.17.0.3:49135, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:25302") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56944->172.17.0.3:25302, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:55547") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:34046->172.17.0.3:55547, err= 2026/10/23 02:21:03 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:8969") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:38380->172.17.0.3:8969, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:779: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:45660") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:44892->172.17.0.3:45660, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:47222") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:36642->172.17.0.3:47222, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:42442") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:41838->172.17.0.3:42442, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:30029") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55996->172.17.0.3:30029, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:779: testing "．" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:28786") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33800->172.17.0.3:28786, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:46167") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:60150->172.17.0.3:46167, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:39691") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:40900->172.17.0.3:39691, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:47149") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:59444->172.17.0.3:47149, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:779: testing "．．" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:23015") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:37240->172.17.0.3:23015, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:14901") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:44536->172.17.0.3:14901, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:49450") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:58640->172.17.0.3:49450, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:51991") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47284->172.17.0.3:51991, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:779: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:44906") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:39544->172.17.0.3:44906, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:4962") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:46972->172.17.0.3:4962, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:62318") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:41746->172.17.0.3:62318, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:40213") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:57070->172.17.0.3:40213, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:779: testing " leading space" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:4514") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:39552->172.17.0.3:4514, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:13685") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:54370->172.17.0.3:13685, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:36642") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:32944->172.17.0.3:36642, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:65430") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33102->172.17.0.3:65430, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:779: testing "~leading tilde" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:50320") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:40686->172.17.0.3:50320, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:36841") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:51264->172.17.0.3:36841, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:17816") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:59796->172.17.0.3:17816, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:2759") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:41292->172.17.0.3:2759, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:779: testing "␍leading CR" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:3196") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47262->172.17.0.3:3196, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:48742") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:52886->172.17.0.3:48742, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:50831") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33922->172.17.0.3:50831, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:51307") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:34024->172.17.0.3:51307, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:779: testing "␊leading LF" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:8509") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:57294->172.17.0.3:8509, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:40844") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:46318->172.17.0.3:40844, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:59253") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:53266->172.17.0.3:59253, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:53145") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:52764->172.17.0.3:53145, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:779: testing "␉leading HT" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:22532") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:52114->172.17.0.3:22532, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:36160") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:57586->172.17.0.3:36160, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:17640") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56776->172.17.0.3:17640, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:50294") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:45460->172.17.0.3:50294, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:779: testing "␋leading VT" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:16793") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:38530->172.17.0.3:16793, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:56308") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35970->172.17.0.3:56308, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:31908") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47016->172.17.0.3:31908, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:38785") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56320->172.17.0.3:38785, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:779: testing ".leading dot" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:5674") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35788->172.17.0.3:5674, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:15824") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:45922->172.17.0.3:15824, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:25241") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33608->172.17.0.3:25241, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:49556") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47818->172.17.0.3:49556, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:779: testing "trailing space " 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:19763") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55142->172.17.0.3:19763, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:22025") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:41342->172.17.0.3:22025, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:24235") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:59416->172.17.0.3:24235, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:23254") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:48420->172.17.0.3:23254, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:779: testing "trailing CR␍" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:34687") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:39506->172.17.0.3:34687, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:59854") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:59190->172.17.0.3:59854, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:23149") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:36984->172.17.0.3:23149, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:19472") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:32798->172.17.0.3:19472, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:779: testing "trailing LF␊" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:31161") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35106->172.17.0.3:31161, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:39942") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55846->172.17.0.3:39942, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:21208") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:44124->172.17.0.3:21208, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:33333") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:58196->172.17.0.3:33333, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:779: testing "trailing HT␉" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:23149") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:37000->172.17.0.3:23149, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:6505") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35400->172.17.0.3:6505, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:19106") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33082->172.17.0.3:19106, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:30635") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:45782->172.17.0.3:30635, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:779: testing "trailing VT␋" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:46326") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:52920->172.17.0.3:46326, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:12824") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33650->172.17.0.3:12824, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:18246") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:37662->172.17.0.3:18246, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:3323") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:38098->172.17.0.3:3323, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:779: testing "trailing dot." 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:47961") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55070->172.17.0.3:47961, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:34862") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56860->172.17.0.3:34862, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:52594") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:48928->172.17.0.3:52594, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:63730") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:44856->172.17.0.3:63730, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:779: testing "invalid utf-8\xfe" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:25635") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:39042->172.17.0.3:25635, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:18968") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:45274->172.17.0.3:18968, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:36981") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:50754->172.17.0.3:36981, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:30286") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55154->172.17.0.3:30286, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:779: testing "test%46.txt" 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:33768") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:54004->172.17.0.3:33768, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:61199") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:43724->172.17.0.3:61199, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:14307") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:36740->172.17.0.3:14307, err= 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:52507") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:60908->172.17.0.3:52507, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:17689") 2026/10/23 02:21:03 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:54756->172.17.0.3:17689, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47396->172.17.0.3:21, err= 2026/10/23 02:21:04 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:30302") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:37936->172.17.0.3:30302, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:30175") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:37920->172.17.0.3:30175, err= === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:858: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:895: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:977: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:18930") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:59182->172.17.0.3:18930, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:55016") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56992->172.17.0.3:55016, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:14508") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:41392->172.17.0.3:14508, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:25759") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35808->172.17.0.3:25759, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:52112") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:57342->172.17.0.3:52112, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:32475") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:53388->172.17.0.3:32475, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:40484") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:37250->172.17.0.3:40484, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:21980") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:42908->172.17.0.3:21980, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:42554") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:40968->172.17.0.3:42554, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:17538") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:38340->172.17.0.3:17538, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:6584") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35468->172.17.0.3:6584, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:29173") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55402->172.17.0.3:29173, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:7017") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:50208->172.17.0.3:7017, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:27927") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:48714->172.17.0.3:27927, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:4123") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:41788->172.17.0.3:4123, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:4664") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:37620->172.17.0.3:4664, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:61517") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47034->172.17.0.3:61517, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/10/23 02:21:04 DEBUG : Creating backend with remote "TestFTPProftpd:" 2026/10/23 02:21:04 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:47410->172.17.0.3:21, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:21966") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:38078->172.17.0.3:21966, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:26088") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47686->172.17.0.3:26088, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:2290") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:59248->172.17.0.3:2290, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:4090") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:60284->172.17.0.3:4090, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:62291") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:53026->172.17.0.3:62291, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:44588") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:38928->172.17.0.3:44588, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:46507") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47280->172.17.0.3:46507, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:45561") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:53188->172.17.0.3:45561, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:40282") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35220->172.17.0.3:40282, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:4359") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:37586->172.17.0.3:4359, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1230: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:17597") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33746->172.17.0.3:17597, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:48931") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:54380->172.17.0.3:48931, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:37109") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:52572->172.17.0.3:37109, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:46876") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:44620->172.17.0.3:46876, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:13571") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:48646->172.17.0.3:13571, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge fstests.go:1262: FS has no Purge interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot fstests.go:1299: FS has no Purge interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/10/23 02:21:04 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-cupoxew3zore/hello? sausage/êé" 2026/10/23 02:21:04 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:47412->172.17.0.3:21, err= 2026/10/23 02:21:04 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-cupoxew3zore/hello? sausage/êé" to be canonical "TestFTPProftpd{2MsMw}:rclone-test-cupoxew3zore/hello? sausage/êé" 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:11162") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:48204->172.17.0.3:11162, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:25455") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:44570->172.17.0.3:25455, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:47426->172.17.0.3:21, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:22873") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:58208->172.17.0.3:22873, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:58911") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:34896->172.17.0.3:58911, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:32499") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:49904->172.17.0.3:32499, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:49640") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:40268->172.17.0.3:49640, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:30357") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:45338->172.17.0.3:30357, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:28201") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:54332->172.17.0.3:28201, err= 2026/10/23 02:21:04 DEBUG : Waiting for deletions to finish 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:41165") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:50382->172.17.0.3:41165, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:65307") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:56306->172.17.0.3:65307, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:7139") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:32892->172.17.0.3:7139, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:37113") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:60054->172.17.0.3:37113, err= 2026/10/23 02:21:04 INFO : a/b/c/d/e.txt: Deleted 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:61858") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:33188->172.17.0.3:61858, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:46849") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:36340->172.17.0.3:46849, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:37849") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:41448->172.17.0.3:37849, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: dial("tcp","172.17.0.3:58899") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé: > dial: conn=172.17.0.1:40536->172.17.0.3:58899, err= 2026/10/23 02:21:04 DEBUG : removing 1 level 4 directories 2026/10/23 02:21:04 INFO : a/b/c/d: Removing directory 2026/10/23 02:21:04 DEBUG : removing 1 level 3 directories 2026/10/23 02:21:04 INFO : a/b/c: Removing directory 2026/10/23 02:21:04 DEBUG : removing 1 level 2 directories 2026/10/23 02:21:04 INFO : a/b: Removing directory 2026/10/23 02:21:04 DEBUG : removing 1 level 1 directories 2026/10/23 02:21:04 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1363: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:55015") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:48786->172.17.0.3:55015, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:36357") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:50818->172.17.0.3:36357, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:27056") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35220->172.17.0.3:27056, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:15239") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:60054->172.17.0.3:15239, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:22935") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:57790->172.17.0.3:22935, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:58507") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47722->172.17.0.3:58507, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47436->172.17.0.3:21, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:54700") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56082->172.17.0.3:54700, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:37690") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:50550->172.17.0.3:37690, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:43891") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47934->172.17.0.3:43891, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:41160") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:35580->172.17.0.3:41160, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:35527") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:41036->172.17.0.3:35527, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:32896") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:38560->172.17.0.3:32896, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:6656") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:39140->172.17.0.3:6656, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:6704") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56976->172.17.0.3:6704, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:49451") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33454->172.17.0.3:49451, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:8994") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:38120->172.17.0.3:8994, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:63677") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:42414->172.17.0.3:63677, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:62597") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:54450->172.17.0.3:62597, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:12698") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:57256->172.17.0.3:12698, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:11464") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:59470->172.17.0.3:11464, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:51259") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33268->172.17.0.3:51259, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:11844") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:54416->172.17.0.3:11844, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:24870") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:40950->172.17.0.3:24870, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1515: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/10/23 02:21:04 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-bokuwoj5fuke" 2026/10/23 02:21:04 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:47446->172.17.0.3:21, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:2266") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:58310->172.17.0.3:2266, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:37724") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:43026->172.17.0.3:37724, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:12577") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:56632->172.17.0.3:12577, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:32048") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:56660->172.17.0.3:32048, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:4649") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:40986->172.17.0.3:4649, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:55006") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:60654->172.17.0.3:55006, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:19073") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:58742->172.17.0.3:19073, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:47900") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:34778->172.17.0.3:47900, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:63654") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:41446->172.17.0.3:63654, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:16339") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:40468->172.17.0.3:16339, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:18381") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55428->172.17.0.3:18381, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:7826") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:44218->172.17.0.3:7826, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:61020") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:60582->172.17.0.3:61020, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:36288") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:58210->172.17.0.3:36288, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:65030") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:34678->172.17.0.3:65030, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:34704") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:36030->172.17.0.3:34704, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: dial("tcp","172.17.0.3:52715") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: > dial: conn=172.17.0.1:53128->172.17.0.3:52715, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: Purge dir "new_name" 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-bokuwoj5fuke: 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:1711: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1742: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1813: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:28273") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:42700->172.17.0.3:28273, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:55343") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:39698->172.17.0.3:55343, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:41898") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:46416->172.17.0.3:41898, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:27330") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:34406->172.17.0.3:27330, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:29694") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:60184->172.17.0.3:29694, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:17605") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:52526->172.17.0.3:17605, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:10893") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:34130->172.17.0.3:10893, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:24087") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:53432->172.17.0.3:24087, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:61170") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55752->172.17.0.3:61170, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:58798") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:42686->172.17.0.3:58798, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:56376") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:58600->172.17.0.3:56376, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:59474") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:49240->172.17.0.3:59474, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:4114") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47862->172.17.0.3:4114, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:35917") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55800->172.17.0.3:35917, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:59711") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:36224->172.17.0.3:59711, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:20687") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:45410->172.17.0.3:20687, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/10/23 02:21:04 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-cupoxew3zore/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/10/23 02:21:04 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:47454->172.17.0.3:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.3:19127") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:47556->172.17.0.3:19127, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/10/23 02:21:04 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-cupoxew3zore/not found.txt" 2026/10/23 02:21:04 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/not found.txt: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/not found.txt: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/not found.txt: > dial: conn=172.17.0.1:47460->172.17.0.3:21, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/not found.txt: dial("tcp","172.17.0.3:7988") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/not found.txt: > dial: conn=172.17.0.1:60724->172.17.0.3:7988, err= 2026/10/23 02:21:04 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:2072: Opening root remote "TestFTPProftpd:" path "rclone-test-cupoxew3zore" from "TestFTPProftpd:rclone-test-cupoxew3zore" 2026/10/23 02:21:04 DEBUG : Creating backend with remote "TestFTPProftpd:" 2026/10/23 02:21:04 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:47472->172.17.0.3:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:64474") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:49138->172.17.0.3:64474, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:32188") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:60086->172.17.0.3:32188, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:9141") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:52052->172.17.0.3:9141, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:15117") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:52808->172.17.0.3:15117, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:13283") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:59756->172.17.0.3:13283, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:8001") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:49558->172.17.0.3:8001, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2109: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:22634") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:34224->172.17.0.3:22634, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:33519") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:54616->172.17.0.3:33519, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:3950") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:37580->172.17.0.3:3950, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:64111") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:52876->172.17.0.3:64111, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:59337") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:47424->172.17.0.3:59337, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:21289") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:38496->172.17.0.3:21289, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:32798") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:59600->172.17.0.3:32798, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:44780") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:45728->172.17.0.3:44780, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:15930") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:33266->172.17.0.3:15930, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:7243") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:43342->172.17.0.3:7243, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:60425") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:49698->172.17.0.3:60425, err= === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2168: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:438: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2273: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2289: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:21884") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:39634->172.17.0.3:21884, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:19314") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33004->172.17.0.3:19314, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:38348") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:42320->172.17.0.3:38348, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:31285") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:50854->172.17.0.3:31285, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:37253") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:48456->172.17.0.3:37253, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout fstests.go:2445: FS does not support About === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:49527") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56474->172.17.0.3:49527, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:2029") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47036->172.17.0.3:2029, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2026/10/23 02:21:04 DEBUG : Creating backend with remote "TestFTPProftpd,concurrency='0',shut_timeout='1s':rclone-test-cupoxew3zore" 2026/10/23 02:21:04 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{FaqcJ}" suffix to name 2026/10/23 02:21:04 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:04 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:04 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: Connecting to FTP server 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:47476->172.17.0.3:21, err= 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:3501") 2026/10/23 02:21:04 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:37860->172.17.0.3:3501, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2530: *ftp.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutRetry === RUN TestIntegration/FsMkdir/FsPutRetry/Simple === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/Status500 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:3533") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:39648->172.17.0.3:3533, err= fstests.go:2733: no upload request could be faulted === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:31862") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:36900->172.17.0.3:31862, err= fstests.go:2733: no upload request could be faulted === RUN TestIntegration/FsMkdir/FsPutRetry/Chunked fstests.go:2775: *ftp.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutShortEOF === RUN TestIntegration/FsMkdir/FsPutShortEOF/Simple 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:61173") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:51418->172.17.0.3:61173, err= === RUN TestIntegration/FsMkdir/FsPutShortEOF/Chunked fstests.go:2906: *ftp.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsCopyChunked fstests.go:2948: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:20766") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:59566->172.17.0.3:20766, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:37362") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:44696->172.17.0.3:37362, err= 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:14531") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:56228->172.17.0.3:14531, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2026/10/23 02:21:05 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-cupoxew3zore/deeper/nonexisting/directory" 2026/10/23 02:21:05 DEBUG : Setting type=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2026/10/23 02:21:05 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:05 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:05 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:05 DEBUG : TestFTPProftpd: detected overridden config - adding "{2MsMw}" suffix to name 2026/10/23 02:21:05 DEBUG : Setting host=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2026/10/23 02:21:05 DEBUG : Setting user=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2026/10/23 02:21:05 DEBUG : Setting pass=XXX for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2026/10/23 02:21:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/deeper/nonexisting/directory: Connecting to FTP server 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/deeper/nonexisting/directory: dial("tcp","172.17.0.3:21") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore/deeper/nonexisting/directory: > dial: conn=172.17.0.1:47488->172.17.0.3:21, err= === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:3097: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:3140: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory fstests.go:3197: FS has no Directory methods and doesn't Wrap 2026/10/23 02:21:05 DEBUG : Waiting for deletions to finish 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:57894") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55364->172.17.0.3:57894, err= 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:36403") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:44616->172.17.0.3:36403, err= 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:34981") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:50836->172.17.0.3:34981, err= 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:4059") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55418->172.17.0.3:4059, err= 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:59273") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:51502->172.17.0.3:59273, err= 2026/10/23 02:21:05 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:63800") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:55094->172.17.0.3:63800, err= 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:50866") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:46766->172.17.0.3:50866, err= 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:31566") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:53070->172.17.0.3:31566, err= 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:24147") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:48174->172.17.0.3:24147, err= 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:65107") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:53840->172.17.0.3:65107, err= 2026/10/23 02:21:05 DEBUG : removing 1 level 4 directories 2026/10/23 02:21:05 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2026/10/23 02:21:05 DEBUG : removing 1 level 3 directories 2026/10/23 02:21:05 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2026/10/23 02:21:05 DEBUG : removing 1 level 2 directories 2026/10/23 02:21:05 INFO : hello? sausage/êé: Removing directory 2026/10/23 02:21:05 DEBUG : removing 1 level 1 directories 2026/10/23 02:21:05 INFO : hello? sausage: Removing directory 2026/10/23 02:21:05 DEBUG : removing 1 level 0 directories 2026/10/23 02:21:05 INFO : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: Removing directory 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:15216") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:33328->172.17.0.3:15216, err= 2026/10/23 02:21:05 ERROR : error listing: directory not found 2026/10/23 02:21:05 DEBUG : Waiting for deletions to finish 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:37630") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:59864->172.17.0.3:37630, err= 2026/10/23 02:21:05 ERROR : error listing: directory not found 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: dial("tcp","172.17.0.3:3722") 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: > dial: conn=172.17.0.1:58084->172.17.0.3:3722, err= 2026/10/23 02:21:05 ERROR : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: Failed to list "": directory not found 2026/10/23 02:21:05 DEBUG : removing 1 level 0 directories 2026/10/23 02:21:05 INFO : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: Removing directory 2026/10/23 02:21:05 ERROR : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2026/10/23 02:21:05 DEBUG : ftp://172.17.0.3:21/rclone-test-cupoxew3zore: closing 2 unused connections 2026/10/23 02:21:05 NOTICE: TestFTPProftpd: Stopping server --- PASS: TestIntegration (3.93s) --- 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/FsRootCannotChangeHost (0.03s) --- PASS: TestIntegration/FsRmdirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir (2.58s) --- 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.15s) --- 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.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.01s) --- 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 (1.33s) --- 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.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.01s) --- 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.05s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.05s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.05s) --- 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) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (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.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint (0.08s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (0.04s) --- 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.02s) --- 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) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (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 (0.84s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (0.84s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutRetry (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutRetry/Simple (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutRetry/Simple/Status500 (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutRetry/Simple/NetError (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutRetry/Chunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutShortEOF (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutShortEOF/Simple (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutShortEOF/Chunked (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 4.068s "go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Finished OK in 5.264143319s (try 1/5)