"go test -v -timeout 1h0m0s -remote TestFTPPureftpd: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestFTPPureftpd:" 2025/10/22 01:08:04 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:05 NOTICE: TestFTPPureftpd: Attempting to stop TestFTPPureftpd try 2/10 2025/10/22 01:08:05 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:06 NOTICE: TestFTPPureftpd: Attempting to stop TestFTPPureftpd try 3/10 2025/10/22 01:08:06 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:07 NOTICE: TestFTPPureftpd: Attempting to stop TestFTPPureftpd try 4/10 2025/10/22 01:08:07 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:08 NOTICE: TestFTPPureftpd: Attempting to stop TestFTPPureftpd try 5/10 2025/10/22 01:08:08 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:09 NOTICE: TestFTPPureftpd: Attempting to stop TestFTPPureftpd try 6/10 2025/10/22 01:08:09 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:10 NOTICE: TestFTPPureftpd: Attempting to stop TestFTPPureftpd try 7/10 2025/10/22 01:08:10 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:11 NOTICE: TestFTPPureftpd: Attempting to stop TestFTPPureftpd try 8/10 2025/10/22 01:08:11 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:12 NOTICE: TestFTPPureftpd: Attempting to stop TestFTPPureftpd try 9/10 2025/10/22 01:08:12 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:13 NOTICE: TestFTPPureftpd: Attempting to stop TestFTPPureftpd try 10/10 2025/10/22 01:08:13 NOTICE: TestFTPPureftpd: Stopping server 2025/10/22 01:08:13 NOTICE: TestFTPPureftpd: Starting server 2025/10/22 01:08:14 NOTICE: TestFTPPureftpd: Attempting to connect to "172.17.0.3:21" try 1/100 2025/10/22 01:08:14 DEBUG : TestFTPPureftpd: Read 1, error: 2025/10/22 01:08:14 NOTICE: TestFTPPureftpd: Started server and connected to "172.17.0.3:21" 2025/10/22 01:08:14 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lovobor4ziqi" 2025/10/22 01:08:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/22 01:08:14 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/10/22 01:08:14 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:14 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:14 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:14 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:14 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6dIUE}" suffix to name 2025/10/22 01:08:14 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:14 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:14 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:14 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: Connecting to FTP server 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:45130->172.17.0.3:21, err= 2025/10/22 01:08:14 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE === RUN TestIntegration/FsCheckWrap fstests.go:479: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:507: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36301") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:32914->172.17.0.3:36301, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38766") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:57918->172.17.0.3:38766, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/10/22 01:08:14 INFO : dir/subdir: Making directory 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36561") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:48868->172.17.0.3:36561, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31433") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:49936->172.17.0.3:31433, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30281") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:42790->172.17.0.3:30281, err= 2025/10/22 01:08:14 INFO : dir/subdir: Removing directory 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32132") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:34680->172.17.0.3:32132, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35307") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:51482->172.17.0.3:35307, err= 2025/10/22 01:08:14 INFO : dir: Removing directory 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34469") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:32930->172.17.0.3:34469, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34842") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:43686->172.17.0.3:34842, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34083") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:41078->172.17.0.3:34083, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37759") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:59240->172.17.0.3:37759, err= 2025/10/22 01:08:14 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/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36300") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:38262->172.17.0.3:36300, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35796") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50498->172.17.0.3:35796, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33859") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:56872->172.17.0.3:33859, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31764") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37412->172.17.0.3:31764, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31926") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:42858->172.17.0.3:31926, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33667") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:49410->172.17.0.3:33667, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37642") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:33024->172.17.0.3:37642, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39412") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:44220->172.17.0.3:39412, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36042") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:32788->172.17.0.3:36042, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35798") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:41486->172.17.0.3:35798, err= 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30673") 2025/10/22 01:08:14 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:38182->172.17.0.3:30673, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30265") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:51418->172.17.0.3:30265, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37840") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:48662->172.17.0.3:37840, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37679") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:33132->172.17.0.3:37679, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33397") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:36152->172.17.0.3:33397, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39200") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37106->172.17.0.3:39200, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38104") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:44408->172.17.0.3:38104, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31917") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:34096->172.17.0.3:31917, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30550") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:52276->172.17.0.3:30550, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36635") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:36698->172.17.0.3:36635, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37371") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:43630->172.17.0.3:37371, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34298") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:60278->172.17.0.3:34298, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38353") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:52750->172.17.0.3:38353, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34813") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:41356->172.17.0.3:34813, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33673") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:58710->172.17.0.3:33673, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38068") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:46722->172.17.0.3:38068, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30632") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:39168->172.17.0.3:30632, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33529") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:45514->172.17.0.3:33529, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38405") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:51584->172.17.0.3:38405, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39089") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50190->172.17.0.3:39089, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31793") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:41718->172.17.0.3:31793, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37751") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:53444->172.17.0.3:37751, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34357") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:43300->172.17.0.3:34357, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38882") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:34584->172.17.0.3:38882, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35243") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:49684->172.17.0.3:35243, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30764") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:57982->172.17.0.3:30764, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33727") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37424->172.17.0.3:33727, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32725") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:35420->172.17.0.3:32725, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33737") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:40278->172.17.0.3:33737, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37014") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:54972->172.17.0.3:37014, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33858") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:44162->172.17.0.3:33858, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37923") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:52372->172.17.0.3:37923, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31435") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37868->172.17.0.3:31435, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31022") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:44520->172.17.0.3:31022, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38412") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:60070->172.17.0.3:38412, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39589") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:32774->172.17.0.3:39589, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37914") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:43978->172.17.0.3:37914, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35124") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:59442->172.17.0.3:35124, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35289") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:43102->172.17.0.3:35289, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34468") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:32868->172.17.0.3:34468, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35326") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:55312->172.17.0.3:35326, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37149") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:46492->172.17.0.3:37149, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35726") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:48004->172.17.0.3:35726, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39986") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:46858->172.17.0.3:39986, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35958") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:48452->172.17.0.3:35958, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31555") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:42748->172.17.0.3:31555, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34517") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:54646->172.17.0.3:34517, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33010") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:56546->172.17.0.3:33010, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38640") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:42176->172.17.0.3:38640, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36891") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:59846->172.17.0.3:36891, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32050") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:54378->172.17.0.3:32050, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34518") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:58380->172.17.0.3:34518, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32724") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:59536->172.17.0.3:32724, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31592") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:49140->172.17.0.3:31592, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33287") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:35464->172.17.0.3:33287, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38530") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:36992->172.17.0.3:38530, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37381") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:35032->172.17.0.3:37381, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35980") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:44834->172.17.0.3:35980, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35135") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:46098->172.17.0.3:35135, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32303") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:41866->172.17.0.3:32303, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36732") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:46396->172.17.0.3:36732, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36912") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:54018->172.17.0.3:36912, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38760") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:45262->172.17.0.3:38760, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37861") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:45238->172.17.0.3:37861, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35560") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:58512->172.17.0.3:35560, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38899") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:35972->172.17.0.3:38899, err= 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30299") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:35136->172.17.0.3:30299, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34812") 2025/10/22 01:08:15 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:60996->172.17.0.3:34812, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37812->172.17.0.3:21, err= 2025/10/22 01:08:16 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred: * potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30650") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:32890->172.17.0.3:30650, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34644") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50398->172.17.0.3:34644, 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/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32430") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:33132->172.17.0.3:32430, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37570") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:40398->172.17.0.3:37570, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31856") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:58246->172.17.0.3:31856, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31121") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:48556->172.17.0.3:31121, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34371") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:52768->172.17.0.3:34371, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30766") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:51540->172.17.0.3:30766, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35375") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:54066->172.17.0.3:35375, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37483") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:49914->172.17.0.3:37483, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35424") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:52142->172.17.0.3:35424, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31572") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:53452->172.17.0.3:31572, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36196") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:46770->172.17.0.3:36196, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32450") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:56924->172.17.0.3:32450, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34195") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:44924->172.17.0.3:34195, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37784") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:36652->172.17.0.3:37784, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33377") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:45132->172.17.0.3:33377, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38345") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:34034->172.17.0.3:38345, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37251") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:59484->172.17.0.3:37251, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/10/22 01:08:16 DEBUG : Creating backend with remote "TestFTPPureftpd:" 2025/10/22 01:08:16 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6dIUE}" suffix to name 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:37824->172.17.0.3:21, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:36001") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:44372->172.17.0.3:36001, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30037") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50106->172.17.0.3:30037, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30425") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:35252->172.17.0.3:30425, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32854") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:42662->172.17.0.3:32854, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33560") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:41202->172.17.0.3:33560, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39930") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:39088->172.17.0.3:39930, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37510") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:33160->172.17.0.3:37510, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32311") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:36778->172.17.0.3:32311, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33976") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:56180->172.17.0.3:33976, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37742") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:39232->172.17.0.3:37742, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1121: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30417") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:58488->172.17.0.3:30417, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39872") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:39300->172.17.0.3:39872, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36566") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50184->172.17.0.3:36566, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33461") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:43826->172.17.0.3:33461, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34822") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37318->172.17.0.3:34822, 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/22 01:08:16 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lovobor4ziqi/hello? sausage/êé" 2025/10/22 01:08:16 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6dIUE}" suffix to name 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:37834->172.17.0.3:21, err= 2025/10/22 01:08:16 DEBUG : fs cache: renaming cache item "TestFTPPureftpd:rclone-test-lovobor4ziqi/hello? sausage/êé" to be canonical "TestFTPPureftpd{6dIUE}:rclone-test-lovobor4ziqi/hello? sausage/êé" 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:34347") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:39520->172.17.0.3:34347, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:38600") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:48102->172.17.0.3:38600, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:38156") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:51596->172.17.0.3:38156, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:37840->172.17.0.3:21, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:35647") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:33298->172.17.0.3:35647, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:34749") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:36824->172.17.0.3:34749, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:38686") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:41172->172.17.0.3:38686, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:32412") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:40938->172.17.0.3:32412, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:32240") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:55632->172.17.0.3:32240, err= 2025/10/22 01:08:16 DEBUG : Waiting for deletions to finish 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:33637") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:40994->172.17.0.3:33637, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:36885") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:47320->172.17.0.3:36885, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:32789") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:33826->172.17.0.3:32789, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:35499") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:37946->172.17.0.3:35499, err= 2025/10/22 01:08:16 INFO : a/b/c/d/e.txt: Deleted 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:33156") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:46526->172.17.0.3:33156, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:35612") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:32840->172.17.0.3:35612, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:30144") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:43708->172.17.0.3:30144, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: dial("tcp","172.17.0.3:31569") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé: > dial: conn=172.17.0.1:60948->172.17.0.3:31569, err= 2025/10/22 01:08:16 DEBUG : removing 1 level 4 directories 2025/10/22 01:08:16 INFO : a/b/c/d: Removing directory 2025/10/22 01:08:16 DEBUG : removing 1 level 3 directories 2025/10/22 01:08:16 INFO : a/b/c: Removing directory 2025/10/22 01:08:16 DEBUG : removing 1 level 2 directories 2025/10/22 01:08:16 INFO : a/b: Removing directory 2025/10/22 01:08:16 DEBUG : removing 1 level 1 directories 2025/10/22 01:08:16 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1254: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31990") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:58240->172.17.0.3:31990, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33931") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:34284->172.17.0.3:33931, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39815") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37212->172.17.0.3:39815, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39288") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50640->172.17.0.3:39288, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33407") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:52518->172.17.0.3:33407, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38039") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:32990->172.17.0.3:38039, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31136") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:51510->172.17.0.3:31136, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37846->172.17.0.3:21, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38999") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:55580->172.17.0.3:38999, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36507") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50288->172.17.0.3:36507, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35513") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:43386->172.17.0.3:35513, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33931") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:34292->172.17.0.3:33931, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36192") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:54506->172.17.0.3:36192, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33513") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38225") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:60708->172.17.0.3:38225, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:48048->172.17.0.3:33513, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38120") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:41072->172.17.0.3:38120, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37201") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:59206->172.17.0.3:37201, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32421") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:51214->172.17.0.3:32421, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36310") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50102->172.17.0.3:36310, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39702") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37892->172.17.0.3:39702, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31911") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:55042->172.17.0.3:31911, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33772") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:45842->172.17.0.3:33772, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31311") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:45978->172.17.0.3:31311, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34068") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:35748->172.17.0.3:34068, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/10/22 01:08:16 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-qakanev4keti" 2025/10/22 01:08:16 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6dIUE}" suffix to name 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:37858->172.17.0.3:21, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35432") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:60594->172.17.0.3:35432, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:31076") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:38080->172.17.0.3:31076, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:34750") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:46794->172.17.0.3:34750, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:36000") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:41916->172.17.0.3:36000, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:38521") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:50660->172.17.0.3:38521, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:39819") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:33998->172.17.0.3:39819, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:39208") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:35370->172.17.0.3:39208, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:35173") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:52770->172.17.0.3:35173, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36302") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:55118->172.17.0.3:36302, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30469") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:39422->172.17.0.3:30469, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38380") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:54642->172.17.0.3:38380, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30616") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:34176->172.17.0.3:30616, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33287") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50750->172.17.0.3:33287, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:35999") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:40198->172.17.0.3:35999, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:33899") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:45304->172.17.0.3:33899, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:32212") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:53516->172.17.0.3:32212, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: dial("tcp","172.17.0.3:30549") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: > dial: conn=172.17.0.1:47924->172.17.0.3:30549, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: Purge dir "new_name" 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-qakanev4keti: 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/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35219") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:43520->172.17.0.3:35219, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30457") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50682->172.17.0.3:30457, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34427") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:42740->172.17.0.3:34427, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32430") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:33138->172.17.0.3:32430, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33716") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50718->172.17.0.3:33716, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30571") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:32802->172.17.0.3:30571, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34497") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:47118->172.17.0.3:34497, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33594") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:44426->172.17.0.3:33594, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31874") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:46990->172.17.0.3:31874, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/10/22 01:08:16 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lovobor4ziqi/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/10/22 01:08:16 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6dIUE}" suffix to name 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:37868->172.17.0.3:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.3:32624") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:51362->172.17.0.3:32624, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/10/22 01:08:16 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lovobor4ziqi/not found.txt" 2025/10/22 01:08:16 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6dIUE}" suffix to name 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/not found.txt: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/not found.txt: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/not found.txt: > dial: conn=172.17.0.1:37878->172.17.0.3:21, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/not found.txt: dial("tcp","172.17.0.3:34399") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/not found.txt: > dial: conn=172.17.0.1:53582->172.17.0.3:34399, err= 2025/10/22 01:08:16 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestFTPPureftpd:" path "rclone-test-lovobor4ziqi" from "TestFTPPureftpd:rclone-test-lovobor4ziqi" 2025/10/22 01:08:16 DEBUG : Creating backend with remote "TestFTPPureftpd:" 2025/10/22 01:08:16 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6dIUE}" suffix to name 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:37892->172.17.0.3:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:39341") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:35402->172.17.0.3:39341, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:36563") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:60986->172.17.0.3:36563, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:31459") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:52810->172.17.0.3:31459, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:38172") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:44768->172.17.0.3:38172, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:35481") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:50672->172.17.0.3:35481, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:33417") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:46354->172.17.0.3:33417, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1945: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:30872") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:34432->172.17.0.3:30872, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:39595") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:49512->172.17.0.3:39595, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:30009") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:50126->172.17.0.3:30009, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:33359") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:38460->172.17.0.3:33359, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:37582") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:35144->172.17.0.3:37582, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:34954") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:60480->172.17.0.3:34954, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:33154") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:60070->172.17.0.3:33154, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:35501") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:59744->172.17.0.3:35501, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:33509") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:54344->172.17.0.3:33509, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:34561") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:52090->172.17.0.3:34561, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: dial("tcp","172.17.0.3:34156") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21: > dial: conn=172.17.0.1:55192->172.17.0.3:34156, 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/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34013") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:44310->172.17.0.3:34013, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33584") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:34658->172.17.0.3:33584, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33510") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:39636->172.17.0.3:33510, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36665") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:55050->172.17.0.3:36665, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:37517") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:39640->172.17.0.3:37517, 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/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:32024") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:60004->172.17.0.3:32024, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39737") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:33664->172.17.0.3:39737, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2025/10/22 01:08:16 DEBUG : Creating backend with remote "TestFTPPureftpd,concurrency='0',shut_timeout='1s':rclone-test-lovobor4ziqi" 2025/10/22 01:08:16 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : TestFTPPureftpd: detected overridden config - adding "{ImR8G}" suffix to name 2025/10/22 01:08:16 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:16 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:16 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:16 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: Connecting to FTP server 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:37900->172.17.0.3:21, err= 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34783") 2025/10/22 01:08:16 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:39622->172.17.0.3:34783, 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/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34100") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:51144->172.17.0.3:34100, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35698") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:35052->172.17.0.3:35698, err= 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:36685") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:47396->172.17.0.3:36685, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/10/22 01:08:17 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-lovobor4ziqi/deeper/nonexisting/directory" 2025/10/22 01:08:17 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/10/22 01:08:17 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:17 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:17 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:17 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:17 DEBUG : TestFTPPureftpd: detected overridden config - adding "{6dIUE}" suffix to name 2025/10/22 01:08:17 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/10/22 01:08:17 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/10/22 01:08:17 DEBUG : Setting pass="c3On3Vm5n2iy8ssiSTWYZMssqhrGwAR_Qij6juGGniI" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/10/22 01:08:17 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/deeper/nonexisting/directory: Connecting to FTP server 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/deeper/nonexisting/directory: dial("tcp","172.17.0.3:21") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi/deeper/nonexisting/directory: > dial: conn=172.17.0.1:37902->172.17.0.3:21, err= === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:2625: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:2668: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory fstests.go:2725: FS has no Directory methods and doesn't Wrap 2025/10/22 01:08:17 DEBUG : Waiting for deletions to finish 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38415") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:43834->172.17.0.3:38415, err= 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:38248") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:49230->172.17.0.3:38248, err= 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:33866") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:59698->172.17.0.3:33866, err= 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:39822") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50094->172.17.0.3:39822, err= 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35943") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:33022->172.17.0.3:35943, err= 2025/10/22 01:08:17 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35799") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:46580->172.17.0.3:35799, err= 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31418") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:50542->172.17.0.3:31418, err= 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34957") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:60608->172.17.0.3:34957, err= 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34891") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:35518->172.17.0.3:34891, err= 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:30028") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:59128->172.17.0.3:30028, err= 2025/10/22 01:08:17 DEBUG : removing 1 level 4 directories 2025/10/22 01:08:17 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2025/10/22 01:08:17 DEBUG : removing 1 level 3 directories 2025/10/22 01:08:17 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2025/10/22 01:08:17 DEBUG : removing 1 level 2 directories 2025/10/22 01:08:17 INFO : hello? sausage/êé: Removing directory 2025/10/22 01:08:17 DEBUG : removing 1 level 1 directories 2025/10/22 01:08:17 INFO : hello? sausage: Removing directory 2025/10/22 01:08:17 DEBUG : removing 1 level 0 directories 2025/10/22 01:08:17 INFO : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: Removing directory 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:31282") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:39288->172.17.0.3:31282, err= 2025/10/22 01:08:17 ERROR : error listing: directory not found 2025/10/22 01:08:17 DEBUG : Waiting for deletions to finish 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:34511") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:46872->172.17.0.3:34511, err= 2025/10/22 01:08:17 ERROR : error listing: directory not found 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: dial("tcp","172.17.0.3:35990") 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: > dial: conn=172.17.0.1:47270->172.17.0.3:35990, err= 2025/10/22 01:08:17 ERROR : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: Failed to list "": directory not found 2025/10/22 01:08:17 DEBUG : removing 1 level 0 directories 2025/10/22 01:08:17 INFO : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: Removing directory 2025/10/22 01:08:17 ERROR : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2025/10/22 01:08:17 DEBUG : ftp://172.17.0.3:21/rclone-test-lovobor4ziqi: closing 2 unused connections 2025/10/22 01:08:27 NOTICE: TestFTPPureftpd: Stopping server --- PASS: TestIntegration (33.88s) --- 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.39s) --- 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.11s) --- 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.00s) --- 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.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (0.00s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutError (1.04s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenChunkWriter (0.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (1.19s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.02s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPurge (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (0.06s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.03s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (0.03s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (0.87s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (0.86s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (0.00s) --- 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 33.898s "go test -v -timeout 1h0m0s -remote TestFTPPureftpd: -verbose" - Finished OK in 34.876705612s (try 1/5)