"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestFTPProftpd:" 2025/10/19 02:11:21 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:22 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 2/10 2025/10/19 02:11:22 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:23 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 3/10 2025/10/19 02:11:23 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:24 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 4/10 2025/10/19 02:11:24 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:25 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 5/10 2025/10/19 02:11:25 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:26 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 6/10 2025/10/19 02:11:26 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:27 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 7/10 2025/10/19 02:11:27 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:28 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 8/10 2025/10/19 02:11:29 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:30 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 9/10 2025/10/19 02:11:30 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:31 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 10/10 2025/10/19 02:11:31 NOTICE: TestFTPProftpd: Stopping server 2025/10/19 02:11:31 NOTICE: TestFTPProftpd: Starting server 2025/10/19 02:11:32 NOTICE: TestFTPProftpd: Attempting to connect to "172.17.0.2:21" try 1/100 2025/10/19 02:11:32 DEBUG : TestFTPProftpd: Read 1, error: 2025/10/19 02:11:32 NOTICE: TestFTPProftpd: Started server and connected to "172.17.0.2:21" 2025/10/19 02:11:32 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-povusoq7liwa" 2025/10/19 02:11:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/19 02:11:32 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/19 02:11:32 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:32 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:32 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:32 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:32 DEBUG : TestFTPProftpd: detected overridden config - adding "{q7JkL}" suffix to name 2025/10/19 02:11:32 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:32 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:32 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:32 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: Connecting to FTP server 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46330->172.17.0.2:21, err= 2025/10/19 02:11:32 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/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:49604") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:58698->172.17.0.2:49604, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:19030") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:55598->172.17.0.2:19030, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/10/19 02:11:32 INFO : dir/subdir: Making directory 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:20509") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46104->172.17.0.2:20509, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:15900") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:52648->172.17.0.2:15900, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:46204") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37642->172.17.0.2:46204, err= 2025/10/19 02:11:32 INFO : dir/subdir: Removing directory 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:58157") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:53902->172.17.0.2:58157, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:12042") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:51544->172.17.0.2:12042, err= 2025/10/19 02:11:32 INFO : dir: Removing directory 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:22465") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:38600->172.17.0.2:22465, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:61980") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:38542->172.17.0.2:61980, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:64224") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46482->172.17.0.2:64224, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:65431") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:42216->172.17.0.2:65431, err= 2025/10/19 02:11:32 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/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:9038") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46310->172.17.0.2:9038, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:5163") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:59694->172.17.0.2:5163, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:3024") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:45804->172.17.0.2:3024, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:56213") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:60566->172.17.0.2:56213, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:34529") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:54620->172.17.0.2:34529, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:30758") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:56358->172.17.0.2:30758, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:30100") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:39912->172.17.0.2:30100, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:32180") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37754->172.17.0.2:32180, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:35860") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:42760->172.17.0.2:35860, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:63254") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:50012->172.17.0.2:63254, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:64864") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:60288->172.17.0.2:64864, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:6797") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:40246->172.17.0.2:6797, err= 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:28626") 2025/10/19 02:11:32 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:53690->172.17.0.2:28626, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:1463") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:49854->172.17.0.2:1463, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:13601") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:38064->172.17.0.2:13601, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:43742") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:34818->172.17.0.2:43742, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:43461") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:34038->172.17.0.2:43461, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:33480") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:47198->172.17.0.2:33480, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:18215") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46698->172.17.0.2:18215, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:8739") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37044->172.17.0.2:8739, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:17549") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:58976->172.17.0.2:17549, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:36222") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:59594->172.17.0.2:36222, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:28225") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:50402->172.17.0.2:28225, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:32426") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:57376->172.17.0.2:32426, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:16891") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:60262->172.17.0.2:16891, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:20848") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:43156->172.17.0.2:20848, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:43444") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:41472->172.17.0.2:43444, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:38333") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:36130->172.17.0.2:38333, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:17293") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:41450->172.17.0.2:17293, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:42134") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:52324->172.17.0.2:42134, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:38229") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:59466->172.17.0.2:38229, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:25308") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:39620->172.17.0.2:25308, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:46273") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37758->172.17.0.2:46273, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:40230") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:57718->172.17.0.2:40230, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:15986") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:49462->172.17.0.2:15986, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:15267") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:59342->172.17.0.2:15267, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:5454") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:34438->172.17.0.2:5454, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:45063") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:39330->172.17.0.2:45063, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:46424") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:35060->172.17.0.2:46424, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:40291") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:55740->172.17.0.2:40291, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:42782") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37212->172.17.0.2:42782, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:45754") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37428->172.17.0.2:45754, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:46065") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:47042->172.17.0.2:46065, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:5874") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:47536->172.17.0.2:5874, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:46193") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:52634->172.17.0.2:46193, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:58643") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:39914->172.17.0.2:58643, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:48593") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:44324->172.17.0.2:48593, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:24119") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:45240->172.17.0.2:24119, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:26588") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:55382->172.17.0.2:26588, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:1273") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:47124->172.17.0.2:1273, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:31835") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:34074->172.17.0.2:31835, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:43113") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:44028->172.17.0.2:43113, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:36471") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:59188->172.17.0.2:36471, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:59037") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:50118->172.17.0.2:59037, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:10005") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:59700->172.17.0.2:10005, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:52339") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:42936->172.17.0.2:52339, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:14350") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37534->172.17.0.2:14350, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:52426") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:55332->172.17.0.2:52426, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:25138") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37220->172.17.0.2:25138, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:30620") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:40204->172.17.0.2:30620, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:29025") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:47458->172.17.0.2:29025, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:62344") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:36632->172.17.0.2:62344, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:54904") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:38322->172.17.0.2:54904, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:9763") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:44512->172.17.0.2:9763, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:37040") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:36826->172.17.0.2:37040, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:5356") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:47118->172.17.0.2:5356, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:24007") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:40184->172.17.0.2:24007, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:41470") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:33396->172.17.0.2:41470, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:49395") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:52022->172.17.0.2:49395, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:4896") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:59080->172.17.0.2:4896, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:16227") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:60816->172.17.0.2:16227, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:26643") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:39360->172.17.0.2:26643, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:49626") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:57760->172.17.0.2:49626, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:61268") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:55450->172.17.0.2:61268, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:31493") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:33502->172.17.0.2:31493, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:30285") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:41996->172.17.0.2:30285, err= 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:54376") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:36094->172.17.0.2:54376, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:14551") 2025/10/19 02:11:33 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:56242->172.17.0.2:14551, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46332->172.17.0.2:21, err= 2025/10/19 02:11:34 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred: * potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:40633") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:52218->172.17.0.2:40633, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:54237") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:38532->172.17.0.2:54237, 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/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:45124") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:49654->172.17.0.2:45124, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:38391") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:38572->172.17.0.2:38391, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:34599") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:35976->172.17.0.2:34599, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:30289") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:38508->172.17.0.2:30289, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:16749") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46368->172.17.0.2:16749, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:42810") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:45896->172.17.0.2:42810, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:28502") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:58236->172.17.0.2:28502, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:58886") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:40880->172.17.0.2:58886, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:5182") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:56832->172.17.0.2:5182, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:35181") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:38176->172.17.0.2:35181, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:55329") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:44556->172.17.0.2:55329, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:29249") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:56844->172.17.0.2:29249, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:18205") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:59382->172.17.0.2:18205, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:9190") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:35130->172.17.0.2:9190, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:39570") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:54070->172.17.0.2:39570, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:1111") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:57962->172.17.0.2:1111, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:19516") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:33264->172.17.0.2:19516, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/10/19 02:11:34 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/10/19 02:11:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{q7JkL}" suffix to name 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:46348->172.17.0.2:21, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:28790") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:52658->172.17.0.2:28790, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:8100") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37538->172.17.0.2:8100, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:35942") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:44106->172.17.0.2:35942, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:15078") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:44470->172.17.0.2:15078, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:24367") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:50222->172.17.0.2:24367, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:40161") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:43828->172.17.0.2:40161, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:51101") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:45876->172.17.0.2:51101, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:32965") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:56364->172.17.0.2:32965, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:30495") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:51038->172.17.0.2:30495, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:62943") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:50510->172.17.0.2:62943, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1121: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:6403") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:36876->172.17.0.2:6403, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:21067") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:57482->172.17.0.2:21067, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:18936") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:53888->172.17.0.2:18936, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:46012") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:50404->172.17.0.2:46012, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:9770") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:42388->172.17.0.2:9770, 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/10/19 02:11:34 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-povusoq7liwa/hello? sausage/êé" 2025/10/19 02:11:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{q7JkL}" suffix to name 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:46360->172.17.0.2:21, err= 2025/10/19 02:11:34 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-povusoq7liwa/hello? sausage/êé" to be canonical "TestFTPProftpd{q7JkL}:rclone-test-povusoq7liwa/hello? sausage/êé" 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:18619") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:45996->172.17.0.2:18619, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:31928") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:45278->172.17.0.2:31928, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:46362->172.17.0.2:21, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:54496") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:39932->172.17.0.2:54496, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:23038") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:41772->172.17.0.2:23038, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:14004") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:34290->172.17.0.2:14004, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:10457") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:32962->172.17.0.2:10457, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:28555") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:36468->172.17.0.2:28555, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:48779") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:52568->172.17.0.2:48779, err= 2025/10/19 02:11:34 DEBUG : Waiting for deletions to finish 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:51343") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:59556->172.17.0.2:51343, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:17452") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:34928->172.17.0.2:17452, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:5858") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:34138->172.17.0.2:5858, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:62810") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:49676->172.17.0.2:62810, err= 2025/10/19 02:11:34 INFO : a/b/c/d/e.txt: Deleted 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:10138") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:35670->172.17.0.2:10138, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:10422") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:56492->172.17.0.2:10422, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:42513") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:55872->172.17.0.2:42513, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: dial("tcp","172.17.0.2:48999") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé: > dial: conn=172.17.0.1:43298->172.17.0.2:48999, err= 2025/10/19 02:11:34 DEBUG : removing 1 level 4 directories 2025/10/19 02:11:34 INFO : a/b/c/d: Removing directory 2025/10/19 02:11:34 DEBUG : removing 1 level 3 directories 2025/10/19 02:11:34 INFO : a/b/c: Removing directory 2025/10/19 02:11:34 DEBUG : removing 1 level 2 directories 2025/10/19 02:11:34 INFO : a/b: Removing directory 2025/10/19 02:11:34 DEBUG : removing 1 level 1 directories 2025/10/19 02:11:34 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1254: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:63036") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:57792->172.17.0.2:63036, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:18869") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:52838->172.17.0.2:18869, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:43345") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:48280->172.17.0.2:43345, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:27791") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:57836->172.17.0.2:27791, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:34594") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37820->172.17.0.2:34594, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:20621") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:60024->172.17.0.2:20621, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:55269") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46378->172.17.0.2:21, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:53448->172.17.0.2:55269, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:59474") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:60258->172.17.0.2:59474, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:27945") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:60806->172.17.0.2:27945, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:45172") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:32982->172.17.0.2:45172, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:24779") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:35472->172.17.0.2:24779, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:30477") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:34902->172.17.0.2:30477, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:20896") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:24916") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:49638->172.17.0.2:20896, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:38172->172.17.0.2:24916, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:2619") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:55624->172.17.0.2:2619, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:17739") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:39052->172.17.0.2:17739, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:55767") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:54518->172.17.0.2:55767, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:53005") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:58242->172.17.0.2:53005, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:42097") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:54000->172.17.0.2:42097, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:17789") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:58740->172.17.0.2:17789, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:65076") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:36390->172.17.0.2:65076, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:25905") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:57410->172.17.0.2:25905, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:57505") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:58450->172.17.0.2:57505, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/10/19 02:11:34 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-watamix1vebe" 2025/10/19 02:11:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{q7JkL}" suffix to name 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:46392->172.17.0.2:21, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:27041") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:42990->172.17.0.2:27041, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:4976") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:49688->172.17.0.2:4976, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:64694") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:43090->172.17.0.2:64694, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:46317") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:38136->172.17.0.2:46317, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:40330") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:36884->172.17.0.2:40330, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:63520") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:41986->172.17.0.2:63520, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:6896") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:36462->172.17.0.2:6896, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:15657") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:46514->172.17.0.2:15657, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:15787") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:56946->172.17.0.2:15787, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:42184") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:34882->172.17.0.2:42184, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:43468") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:45212->172.17.0.2:43468, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:44398") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:45578->172.17.0.2:44398, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:43377") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:37202->172.17.0.2:43377, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:53287") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:60306->172.17.0.2:53287, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:17317") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:40674->172.17.0.2:17317, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:1893") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:52296->172.17.0.2:1893, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: dial("tcp","172.17.0.2:14406") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: > dial: conn=172.17.0.1:52870->172.17.0.2:14406, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: Purge dir "new_name" 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-watamix1vebe: 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/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:34117") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:39956->172.17.0.2:34117, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:12592") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:56566->172.17.0.2:12592, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:58452") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:55050->172.17.0.2:58452, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:9745") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:48066->172.17.0.2:9745, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:57460") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:42286->172.17.0.2:57460, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:54242") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:54850->172.17.0.2:54242, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:7474") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:34324->172.17.0.2:7474, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:27257") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:58264->172.17.0.2:27257, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:2928") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:41198->172.17.0.2:2928, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/10/19 02:11:34 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-povusoq7liwa/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/10/19 02:11:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{q7JkL}" suffix to name 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:46406->172.17.0.2:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.2:40444") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:58540->172.17.0.2:40444, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/10/19 02:11:34 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-povusoq7liwa/not found.txt" 2025/10/19 02:11:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{q7JkL}" suffix to name 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/not found.txt: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/not found.txt: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/not found.txt: > dial: conn=172.17.0.1:46416->172.17.0.2:21, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/not found.txt: dial("tcp","172.17.0.2:1573") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/not found.txt: > dial: conn=172.17.0.1:33870->172.17.0.2:1573, err= 2025/10/19 02:11:34 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestFTPProftpd:" path "rclone-test-povusoq7liwa" from "TestFTPProftpd:rclone-test-povusoq7liwa" 2025/10/19 02:11:34 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/10/19 02:11:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{q7JkL}" suffix to name 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:46422->172.17.0.2:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:11430") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:52438->172.17.0.2:11430, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:6451") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:55730->172.17.0.2:6451, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:13876") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:35840->172.17.0.2:13876, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:47988") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:40372->172.17.0.2:47988, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:43525") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:56660->172.17.0.2:43525, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:16398") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:51858->172.17.0.2:16398, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1945: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:19263") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:39428->172.17.0.2:19263, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:30298") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:34130->172.17.0.2:30298, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:13495") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:47734->172.17.0.2:13495, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:57507") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:59818->172.17.0.2:57507, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:63459") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:46266->172.17.0.2:63459, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:44831") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:47080->172.17.0.2:44831, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:48203") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:58468->172.17.0.2:48203, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:59457") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:35012->172.17.0.2:59457, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:11777") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:59098->172.17.0.2:11777, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:23968") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:53652->172.17.0.2:23968, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:35521") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:40708->172.17.0.2:35521, 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/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:51730") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:35494->172.17.0.2:51730, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:59113") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:49340->172.17.0.2:59113, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:44018") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:47190->172.17.0.2:44018, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:26447") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:39694->172.17.0.2:26447, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:24891") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:43046->172.17.0.2:24891, 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/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:16690") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:54788->172.17.0.2:16690, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:3853") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:44704->172.17.0.2:3853, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2025/10/19 02:11:34 DEBUG : Creating backend with remote "TestFTPProftpd,concurrency='0',shut_timeout='1s':rclone-test-povusoq7liwa" 2025/10/19 02:11:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{2VGsd}" suffix to name 2025/10/19 02:11:34 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:34 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: Connecting to FTP server 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46428->172.17.0.2:21, err= 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:23775") 2025/10/19 02:11:34 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:52938->172.17.0.2:23775, 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/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:41427") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:51216->172.17.0.2:41427, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:30270") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:46024->172.17.0.2:30270, err= 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:23992") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:33724->172.17.0.2:23992, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/10/19 02:11:35 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-povusoq7liwa/deeper/nonexisting/directory" 2025/10/19 02:11:35 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/19 02:11:35 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:35 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:35 DEBUG : TestFTPProftpd: detected overridden config - adding "{q7JkL}" suffix to name 2025/10/19 02:11:35 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/19 02:11:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/19 02:11:35 DEBUG : Setting pass="DvzRrKruGUljuSTVNKAsa4wD6w4-VmfMINZYyaVHXPz2CQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/19 02:11:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/deeper/nonexisting/directory: Connecting to FTP server 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/deeper/nonexisting/directory: dial("tcp","172.17.0.2:21") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa/deeper/nonexisting/directory: > dial: conn=172.17.0.1:46442->172.17.0.2: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/10/19 02:11:35 DEBUG : Waiting for deletions to finish 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:36734") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:34746->172.17.0.2:36734, err= 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:50877") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:34402->172.17.0.2:50877, err= 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:59339") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:36694->172.17.0.2:59339, err= 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:30158") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:36876->172.17.0.2:30158, err= 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:10748") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:35354->172.17.0.2:10748, err= 2025/10/19 02:11:35 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:37617") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:41786->172.17.0.2:37617, err= 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:45481") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:57926->172.17.0.2:45481, err= 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:32071") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:35382->172.17.0.2:32071, err= 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:56840") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:33484->172.17.0.2:56840, err= 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:43012") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:51086->172.17.0.2:43012, err= 2025/10/19 02:11:35 DEBUG : removing 1 level 4 directories 2025/10/19 02:11:35 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2025/10/19 02:11:35 DEBUG : removing 1 level 3 directories 2025/10/19 02:11:35 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2025/10/19 02:11:35 DEBUG : removing 1 level 2 directories 2025/10/19 02:11:35 INFO : hello? sausage/êé: Removing directory 2025/10/19 02:11:35 DEBUG : removing 1 level 1 directories 2025/10/19 02:11:35 INFO : hello? sausage: Removing directory 2025/10/19 02:11:35 DEBUG : removing 1 level 0 directories 2025/10/19 02:11:35 INFO : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: Removing directory 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:49139") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:41166->172.17.0.2:49139, err= 2025/10/19 02:11:35 ERROR : error listing: directory not found 2025/10/19 02:11:35 DEBUG : Waiting for deletions to finish 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:28593") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:42584->172.17.0.2:28593, err= 2025/10/19 02:11:35 ERROR : error listing: directory not found 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: dial("tcp","172.17.0.2:50290") 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: > dial: conn=172.17.0.1:50000->172.17.0.2:50290, err= 2025/10/19 02:11:35 ERROR : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: Failed to list "": directory not found 2025/10/19 02:11:35 DEBUG : removing 1 level 0 directories 2025/10/19 02:11:35 INFO : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: Removing directory 2025/10/19 02:11:35 ERROR : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2025/10/19 02:11:35 DEBUG : ftp://172.17.0.2:21/rclone-test-povusoq7liwa: closing 2 unused connections 2025/10/19 02:11:36 NOTICE: TestFTPProftpd: Stopping server --- PASS: TestIntegration (14.94s) --- 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.70s) --- 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.28s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (0.02s) --- 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.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.02s) --- 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.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (0.02s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutError (1.02s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (0.01s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenChunkWriter (0.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (1.29s) --- 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.01s) --- 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.06s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.05s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.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.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (0.05s) --- 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.01s) --- 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.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (0.81s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (0.81s) --- 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.01s) --- 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 14.952s "go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Finished OK in 15.865098515s (try 1/5)