"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestFTPProftpd:" 2025/09/18 03:08:27 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hoqevaq5wuhu" 2025/09/18 03:08:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/18 03:08:27 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/09/18 03:08:27 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:27 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:27 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:27 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:27 DEBUG : TestFTPProftpd: detected overridden config - adding "{EJXFg}" suffix to name 2025/09/18 03:08:27 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:27 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:27 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:27 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: Connecting to FTP server 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50852->172.17.0.5:21, err= 2025/09/18 03:08:27 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE === RUN TestIntegration/FsCheckWrap fstests.go:479: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:507: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:33573") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:34294->172.17.0.5:33573, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:22802") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:42508->172.17.0.5:22802, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/09/18 03:08:27 INFO : dir/subdir: Making directory 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:22445") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49152->172.17.0.5:22445, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:43687") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:39752->172.17.0.5:43687, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:60726") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49538->172.17.0.5:60726, err= 2025/09/18 03:08:27 INFO : dir/subdir: Removing directory 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:27134") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:55416->172.17.0.5:27134, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:11940") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:55372->172.17.0.5:11940, err= 2025/09/18 03:08:27 INFO : dir: Removing directory 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:26854") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:37494->172.17.0.5:26854, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:4195") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:54716->172.17.0.5:4195, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:8719") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50898->172.17.0.5:8719, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:46830") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:45114->172.17.0.5:46830, err= 2025/09/18 03:08:27 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:12222") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:55450->172.17.0.5:12222, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:8794") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44876->172.17.0.5:8794, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:37383") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:45694->172.17.0.5:37383, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21779") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:33886->172.17.0.5:21779, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:56805") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:41046->172.17.0.5:56805, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:35528") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:55110->172.17.0.5:35528, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:43380") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:36426->172.17.0.5:43380, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:51637") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50964->172.17.0.5:51637, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:59417") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:40632->172.17.0.5:59417, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:53107") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49526->172.17.0.5:53107, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:49303") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:46534->172.17.0.5:49303, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:3571") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:52962->172.17.0.5:3571, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:11128") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44350->172.17.0.5:11128, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:52697") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:58096->172.17.0.5:52697, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:51856") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44928->172.17.0.5:51856, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:31476") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:39512->172.17.0.5:31476, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:15264") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:36364->172.17.0.5:15264, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:49238") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:35084->172.17.0.5:49238, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:59666") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:33782->172.17.0.5:59666, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:4482") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44712->172.17.0.5:4482, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:17277") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:58852->172.17.0.5:17277, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:16933") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:51108->172.17.0.5:16933, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:25903") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:34438->172.17.0.5:25903, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:59941") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:55684->172.17.0.5:59941, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:12125") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:52066->172.17.0.5:12125, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:52013") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:32948->172.17.0.5:52013, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:6346") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:41568->172.17.0.5:6346, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:37956") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49182->172.17.0.5:37956, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:55185") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50702->172.17.0.5:55185, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:14041") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:43076->172.17.0.5:14041, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:19251") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:35756->172.17.0.5:19251, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:1872") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44528->172.17.0.5:1872, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21811") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49766->172.17.0.5:21811, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:55611") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:52712->172.17.0.5:55611, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:22628") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:34530->172.17.0.5:22628, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:13081") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:37508->172.17.0.5:13081, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:25604") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:60590->172.17.0.5:25604, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:64984") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:53542->172.17.0.5:64984, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:63694") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:57188->172.17.0.5:63694, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:19486") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:45038->172.17.0.5:19486, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:52556") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:60930->172.17.0.5:52556, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:47463") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:35504->172.17.0.5:47463, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:22034") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:48780->172.17.0.5:22034, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:62661") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:39172->172.17.0.5:62661, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:34626") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49668->172.17.0.5:34626, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:8355") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:35918->172.17.0.5:8355, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:28603") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:55664->172.17.0.5:28603, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:48867") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:34102->172.17.0.5:48867, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:56570") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50174->172.17.0.5:56570, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:22734") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:52472->172.17.0.5:22734, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:52325") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:54146->172.17.0.5:52325, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:8312") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:42408->172.17.0.5:8312, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:38644") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:38974->172.17.0.5:38644, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:12694") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:57084->172.17.0.5:12694, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:2718") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:38832->172.17.0.5:2718, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:49745") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:32862->172.17.0.5:49745, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:63684") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:35220->172.17.0.5:63684, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:8041") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:45472->172.17.0.5:8041, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:22167") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44674->172.17.0.5:22167, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:53334") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:51038->172.17.0.5:53334, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21058") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:40476->172.17.0.5:21058, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:40394") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:47478->172.17.0.5:40394, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:54183") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:47242->172.17.0.5:54183, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:41845") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:36312->172.17.0.5:41845, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:30471") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44684->172.17.0.5:30471, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:11277") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:45032->172.17.0.5:11277, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:53903") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:58304->172.17.0.5:53903, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:55052") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:51198->172.17.0.5:55052, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:10726") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:60894->172.17.0.5:10726, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:52063") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:60808->172.17.0.5:52063, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:9004") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:34076->172.17.0.5:9004, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:62259") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:35068->172.17.0.5:62259, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:33992") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:51916->172.17.0.5:33992, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:30014") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:33868->172.17.0.5:30014, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:59386") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:33578->172.17.0.5:59386, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:3083") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49822->172.17.0.5:3083, err= 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:37346") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50362->172.17.0.5:37346, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:22454") 2025/09/18 03:08:27 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50802->172.17.0.5:22454, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: Connecting to FTP server 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50854->172.17.0.5:21, err= 2025/09/18 03:08:28 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred: * potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:55575") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:33060->172.17.0.5:55575, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:10399") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:53638->172.17.0.5:10399, err= === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:786: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:819: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:868: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:7625") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:59944->172.17.0.5:7625, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:24167") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:47624->172.17.0.5:24167, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:32484") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:46104->172.17.0.5:32484, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:29080") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:59196->172.17.0.5:29080, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:35550") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:55722->172.17.0.5:35550, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:33727") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49682->172.17.0.5:33727, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:27694") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:45764->172.17.0.5:27694, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:44026") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:47576->172.17.0.5:44026, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:13901") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:48672->172.17.0.5:13901, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:54028") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:39092->172.17.0.5:54028, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:7990") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:52208->172.17.0.5:7990, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:8243") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:51216->172.17.0.5:8243, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:8855") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49450->172.17.0.5:8855, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:9258") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:39396->172.17.0.5:9258, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:63197") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:48618->172.17.0.5:63197, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:53817") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:48984->172.17.0.5:53817, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:59381") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:56298->172.17.0.5:59381, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/09/18 03:08:28 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/09/18 03:08:28 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/09/18 03:08:28 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:28 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:28 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:28 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:28 DEBUG : TestFTPProftpd: detected overridden config - adding "{EJXFg}" suffix to name 2025/09/18 03:08:28 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:28 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:28 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:28 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21: Connecting to FTP server 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:50862->172.17.0.5:21, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:30125") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:40078->172.17.0.5:30125, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:19357") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:56000->172.17.0.5:19357, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:30237") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:54292->172.17.0.5:30237, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:53804") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:39322->172.17.0.5:53804, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:39452") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:39004->172.17.0.5:39452, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:11496") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:52460->172.17.0.5:11496, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21645") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:60608->172.17.0.5:21645, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:12350") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:36880->172.17.0.5:12350, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:44466") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:48798->172.17.0.5:44466, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:19024") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:58658->172.17.0.5:19024, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1121: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:25100") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44214->172.17.0.5:25100, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:41037") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:56714->172.17.0.5:41037, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:34676") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:45948->172.17.0.5:34676, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:15140") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:58476->172.17.0.5:15140, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:50413") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:39368->172.17.0.5:50413, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge fstests.go:1153: FS has no Purge interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot fstests.go:1190: FS has no Purge interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/09/18 03:08:28 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hoqevaq5wuhu/hello? sausage/êé" 2025/09/18 03:08:28 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/09/18 03:08:28 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:28 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:28 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:28 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:28 DEBUG : TestFTPProftpd: detected overridden config - adding "{EJXFg}" suffix to name 2025/09/18 03:08:28 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:28 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:28 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:28 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: Connecting to FTP server 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:50872->172.17.0.5:21, err= 2025/09/18 03:08:28 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-hoqevaq5wuhu/hello? sausage/êé" to be canonical "TestFTPProftpd{EJXFg}:rclone-test-hoqevaq5wuhu/hello? sausage/êé" 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:47721") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:55492->172.17.0.5:47721, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:24405") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:54236->172.17.0.5:24405, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: Connecting to FTP server 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:50876->172.17.0.5:21, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:25402") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:48734->172.17.0.5:25402, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:24507") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:52284->172.17.0.5:24507, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:33194") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:55122->172.17.0.5:33194, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:1168") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:48896->172.17.0.5:1168, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:60625") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:35772->172.17.0.5:60625, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:46156") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:56482->172.17.0.5:46156, err= 2025/09/18 03:08:28 DEBUG : Waiting for deletions to finish 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:52084") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:56460->172.17.0.5:52084, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:50614") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:42948->172.17.0.5:50614, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:62632") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:54556->172.17.0.5:62632, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:58704") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:36014->172.17.0.5:58704, err= 2025/09/18 03:08:28 INFO : a/b/c/d/e.txt: Deleted 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:64473") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:44334->172.17.0.5:64473, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:24830") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:48112->172.17.0.5:24830, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:33090") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:54762->172.17.0.5:33090, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: dial("tcp","172.17.0.5:9348") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé: > dial: conn=172.17.0.1:52824->172.17.0.5:9348, err= 2025/09/18 03:08:28 DEBUG : removing 1 level 4 directories 2025/09/18 03:08:28 INFO : a/b/c/d: Removing directory 2025/09/18 03:08:28 DEBUG : removing 1 level 3 directories 2025/09/18 03:08:28 INFO : a/b/c: Removing directory 2025/09/18 03:08:28 DEBUG : removing 1 level 2 directories 2025/09/18 03:08:28 INFO : a/b: Removing directory 2025/09/18 03:08:28 DEBUG : removing 1 level 1 directories 2025/09/18 03:08:28 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1254: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:41277") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:58378->172.17.0.5:41277, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:38283") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50442->172.17.0.5:38283, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:17362") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50494->172.17.0.5:17362, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:4823") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:33532->172.17.0.5:4823, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:8299") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:33394->172.17.0.5:8299, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:50065") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:39762->172.17.0.5:50065, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: Connecting to FTP server 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:31493") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50884->172.17.0.5:21, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:45582->172.17.0.5:31493, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:53084") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:40132->172.17.0.5:53084, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:51301") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:60538->172.17.0.5:51301, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:9113") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:53854->172.17.0.5:9113, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:62943") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:60914->172.17.0.5:62943, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:59266") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:48742->172.17.0.5:59266, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:19986") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49760->172.17.0.5:19986, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:16899") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:47910->172.17.0.5:16899, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:58267") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:58730->172.17.0.5:58267, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:37526") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:48716->172.17.0.5:37526, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:5652") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:38686->172.17.0.5:5652, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:31725") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49462->172.17.0.5:31725, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:26784") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:48300->172.17.0.5:26784, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:27817") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:35070->172.17.0.5:27817, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:41542") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:53672->172.17.0.5:41542, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:1991") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:48116->172.17.0.5:1991, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:9363") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:56972->172.17.0.5:9363, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/09/18 03:08:28 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-mahexeq6reme" 2025/09/18 03:08:28 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/09/18 03:08:28 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:28 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:28 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:28 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:28 DEBUG : TestFTPProftpd: detected overridden config - adding "{EJXFg}" suffix to name 2025/09/18 03:08:28 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:28 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:28 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:28 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: Connecting to FTP server 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:50900->172.17.0.5:21, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:3314") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:43710->172.17.0.5:3314, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:63161") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:39268->172.17.0.5:63161, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:33645") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:55532->172.17.0.5:33645, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:4521") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:43956->172.17.0.5:4521, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:31748") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:47818->172.17.0.5:31748, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:56743") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:45854->172.17.0.5:56743, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:5157") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:34518->172.17.0.5:5157, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:5091") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:58990->172.17.0.5:5091, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:36316") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:36236->172.17.0.5:36316, err= 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:16100") 2025/09/18 03:08:28 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:58148->172.17.0.5:16100, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:61755") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:40746->172.17.0.5:61755, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:60348") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:55766->172.17.0.5:60348, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:53029") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:40288->172.17.0.5:53029, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:51664") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:42510->172.17.0.5:51664, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:40121") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:46230->172.17.0.5:40121, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:21603") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:40954->172.17.0.5:21603, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: dial("tcp","172.17.0.5:58062") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: > dial: conn=172.17.0.1:33034->172.17.0.5:58062, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: Purge dir "new_name" 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-mahexeq6reme: 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:1592: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1623: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1694: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21647") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:56400->172.17.0.5:21647, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:45314") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:43506->172.17.0.5:45314, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:48618") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50852->172.17.0.5:48618, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21886") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:59848->172.17.0.5:21886, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:60076") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:51474->172.17.0.5:60076, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:61286") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:53128->172.17.0.5:61286, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:63490") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:53830->172.17.0.5:63490, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:25250") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:32880->172.17.0.5:25250, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:36280") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:42426->172.17.0.5:36280, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/09/18 03:08:29 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hoqevaq5wuhu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/09/18 03:08:29 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/09/18 03:08:29 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:29 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:29 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:29 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:29 DEBUG : TestFTPProftpd: detected overridden config - adding "{EJXFg}" suffix to name 2025/09/18 03:08:29 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:29 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:29 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:29 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:50910->172.17.0.5:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.5:14879") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:32950->172.17.0.5:14879, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/09/18 03:08:29 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hoqevaq5wuhu/not found.txt" 2025/09/18 03:08:29 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/09/18 03:08:29 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:29 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:29 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:29 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:29 DEBUG : TestFTPProftpd: detected overridden config - adding "{EJXFg}" suffix to name 2025/09/18 03:08:29 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:29 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:29 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:29 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/not found.txt: Connecting to FTP server 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/not found.txt: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/not found.txt: > dial: conn=172.17.0.1:50922->172.17.0.5:21, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/not found.txt: dial("tcp","172.17.0.5:38136") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/not found.txt: > dial: conn=172.17.0.1:60904->172.17.0.5:38136, err= 2025/09/18 03:08:29 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestFTPProftpd:" path "rclone-test-hoqevaq5wuhu" from "TestFTPProftpd:rclone-test-hoqevaq5wuhu" 2025/09/18 03:08:29 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/09/18 03:08:29 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/09/18 03:08:29 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:29 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:29 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:29 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:29 DEBUG : TestFTPProftpd: detected overridden config - adding "{EJXFg}" suffix to name 2025/09/18 03:08:29 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:29 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:29 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:29 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: Connecting to FTP server 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:50926->172.17.0.5:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:35914") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:47916->172.17.0.5:35914, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:8646") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:37600->172.17.0.5:8646, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:21494") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:57252->172.17.0.5:21494, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:6352") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:50280->172.17.0.5:6352, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:15128") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:57176->172.17.0.5:15128, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:43348") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:40350->172.17.0.5:43348, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1945: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:11072") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:41102->172.17.0.5:11072, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:49495") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:51328->172.17.0.5:49495, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:38946") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:34730->172.17.0.5:38946, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:57049") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:60816->172.17.0.5:57049, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:10218") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:59488->172.17.0.5:10218, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:60952") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:42192->172.17.0.5:60952, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:54488") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:39122->172.17.0.5:54488, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:60579") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:49278->172.17.0.5:60579, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:34330") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:36990->172.17.0.5:34330, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:46623") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:54238->172.17.0.5:46623, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:44823") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:52400->172.17.0.5:44823, err= === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2004: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:412: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2109: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2125: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:55786") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:56344->172.17.0.5:55786, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:52348") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:45420->172.17.0.5:52348, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:4728") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:47110->172.17.0.5:4728, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:55732") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:37742->172.17.0.5:55732, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:6892") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:43034->172.17.0.5:6892, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout fstests.go:2281: FS does not support About === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:34717") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:55974->172.17.0.5:34717, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:44564") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44332->172.17.0.5:44564, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2025/09/18 03:08:29 DEBUG : Creating backend with remote "TestFTPProftpd,concurrency='0',shut_timeout='1s':rclone-test-hoqevaq5wuhu" 2025/09/18 03:08:29 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/09/18 03:08:29 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:29 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:29 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:29 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:29 DEBUG : TestFTPProftpd: detected overridden config - adding "{Nv26j}" suffix to name 2025/09/18 03:08:29 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:29 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:29 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:29 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: Connecting to FTP server 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:50934->172.17.0.5:21, err= 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:59787") 2025/09/18 03:08:29 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:59130->172.17.0.5:59787, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2364: *ftp.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsCopyChunked fstests.go:2476: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:22729") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:43414->172.17.0.5:22729, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:30968") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:42728->172.17.0.5:30968, err= 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:31141") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:59790->172.17.0.5:31141, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/09/18 03:08:30 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hoqevaq5wuhu/deeper/nonexisting/directory" 2025/09/18 03:08:30 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/09/18 03:08:30 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:30 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:30 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:30 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:30 DEBUG : TestFTPProftpd: detected overridden config - adding "{EJXFg}" suffix to name 2025/09/18 03:08:30 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/09/18 03:08:30 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/09/18 03:08:30 DEBUG : Setting pass="vcMhAITM0fQ15tEwDWSqcH_RzjTVsVbciY1H6FAary1rkA" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/09/18 03:08:30 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/deeper/nonexisting/directory: Connecting to FTP server 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/deeper/nonexisting/directory: dial("tcp","172.17.0.5:21") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu/deeper/nonexisting/directory: > dial: conn=172.17.0.1:50944->172.17.0.5:21, err= === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:2625: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:2668: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory fstests.go:2725: FS has no Directory methods and doesn't Wrap 2025/09/18 03:08:30 DEBUG : Waiting for deletions to finish 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:10220") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:47108->172.17.0.5:10220, err= 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:39145") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:60796->172.17.0.5:39145, err= 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:56381") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:33966->172.17.0.5:56381, err= 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:34631") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:35032->172.17.0.5:34631, err= 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:45084") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49552->172.17.0.5:45084, err= 2025/09/18 03:08:30 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:23538") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:59128->172.17.0.5:23538, err= 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:59609") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:56998->172.17.0.5:59609, err= 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:46559") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:41250->172.17.0.5:46559, err= 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:32124") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:51114->172.17.0.5:32124, err= 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:52666") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:49180->172.17.0.5:52666, err= 2025/09/18 03:08:30 DEBUG : removing 1 level 4 directories 2025/09/18 03:08:30 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2025/09/18 03:08:30 DEBUG : removing 1 level 3 directories 2025/09/18 03:08:30 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2025/09/18 03:08:30 DEBUG : removing 1 level 2 directories 2025/09/18 03:08:30 INFO : hello? sausage/êé: Removing directory 2025/09/18 03:08:30 DEBUG : removing 1 level 1 directories 2025/09/18 03:08:30 INFO : hello? sausage: Removing directory 2025/09/18 03:08:30 DEBUG : removing 1 level 0 directories 2025/09/18 03:08:30 INFO : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: Removing directory 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:20266") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:38136->172.17.0.5:20266, err= 2025/09/18 03:08:30 ERROR : error listing: directory not found 2025/09/18 03:08:30 DEBUG : Waiting for deletions to finish 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:17833") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:44784->172.17.0.5:17833, err= 2025/09/18 03:08:30 ERROR : error listing: directory not found 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: dial("tcp","172.17.0.5:44293") 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: > dial: conn=172.17.0.1:60276->172.17.0.5:44293, err= 2025/09/18 03:08:30 ERROR : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: Failed to list "": directory not found 2025/09/18 03:08:30 DEBUG : removing 1 level 0 directories 2025/09/18 03:08:30 INFO : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: Removing directory 2025/09/18 03:08:30 ERROR : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2025/09/18 03:08:30 DEBUG : ftp://172.17.0.5:21/rclone-test-hoqevaq5wuhu: closing 2 unused connections --- PASS: TestIntegration (2.64s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- SKIP: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.00s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir (2.58s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.02s) --- 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.17s) --- 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.03s) --- 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.30s) --- 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.00s) --- 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.04s) --- 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.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (0.06s) --- 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.03s) --- 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.02s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (0.89s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (0.89s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.01s) --- SKIP: TestIntegration/FsMkdir/FsDirSetModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsMkdirMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsDirectory (0.00s) --- PASS: TestIntegration/FsShutdown (0.00s) === RUN TestIntegrationProftpd ftp_test.go:23: skipping as -remote is set --- SKIP: TestIntegrationProftpd (0.00s) === RUN TestIntegrationPureftpd ftp_test.go:34: skipping as -remote is set --- SKIP: TestIntegrationPureftpd (0.00s) === RUN TestIntegrationVsftpd ftp_test.go:45: skipping as -remote is set --- SKIP: TestIntegrationVsftpd (0.00s) PASS ok github.com/rclone/rclone/backend/ftp 2.661s "go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Finished OK in 3.734552497s (try 1/5)