"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestFTPProftpd:" 2025/10/23 05:59:52 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 05:59:53 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 2/10 2025/10/23 05:59:53 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 05:59:54 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 3/10 2025/10/23 05:59:54 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 05:59:55 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 4/10 2025/10/23 05:59:55 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 05:59:56 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 5/10 2025/10/23 05:59:56 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 05:59:57 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 6/10 2025/10/23 05:59:57 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 05:59:58 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 7/10 2025/10/23 05:59:58 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 05:59:59 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 8/10 2025/10/23 05:59:59 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 06:00:00 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 9/10 2025/10/23 06:00:00 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 06:00:01 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 10/10 2025/10/23 06:00:01 NOTICE: TestFTPProftpd: Stopping server 2025/10/23 06:00:01 NOTICE: TestFTPProftpd: Starting server 2025/10/23 06:00:03 NOTICE: TestFTPProftpd: Attempting to connect to "172.17.0.2:21" try 1/100 2025/10/23 06:00:03 DEBUG : TestFTPProftpd: Read 1, error: 2025/10/23 06:00:03 NOTICE: TestFTPProftpd: Started server and connected to "172.17.0.2:21" 2025/10/23 06:00:03 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hefuper9pope" 2025/10/23 06:00:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/23 06:00:03 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/23 06:00:03 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:03 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{Om1DW}" suffix to name 2025/10/23 06:00:03 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:03 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: Connecting to FTP server 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:34264->172.17.0.2:21, err= 2025/10/23 06:00:03 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/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:1678") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:34770->172.17.0.2:1678, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:34857") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48378->172.17.0.2:34857, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/10/23 06:00:03 INFO : dir/subdir: Making directory 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:12248") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:47578->172.17.0.2:12248, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:24834") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:51878->172.17.0.2:24834, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:30576") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:35948->172.17.0.2:30576, err= 2025/10/23 06:00:03 INFO : dir/subdir: Removing directory 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:5257") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:39990->172.17.0.2:5257, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:46535") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:56074->172.17.0.2:46535, err= 2025/10/23 06:00:03 INFO : dir: Removing directory 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:29990") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:53114->172.17.0.2:29990, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:52008") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48706->172.17.0.2:52008, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:20111") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:53514->172.17.0.2:20111, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:5210") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43284->172.17.0.2:5210, err= 2025/10/23 06:00:03 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/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:37338") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:52438->172.17.0.2:37338, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:34686") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:54862->172.17.0.2:34686, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:49446") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:53066->172.17.0.2:49446, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:36616") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:33822->172.17.0.2:36616, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:7399") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:49234->172.17.0.2:7399, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:5209") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43486->172.17.0.2:5209, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:5765") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59542->172.17.0.2:5765, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:46366") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:34432->172.17.0.2:46366, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:4774") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59238->172.17.0.2:4774, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:18158") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:40464->172.17.0.2:18158, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:25092") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43886->172.17.0.2:25092, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:34206") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:49616->172.17.0.2:34206, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:7381") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:33574->172.17.0.2:7381, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:10979") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:53190->172.17.0.2:10979, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:15355") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:51490->172.17.0.2:15355, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:49039") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:52320->172.17.0.2:49039, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:15619") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60932->172.17.0.2:15619, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:4479") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:51514->172.17.0.2:4479, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:12224") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:50022->172.17.0.2:12224, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:44785") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:51134->172.17.0.2:44785, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:5133") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:50194->172.17.0.2:5133, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:46058") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59710->172.17.0.2:46058, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:56009") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:46486->172.17.0.2:56009, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:28944") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:40200->172.17.0.2:28944, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:11100") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:54126->172.17.0.2:11100, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:60243") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59428->172.17.0.2:60243, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:9944") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43786->172.17.0.2:9944, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:40066") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:42720->172.17.0.2:40066, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:46716") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:36540->172.17.0.2:46716, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:29032") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48362->172.17.0.2:29032, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:44253") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:33802->172.17.0.2:44253, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:18520") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59746->172.17.0.2:18520, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:62694") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:56110->172.17.0.2:62694, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:28164") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48858->172.17.0.2:28164, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:54112") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:41664->172.17.0.2:54112, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:4559") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:33606->172.17.0.2:4559, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:32350") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:47936->172.17.0.2:32350, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:58854") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:44716->172.17.0.2:58854, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:49901") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:55822->172.17.0.2:49901, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:36100") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:53342->172.17.0.2:36100, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:11478") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:56156->172.17.0.2:11478, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:9459") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:38286->172.17.0.2:9459, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:4772") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:54682->172.17.0.2:4772, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:17835") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:37094->172.17.0.2:17835, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:19415") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:41630->172.17.0.2:19415, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:19104") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48192->172.17.0.2:19104, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:1340") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:35868->172.17.0.2:1340, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:34010") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:57238->172.17.0.2:34010, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:22559") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:51270->172.17.0.2:22559, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:12540") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:46126->172.17.0.2:12540, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:13260") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:44186->172.17.0.2:13260, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:26669") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60574->172.17.0.2:26669, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:57574") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:56136->172.17.0.2:57574, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:3735") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:58216->172.17.0.2:3735, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:54589") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59802->172.17.0.2:54589, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:3140") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:45290->172.17.0.2:3140, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:62954") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:41382->172.17.0.2:62954, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:63510") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59106->172.17.0.2:63510, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:42183") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:51736->172.17.0.2:42183, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:44135") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:37118->172.17.0.2:44135, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:27007") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:55652->172.17.0.2:27007, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:20901") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:46312->172.17.0.2:20901, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:61631") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:36516->172.17.0.2:61631, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:24167") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:52108->172.17.0.2:24167, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:48042") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60362->172.17.0.2:48042, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:50209") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:39290->172.17.0.2:50209, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:27702") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:54318->172.17.0.2:27702, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:14857") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:37616->172.17.0.2:14857, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:43529") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:37858->172.17.0.2:43529, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:12069") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:52728->172.17.0.2:12069, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:49934") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:53680->172.17.0.2:49934, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:53983") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60438->172.17.0.2:53983, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:20504") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:44104->172.17.0.2:20504, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:53682") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:52086->172.17.0.2:53682, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:6284") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:49152->172.17.0.2:6284, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:38895") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59956->172.17.0.2:38895, err= 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:7251") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59708->172.17.0.2:7251, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:6600") 2025/10/23 06:00:03 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:38314->172.17.0.2:6600, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: Connecting to FTP server 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:34280->172.17.0.2:21, err= 2025/10/23 06:00:04 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred: * potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:64182") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:40318->172.17.0.2:64182, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:23416") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43624->172.17.0.2:23416, 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/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:23258") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:44026->172.17.0.2:23258, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:60779") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:39676->172.17.0.2:60779, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:30931") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43518->172.17.0.2:30931, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:32356") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:38242->172.17.0.2:32356, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:56421") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:35334->172.17.0.2:56421, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:29275") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60462->172.17.0.2:29275, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:64757") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43740->172.17.0.2:64757, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:36265") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:56668->172.17.0.2:36265, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:2260") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:47564->172.17.0.2:2260, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:22697") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48502->172.17.0.2:22697, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:13685") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:42994->172.17.0.2:13685, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:9342") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:56376->172.17.0.2:9342, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:13054") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60858->172.17.0.2:13054, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:55989") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48532->172.17.0.2:55989, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:15296") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:42384->172.17.0.2:15296, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:46736") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:55040->172.17.0.2:46736, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:26099") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:32806->172.17.0.2:26099, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/10/23 06:00:04 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/10/23 06:00:04 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/23 06:00:04 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:04 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:04 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{Om1DW}" suffix to name 2025/10/23 06:00:04 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:04 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:04 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21: Connecting to FTP server 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:34294->172.17.0.2:21, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:46747") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:56044->172.17.0.2:46747, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:38423") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48812->172.17.0.2:38423, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:18040") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:50640->172.17.0.2:18040, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:54951") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:54476->172.17.0.2:54951, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:18178") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:56190->172.17.0.2:18178, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:26535") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:58410->172.17.0.2:26535, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:48690") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:36244->172.17.0.2:48690, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:46859") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:54956->172.17.0.2:46859, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:37952") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:40186->172.17.0.2:37952, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:44582") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:44520->172.17.0.2:44582, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1121: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:16695") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:52322->172.17.0.2:16695, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:12001") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:52442->172.17.0.2:12001, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:56529") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:37120->172.17.0.2:56529, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:15342") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:49026->172.17.0.2:15342, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:34394") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60166->172.17.0.2:34394, 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/23 06:00:04 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hefuper9pope/hello? sausage/êé" 2025/10/23 06:00:04 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/23 06:00:04 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:04 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:04 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{Om1DW}" suffix to name 2025/10/23 06:00:04 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:04 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:04 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: Connecting to FTP server 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:34296->172.17.0.2:21, err= 2025/10/23 06:00:04 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-hefuper9pope/hello? sausage/êé" to be canonical "TestFTPProftpd{Om1DW}:rclone-test-hefuper9pope/hello? sausage/êé" 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:16047") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:48820->172.17.0.2:16047, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:62256") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:58504->172.17.0.2:62256, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: Connecting to FTP server 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:34298->172.17.0.2:21, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:58716") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:41434->172.17.0.2:58716, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:43462") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:56936->172.17.0.2:43462, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:47432") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:40414->172.17.0.2:47432, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:21403") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:56858->172.17.0.2:21403, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:11312") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:56200->172.17.0.2:11312, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:26258") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:48994->172.17.0.2:26258, err= 2025/10/23 06:00:04 DEBUG : Waiting for deletions to finish 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:15270") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:41208->172.17.0.2:15270, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:26006") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:49286->172.17.0.2:26006, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:38866") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:52392->172.17.0.2:38866, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:65164") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:56014->172.17.0.2:65164, err= 2025/10/23 06:00:04 INFO : a/b/c/d/e.txt: Deleted 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:18847") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:51190->172.17.0.2:18847, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:44153") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:51150->172.17.0.2:44153, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:46237") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:40182->172.17.0.2:46237, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: dial("tcp","172.17.0.2:8038") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé: > dial: conn=172.17.0.1:57488->172.17.0.2:8038, err= 2025/10/23 06:00:04 DEBUG : removing 1 level 4 directories 2025/10/23 06:00:04 INFO : a/b/c/d: Removing directory 2025/10/23 06:00:04 DEBUG : removing 1 level 3 directories 2025/10/23 06:00:04 INFO : a/b/c: Removing directory 2025/10/23 06:00:04 DEBUG : removing 1 level 2 directories 2025/10/23 06:00:04 INFO : a/b: Removing directory 2025/10/23 06:00:04 DEBUG : removing 1 level 1 directories 2025/10/23 06:00:04 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1254: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:14253") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:36512->172.17.0.2:14253, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:10586") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:37896->172.17.0.2:10586, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:64302") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60528->172.17.0.2:64302, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:45585") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59116->172.17.0.2:45585, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:1472") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:45374->172.17.0.2:1472, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:28042") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:41992->172.17.0.2:28042, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: Connecting to FTP server 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:34310->172.17.0.2:21, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:44808") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:41400->172.17.0.2:44808, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:4220") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:38342->172.17.0.2:4220, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:36714") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:54360->172.17.0.2:36714, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:29854") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48886->172.17.0.2:29854, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:29278") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:35450->172.17.0.2:29278, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:58470") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:53688->172.17.0.2:58470, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:1970") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:51624->172.17.0.2:1970, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:31542") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:51906->172.17.0.2:31542, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:49375") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:42092->172.17.0.2:49375, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:42913") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60372->172.17.0.2:42913, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:37597") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:33082->172.17.0.2:37597, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:60720") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:52918->172.17.0.2:60720, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:14000") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:34110->172.17.0.2:14000, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:22569") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:49296->172.17.0.2:22569, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:39830") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:40016->172.17.0.2:39830, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:33535") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60252->172.17.0.2:33535, err= 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:51870") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43558->172.17.0.2:51870, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/10/23 06:00:04 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-jofitet1jica" 2025/10/23 06:00:04 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/23 06:00:04 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:04 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:04 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{Om1DW}" suffix to name 2025/10/23 06:00:04 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:04 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:04 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: Connecting to FTP server 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:04 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:34314->172.17.0.2:21, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:19953") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:58702->172.17.0.2:19953, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:50703") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:44926->172.17.0.2:50703, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:51463") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:36042->172.17.0.2:51463, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:22395") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:35216->172.17.0.2:22395, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:36014") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:60472->172.17.0.2:36014, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:36141") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:57834->172.17.0.2:36141, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:20932") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:46498->172.17.0.2:20932, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:40515") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:58012->172.17.0.2:40515, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:59713") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:55966->172.17.0.2:59713, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:45872") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:57312->172.17.0.2:45872, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:64905") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:41396->172.17.0.2:64905, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:27953") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:36118->172.17.0.2:27953, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:24759") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:39016->172.17.0.2:24759, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:54155") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:56292->172.17.0.2:54155, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:13708") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:35098->172.17.0.2:13708, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:33874") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:54560->172.17.0.2:33874, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: dial("tcp","172.17.0.2:40509") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: > dial: conn=172.17.0.1:57800->172.17.0.2:40509, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: Purge dir "new_name" 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-jofitet1jica: 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/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:40261") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:45906->172.17.0.2:40261, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:9092") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:44264->172.17.0.2:9092, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:52248") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:44546->172.17.0.2:52248, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:12218") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:35830->172.17.0.2:12218, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:8279") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:47810->172.17.0.2:8279, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:54322") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:53978->172.17.0.2:54322, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:41913") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:48792->172.17.0.2:41913, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:37730") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43024->172.17.0.2:37730, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:37477") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:39488->172.17.0.2:37477, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/10/23 06:00:05 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hefuper9pope/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/10/23 06:00:05 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/23 06:00:05 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:05 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:05 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:05 DEBUG : TestFTPProftpd: detected overridden config - adding "{Om1DW}" suffix to name 2025/10/23 06:00:05 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:05 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:05 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:34330->172.17.0.2:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.2:31807") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:52980->172.17.0.2:31807, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/10/23 06:00:05 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hefuper9pope/not found.txt" 2025/10/23 06:00:05 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/23 06:00:05 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:05 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:05 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:05 DEBUG : TestFTPProftpd: detected overridden config - adding "{Om1DW}" suffix to name 2025/10/23 06:00:05 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:05 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:05 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/not found.txt: Connecting to FTP server 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/not found.txt: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/not found.txt: > dial: conn=172.17.0.1:34338->172.17.0.2:21, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/not found.txt: dial("tcp","172.17.0.2:19588") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/not found.txt: > dial: conn=172.17.0.1:41780->172.17.0.2:19588, err= 2025/10/23 06:00:05 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestFTPProftpd:" path "rclone-test-hefuper9pope" from "TestFTPProftpd:rclone-test-hefuper9pope" 2025/10/23 06:00:05 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/10/23 06:00:05 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/23 06:00:05 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:05 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:05 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:05 DEBUG : TestFTPProftpd: detected overridden config - adding "{Om1DW}" suffix to name 2025/10/23 06:00:05 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:05 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:05 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: Connecting to FTP server 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:34352->172.17.0.2:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:27013") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:48804->172.17.0.2:27013, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:47432") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:40422->172.17.0.2:47432, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:32696") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:50048->172.17.0.2:32696, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:46069") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:45982->172.17.0.2:46069, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:50957") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:56486->172.17.0.2:50957, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:48239") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:58404->172.17.0.2:48239, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1945: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:45630") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:44646->172.17.0.2:45630, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:50517") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:37086->172.17.0.2:50517, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:21538") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:53264->172.17.0.2:21538, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:8843") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:60524->172.17.0.2:8843, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:24845") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:40226->172.17.0.2:24845, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:55586") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:47396->172.17.0.2:55586, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:34035") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:59524->172.17.0.2:34035, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:12260") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:39746->172.17.0.2:12260, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:50843") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:45120->172.17.0.2:50843, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:44568") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:45308->172.17.0.2:44568, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:31835") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:38280->172.17.0.2:31835, 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/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:33349") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:37780->172.17.0.2:33349, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:23237") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:45176->172.17.0.2:23237, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:51075") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:45802->172.17.0.2:51075, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:10147") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:55260->172.17.0.2:10147, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:65073") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43396->172.17.0.2:65073, 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/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:43897") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:36534->172.17.0.2:43897, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:16525") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:58268->172.17.0.2:16525, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2025/10/23 06:00:05 DEBUG : Creating backend with remote "TestFTPProftpd,concurrency='0',shut_timeout='1s':rclone-test-hefuper9pope" 2025/10/23 06:00:05 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/23 06:00:05 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:05 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:05 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:05 DEBUG : TestFTPProftpd: detected overridden config - adding "{Er-7N}" suffix to name 2025/10/23 06:00:05 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:05 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:05 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:05 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: Connecting to FTP server 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:34360->172.17.0.2:21, err= 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:46682") 2025/10/23 06:00:05 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:59236->172.17.0.2:46682, 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/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:22466") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43128->172.17.0.2:22466, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:33038") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:52738->172.17.0.2:33038, err= 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:38908") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:43640->172.17.0.2:38908, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/10/23 06:00:06 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-hefuper9pope/deeper/nonexisting/directory" 2025/10/23 06:00:06 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/23 06:00:06 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:06 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:06 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:06 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:06 DEBUG : TestFTPProftpd: detected overridden config - adding "{Om1DW}" suffix to name 2025/10/23 06:00:06 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/23 06:00:06 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/23 06:00:06 DEBUG : Setting pass="mSXMzZeLnn3UfsglxCq2V0o9hp9mOyFYN3A91vGY8HuWkw" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/23 06:00:06 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/deeper/nonexisting/directory: Connecting to FTP server 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/deeper/nonexisting/directory: dial("tcp","172.17.0.2:21") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope/deeper/nonexisting/directory: > dial: conn=172.17.0.1:38506->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/23 06:00:06 DEBUG : Waiting for deletions to finish 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:32265") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:46396->172.17.0.2:32265, err= 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:21125") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:39288->172.17.0.2:21125, err= 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:55409") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:40532->172.17.0.2:55409, err= 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:7519") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:44390->172.17.0.2:7519, err= 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:62843") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:44768->172.17.0.2:62843, err= 2025/10/23 06:00:06 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:53227") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:34932->172.17.0.2:53227, err= 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:5845") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:38524->172.17.0.2:5845, err= 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:54496") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:46314->172.17.0.2:54496, err= 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:40821") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:58476->172.17.0.2:40821, err= 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:20748") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:53148->172.17.0.2:20748, err= 2025/10/23 06:00:06 DEBUG : removing 1 level 4 directories 2025/10/23 06:00:06 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2025/10/23 06:00:06 DEBUG : removing 1 level 3 directories 2025/10/23 06:00:06 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2025/10/23 06:00:06 DEBUG : removing 1 level 2 directories 2025/10/23 06:00:06 INFO : hello? sausage/êé: Removing directory 2025/10/23 06:00:06 DEBUG : removing 1 level 1 directories 2025/10/23 06:00:06 INFO : hello? sausage: Removing directory 2025/10/23 06:00:06 DEBUG : removing 1 level 0 directories 2025/10/23 06:00:06 INFO : ftp://172.17.0.2:21/rclone-test-hefuper9pope: Removing directory 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:62789") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:60970->172.17.0.2:62789, err= 2025/10/23 06:00:06 ERROR : error listing: directory not found 2025/10/23 06:00:06 DEBUG : Waiting for deletions to finish 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:44124") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:38986->172.17.0.2:44124, err= 2025/10/23 06:00:06 ERROR : error listing: directory not found 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: dial("tcp","172.17.0.2:53263") 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: > dial: conn=172.17.0.1:55094->172.17.0.2:53263, err= 2025/10/23 06:00:06 ERROR : ftp://172.17.0.2:21/rclone-test-hefuper9pope: Failed to list "": directory not found 2025/10/23 06:00:06 DEBUG : removing 1 level 0 directories 2025/10/23 06:00:06 INFO : ftp://172.17.0.2:21/rclone-test-hefuper9pope: Removing directory 2025/10/23 06:00:06 ERROR : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2025/10/23 06:00:06 DEBUG : ftp://172.17.0.2:21/rclone-test-hefuper9pope: closing 2 unused connections 2025/10/23 06:00:06 NOTICE: TestFTPProftpd: Stopping server --- PASS: TestIntegration (15.13s) --- 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.65s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.01s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.00s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.00s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (0.18s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (0.01s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutError (1.02s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (0.01s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenChunkWriter (0.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (1.38s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.02s) --- 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.08s) --- 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.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (0.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.02s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (0.02s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (0.89s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (0.89s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.01s) --- SKIP: TestIntegration/FsMkdir/FsDirSetModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsMkdirMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsDirectory (0.00s) --- PASS: TestIntegration/FsShutdown (0.00s) === RUN TestIntegrationProftpd ftp_test.go:23: skipping as -remote is set --- SKIP: TestIntegrationProftpd (0.00s) === RUN TestIntegrationPureftpd ftp_test.go:34: skipping as -remote is set --- SKIP: TestIntegrationPureftpd (0.00s) === RUN TestIntegrationVsftpd ftp_test.go:45: skipping as -remote is set --- SKIP: TestIntegrationVsftpd (0.00s) PASS ok github.com/rclone/rclone/backend/ftp 15.151s "go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Finished OK in 15.974852963s (try 1/5)