"go test -v -timeout 1h0m0s -remote TestFTPPureftpd: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestFTPPureftpd:" 2025/08/18 02:21:13 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lipizow7doqu" 2025/08/18 02:21:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/18 02:21:13 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/18 02:21:13 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:13 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:13 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:13 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:13 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6PyD9}" suffix to name 2025/08/18 02:21:13 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:13 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:13 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:13 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: Connecting to FTP server 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:41270->172.17.0.3:21, err= 2025/08/18 02:21:13 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_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/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38287") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:58618->172.17.0.3:38287, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33036") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59780->172.17.0.3:33036, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/08/18 02:21:13 INFO : dir/subdir: Making directory 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30094") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:56782->172.17.0.3:30094, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38544") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34784->172.17.0.3:38544, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32874") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:54202->172.17.0.3:32874, err= 2025/08/18 02:21:13 INFO : dir/subdir: Removing directory 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33239") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:38202->172.17.0.3:33239, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33023") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:40634->172.17.0.3:33023, err= 2025/08/18 02:21:13 INFO : dir: Removing directory 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34093") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34632->172.17.0.3:34093, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34414") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:41694->172.17.0.3:34414, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32264") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:52492->172.17.0.3:32264, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37715") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:52014->172.17.0.3:37715, err= 2025/08/18 02:21:13 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/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35656") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:56396->172.17.0.3:35656, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30706") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:46518->172.17.0.3:30706, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37560") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:57198->172.17.0.3:37560, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39961") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:44710->172.17.0.3:39961, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38819") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:38938->172.17.0.3:38819, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38181") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:60750->172.17.0.3:38181, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33693") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:54294->172.17.0.3:33693, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39185") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:41860->172.17.0.3:39185, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38878") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34394->172.17.0.3:38878, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30308") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:37190->172.17.0.3:30308, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35856") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:53036->172.17.0.3:35856, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39683") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:43312->172.17.0.3:39683, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33164") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:41558->172.17.0.3:33164, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39253") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:39756->172.17.0.3:39253, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35130") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59396->172.17.0.3:35130, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32000") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:53602->172.17.0.3:32000, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35641") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:48778->172.17.0.3:35641, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35649") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:37572->172.17.0.3:35649, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36999") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:45804->172.17.0.3:36999, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31652") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:49810->172.17.0.3:31652, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35182") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:49634->172.17.0.3:35182, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36925") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:39038->172.17.0.3:36925, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34485") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:36918->172.17.0.3:34485, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39671") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:55518->172.17.0.3:39671, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33434") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:40306->172.17.0.3:33434, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32110") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:40726->172.17.0.3:32110, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36456") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:45208->172.17.0.3:36456, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35261") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:56462->172.17.0.3:35261, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38952") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:58344->172.17.0.3:38952, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38889") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:38008->172.17.0.3:38889, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31280") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:55898->172.17.0.3:31280, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36663") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:42530->172.17.0.3:36663, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35048") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:40360->172.17.0.3:35048, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32496") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:32884->172.17.0.3:32496, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37162") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:48442->172.17.0.3:37162, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39507") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:43582->172.17.0.3:39507, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30536") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:55976->172.17.0.3:30536, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39869") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59542->172.17.0.3:39869, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30811") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:39358->172.17.0.3:30811, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39719") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:50162->172.17.0.3:39719, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39477") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:33314->172.17.0.3:39477, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32569") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:37764->172.17.0.3:32569, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32601") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:51442->172.17.0.3:32601, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30827") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:54356->172.17.0.3:30827, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39411") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:39380->172.17.0.3:39411, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36277") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:50670->172.17.0.3:36277, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39940") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:45206->172.17.0.3:39940, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38706") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34828->172.17.0.3:38706, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35064") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:41048->172.17.0.3:35064, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34338") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:37734->172.17.0.3:34338, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33372") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:57022->172.17.0.3:33372, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37427") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34224->172.17.0.3:37427, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32595") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:42870->172.17.0.3:32595, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30111") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:47212->172.17.0.3:30111, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38645") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:44074->172.17.0.3:38645, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30092") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59398->172.17.0.3:30092, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30736") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:49060->172.17.0.3:30736, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30438") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59194->172.17.0.3:30438, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30150") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:37306->172.17.0.3:30150, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33544") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:42798->172.17.0.3:33544, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31449") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:33790->172.17.0.3:31449, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32762") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:53164->172.17.0.3:32762, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34312") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59662->172.17.0.3:34312, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31381") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:46596->172.17.0.3:31381, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31500") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:45942->172.17.0.3:31500, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34748") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:54864->172.17.0.3:34748, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32162") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:60450->172.17.0.3:32162, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36473") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34644->172.17.0.3:36473, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30364") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:35418->172.17.0.3:30364, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30955") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:50960->172.17.0.3:30955, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32027") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:48806->172.17.0.3:32027, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32275") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:56814->172.17.0.3:32275, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36440") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:60432->172.17.0.3:36440, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33028") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:42680->172.17.0.3:33028, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31479") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:35328->172.17.0.3:31479, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31442") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:39482->172.17.0.3:31442, err= 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37836") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:51592->172.17.0.3:37836, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35988") 2025/08/18 02:21:13 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:47926->172.17.0.3:35988, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: Connecting to FTP server 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34780->172.17.0.3:21, err= 2025/08/18 02:21:14 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred: * potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37600") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:32800->172.17.0.3:37600, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33977") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:50364->172.17.0.3:33977, 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/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31593") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:51824->172.17.0.3:31593, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39022") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:51684->172.17.0.3:39022, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36723") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:58258->172.17.0.3:36723, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35278") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:48232->172.17.0.3:35278, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32749") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:44712->172.17.0.3:32749, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35944") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:40212->172.17.0.3:35944, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38517") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:57284->172.17.0.3:38517, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32542") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:53216->172.17.0.3:32542, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39767") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:37588->172.17.0.3:39767, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32943") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:52956->172.17.0.3:32943, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34920") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:51132->172.17.0.3:34920, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38967") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:55540->172.17.0.3:38967, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32270") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:43534->172.17.0.3:32270, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33013") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:45984->172.17.0.3:33013, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38773") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:56344->172.17.0.3:38773, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35699") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:40268->172.17.0.3:35699, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38443") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:41490->172.17.0.3:38443, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/08/18 02:21:14 DEBUG : Creating backend with remote "TestFTPPureftpd:" 2025/08/18 02:21:14 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/18 02:21:14 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:14 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:14 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:14 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:14 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6PyD9}" suffix to name 2025/08/18 02:21:14 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:14 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:14 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:14 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21: Connecting to FTP server 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:34784->172.17.0.3:21, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:36251") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:40540->172.17.0.3:36251, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34027") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:40110->172.17.0.3:34027, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39868") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:50524->172.17.0.3:39868, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39484") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:44330->172.17.0.3:39484, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32062") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:54010->172.17.0.3:32062, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37667") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:58094->172.17.0.3:37667, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35435") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:50966->172.17.0.3:35435, err= 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30676") 2025/08/18 02:21:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:53820->172.17.0.3:30676, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37663") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:43548->172.17.0.3:37663, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32409") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:36366->172.17.0.3:32409, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1121: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35147") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:54544->172.17.0.3:35147, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36405") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:50710->172.17.0.3:36405, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31047") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:41238->172.17.0.3:31047, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33757") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:42488->172.17.0.3:33757, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33651") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:56616->172.17.0.3:33651, 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/08/18 02:21:15 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lipizow7doqu/hello? sausage/êé" 2025/08/18 02:21:15 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6PyD9}" suffix to name 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: Connecting to FTP server 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:34794->172.17.0.3:21, err= 2025/08/18 02:21:15 DEBUG : fs cache: renaming cache item "TestFTPPureftpd:rclone-test-lipizow7doqu/hello? sausage/êé" to be canonical "TestFTPPureftpd{6PyD9}:rclone-test-lipizow7doqu/hello? sausage/êé" 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:36800") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:36740->172.17.0.3:36800, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:39733") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:50436->172.17.0.3:39733, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: Connecting to FTP server 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:34810->172.17.0.3:21, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:39567") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:36662->172.17.0.3:39567, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:39860") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:44340->172.17.0.3:39860, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:36933") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:33904->172.17.0.3:36933, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:30291") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:47630->172.17.0.3:30291, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:32567") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:51906->172.17.0.3:32567, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:35990") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:57862->172.17.0.3:35990, err= 2025/08/18 02:21:15 DEBUG : Waiting for deletions to finish 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:35326") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:37720->172.17.0.3:35326, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:38196") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:49478->172.17.0.3:38196, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:33432") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:33536->172.17.0.3:33432, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:37256") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:48972->172.17.0.3:37256, err= 2025/08/18 02:21:15 INFO : a/b/c/d/e.txt: Deleted 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:33897") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:51008->172.17.0.3:33897, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:32002") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:44752->172.17.0.3:32002, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:30921") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:49612->172.17.0.3:30921, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: dial("tcp","172.17.0.3:39126") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé: > dial: conn=172.17.0.1:44662->172.17.0.3:39126, err= 2025/08/18 02:21:15 DEBUG : removing 1 level 4 directories 2025/08/18 02:21:15 INFO : a/b/c/d: Removing directory 2025/08/18 02:21:15 DEBUG : removing 1 level 3 directories 2025/08/18 02:21:15 INFO : a/b/c: Removing directory 2025/08/18 02:21:15 DEBUG : removing 1 level 2 directories 2025/08/18 02:21:15 INFO : a/b: Removing directory 2025/08/18 02:21:15 DEBUG : removing 1 level 1 directories 2025/08/18 02:21:15 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1254: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30058") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:36796->172.17.0.3:30058, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31932") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59280->172.17.0.3:31932, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32161") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:49668->172.17.0.3:32161, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32438") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:45940->172.17.0.3:32438, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35139") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:50626->172.17.0.3:35139, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31477") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:48670->172.17.0.3:31477, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: Connecting to FTP server 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34812->172.17.0.3:21, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31307") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:46528->172.17.0.3:31307, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32799") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:49260->172.17.0.3:32799, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38602") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:52668->172.17.0.3:38602, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38210") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:43476->172.17.0.3:38210, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33489") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:45132->172.17.0.3:33489, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37670") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:53114->172.17.0.3:37670, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30814") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35396") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:39412->172.17.0.3:30814, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:57706->172.17.0.3:35396, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33898") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:38978->172.17.0.3:33898, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36698") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:57762->172.17.0.3:36698, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35988") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:47938->172.17.0.3:35988, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37715") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:56252->172.17.0.3:37715, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34740") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36422") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59014->172.17.0.3:36422, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:38948->172.17.0.3:34740, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39090") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:49668->172.17.0.3:39090, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30170") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:58734->172.17.0.3:30170, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33462") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:57846->172.17.0.3:33462, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/08/18 02:21:15 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-hosubif1diyo" 2025/08/18 02:21:15 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6PyD9}" suffix to name 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: Connecting to FTP server 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:34824->172.17.0.3:21, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36631") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:40736->172.17.0.3:36631, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:31972") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:54206->172.17.0.3:31972, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:32037") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:38386->172.17.0.3:32037, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:31701") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:59734->172.17.0.3:31701, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:36404") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:54458->172.17.0.3:36404, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:39343") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:58332->172.17.0.3:39343, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:35933") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:58290->172.17.0.3:35933, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:38030") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:59404->172.17.0.3:38030, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30653") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:33038->172.17.0.3:30653, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34673") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:40874->172.17.0.3:34673, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35179") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:42530->172.17.0.3:35179, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32385") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:38196->172.17.0.3:32385, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38799") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:46340->172.17.0.3:38799, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:30997") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:55878->172.17.0.3:30997, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:33089") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:33034->172.17.0.3:33089, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:37376") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:38254->172.17.0.3:37376, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: dial("tcp","172.17.0.3:35554") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: > dial: conn=172.17.0.1:55798->172.17.0.3:35554, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: Purge dir "new_name" 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-hosubif1diyo: 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/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:31796") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:51868->172.17.0.3:31796, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39985") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:33432->172.17.0.3:39985, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39035") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:41100->172.17.0.3:39035, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30201") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:35828->172.17.0.3:30201, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39869") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59554->172.17.0.3:39869, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36070") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:51006->172.17.0.3:36070, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32852") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:57806->172.17.0.3:32852, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36855") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:54678->172.17.0.3:36855, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38615") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:35546->172.17.0.3:38615, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/08/18 02:21:15 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lipizow7doqu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/08/18 02:21:15 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6PyD9}" suffix to name 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:34834->172.17.0.3:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.3:35461") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:34452->172.17.0.3:35461, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/08/18 02:21:15 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lipizow7doqu/not found.txt" 2025/08/18 02:21:15 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6PyD9}" suffix to name 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/not found.txt: Connecting to FTP server 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/not found.txt: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/not found.txt: > dial: conn=172.17.0.1:34846->172.17.0.3:21, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/not found.txt: dial("tcp","172.17.0.3:34087") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/not found.txt: > dial: conn=172.17.0.1:42516->172.17.0.3:34087, err= 2025/08/18 02:21:15 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestFTPPureftpd:" path "rclone-test-lipizow7doqu" from "TestFTPPureftpd:rclone-test-lipizow7doqu" 2025/08/18 02:21:15 DEBUG : Creating backend with remote "TestFTPPureftpd:" 2025/08/18 02:21:15 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6PyD9}" suffix to name 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: Connecting to FTP server 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:34854->172.17.0.3:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:33630") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:48792->172.17.0.3:33630, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:32774") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:38530->172.17.0.3:32774, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:37866") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:50428->172.17.0.3:37866, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:31864") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:46178->172.17.0.3:31864, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:33065") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:47070->172.17.0.3:33065, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:35041") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:45800->172.17.0.3:35041, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1945: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:34711") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:35448->172.17.0.3:34711, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:37987") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:45412->172.17.0.3:37987, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:31225") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:51434->172.17.0.3:31225, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:38970") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:50286->172.17.0.3:38970, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:30055") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:39210->172.17.0.3:30055, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:38652") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:43436->172.17.0.3:38652, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:39369") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:37310->172.17.0.3:39369, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:31106") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:49148->172.17.0.3:31106, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:36466") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:58658->172.17.0.3:36466, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:32346") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:49570->172.17.0.3:32346, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:33580") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:49648->172.17.0.3:33580, 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/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39758") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:42814->172.17.0.3:39758, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30713") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:49658->172.17.0.3:30713, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33678") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:45996->172.17.0.3:33678, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36118") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:36446->172.17.0.3:36118, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37700") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:49790->172.17.0.3:37700, 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/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34272") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:45494->172.17.0.3:34272, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36731") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:53936->172.17.0.3:36731, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2025/08/18 02:21:15 DEBUG : Creating backend with remote "TestFTPPureftpd,concurrency='0',shut_timeout='1s':rclone-test-lipizow7doqu" 2025/08/18 02:21:15 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : TestFTPPureftpd: detected overridden config - adding "{JFtyh}" suffix to name 2025/08/18 02:21:15 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:15 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:15 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:15 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: Connecting to FTP server 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34866->172.17.0.3:21, err= 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30282") 2025/08/18 02:21:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:36650->172.17.0.3:30282, 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/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36603") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:37568->172.17.0.3:36603, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38988") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:39604->172.17.0.3:38988, err= 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33905") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:58782->172.17.0.3:33905, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/08/18 02:21:16 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lipizow7doqu/deeper/nonexisting/directory" 2025/08/18 02:21:16 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/18 02:21:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:16 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:16 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6PyD9}" suffix to name 2025/08/18 02:21:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/18 02:21:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/18 02:21:16 DEBUG : Setting pass="jwgUEk8mhX_V8J8Tzmdz2t07npr7Km1MuNtNKKckuD8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/18 02:21:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/deeper/nonexisting/directory: Connecting to FTP server 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/deeper/nonexisting/directory: dial("tcp","172.17.0.3:21") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu/deeper/nonexisting/directory: > dial: conn=172.17.0.1:34874->172.17.0.3: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/08/18 02:21:16 DEBUG : Waiting for deletions to finish 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39253") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:39758->172.17.0.3:39253, err= 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36487") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:44892->172.17.0.3:36487, err= 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35050") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:57234->172.17.0.3:35050, err= 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:34998") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:41592->172.17.0.3:34998, err= 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38674") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:33876->172.17.0.3:38674, err= 2025/08/18 02:21:16 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:33207") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:55596->172.17.0.3:33207, err= 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:37193") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:34518->172.17.0.3:37193, err= 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:30711") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59028->172.17.0.3:30711, err= 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:39465") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:56790->172.17.0.3:39465, err= 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:38860") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:44104->172.17.0.3:38860, err= 2025/08/18 02:21:16 DEBUG : removing 1 level 4 directories 2025/08/18 02:21:16 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2025/08/18 02:21:16 DEBUG : removing 1 level 3 directories 2025/08/18 02:21:16 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2025/08/18 02:21:16 DEBUG : removing 1 level 2 directories 2025/08/18 02:21:16 INFO : hello? sausage/êé: Removing directory 2025/08/18 02:21:16 DEBUG : removing 1 level 1 directories 2025/08/18 02:21:16 INFO : hello? sausage: Removing directory 2025/08/18 02:21:16 DEBUG : removing 1 level 0 directories 2025/08/18 02:21:16 INFO : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: Removing directory 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:32449") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:44732->172.17.0.3:32449, err= 2025/08/18 02:21:16 ERROR : error listing: directory not found 2025/08/18 02:21:16 DEBUG : Waiting for deletions to finish 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:35474") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:56958->172.17.0.3:35474, err= 2025/08/18 02:21:16 ERROR : error listing: directory not found 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: dial("tcp","172.17.0.3:36943") 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: > dial: conn=172.17.0.1:59210->172.17.0.3:36943, err= 2025/08/18 02:21:16 ERROR : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: Failed to list "": directory not found 2025/08/18 02:21:16 DEBUG : removing 1 level 0 directories 2025/08/18 02:21:16 INFO : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: Removing directory 2025/08/18 02:21:16 ERROR : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2025/08/18 02:21:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lipizow7doqu: closing 2 unused connections --- PASS: TestIntegration (2.42s) --- 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.35s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.01s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.00s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.00s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (0.13s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (0.00s) --- 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.00s) --- 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.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenChunkWriter (0.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (1.13s) --- 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.02s) --- 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.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.00s) --- 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.04s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.04s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.03s) --- 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.03s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (0.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.00s) --- 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.00s) --- 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.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (0.78s) --- 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.00s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.02s) --- 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.445s "go test -v -timeout 1h0m0s -remote TestFTPPureftpd: -verbose" - Finished OK in 3.320513041s (try 1/5)