"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestFTPProftpd:" 2025/11/18 04:49:31 NOTICE: TestFTPProftpd: Starting server 2025/11/18 04:49:32 NOTICE: TestFTPProftpd: Attempting to connect to "172.17.0.5:21" try 1/100 2025/11/18 04:49:32 DEBUG : TestFTPProftpd: Read 1, error: 2025/11/18 04:49:32 NOTICE: TestFTPProftpd: Started server and connected to "172.17.0.5:21" 2025/11/18 04:49:32 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-zuqocug5nado" 2025/11/18 04:49:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/18 04:49:32 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/11/18 04:49:32 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:32 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:32 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:32 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:32 DEBUG : TestFTPProftpd: detected overridden config - adding "{57oW9}" suffix to name 2025/11/18 04:49:32 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:32 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:32 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:32 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:32 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: Connecting to FTP server 2025/11/18 04:49:32 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:32 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53594->172.17.0.5:21, err= 2025/11/18 04:49:33 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE === RUN TestIntegration/FsCheckWrap fstests.go:479: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:507: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:28479") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:50000->172.17.0.5:28479, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:45311") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:60860->172.17.0.5:45311, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/11/18 04:49:33 INFO : dir/subdir: Making directory 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:63164") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36450->172.17.0.5:63164, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:22838") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53142->172.17.0.5:22838, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:14516") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43428->172.17.0.5:14516, err= 2025/11/18 04:49:33 INFO : dir/subdir: Removing directory 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:35377") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:33182->172.17.0.5:35377, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:19157") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:41472->172.17.0.5:19157, err= 2025/11/18 04:49:33 INFO : dir: Removing directory 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:19365") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:56852->172.17.0.5:19365, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:16476") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:48872->172.17.0.5:16476, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:30357") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:48898->172.17.0.5:30357, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:44438") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53736->172.17.0.5:44438, err= 2025/11/18 04:49:33 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/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:7526") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:45328->172.17.0.5:7526, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:16033") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:46452->172.17.0.5:16033, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:4028") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40048->172.17.0.5:4028, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:31820") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:51332->172.17.0.5:31820, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:2703") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:39742->172.17.0.5:2703, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:44742") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:52248->172.17.0.5:44742, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:25437") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:52692->172.17.0.5:25437, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:39065") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40708->172.17.0.5:39065, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:24450") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40350->172.17.0.5:24450, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:22324") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:34202->172.17.0.5:22324, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:13068") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:32840->172.17.0.5:13068, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:28942") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:59622->172.17.0.5:28942, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:62334") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:52632->172.17.0.5:62334, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:29828") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:33708->172.17.0.5:29828, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:21907") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43354->172.17.0.5:21907, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:11721") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43928->172.17.0.5:11721, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:1526") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:48592->172.17.0.5:1526, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:58501") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:51884->172.17.0.5:58501, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:5974") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:57194->172.17.0.5:5974, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:43744") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40702->172.17.0.5:43744, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:21446") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:57942->172.17.0.5:21446, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:50261") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:54548->172.17.0.5:50261, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:41374") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:48472->172.17.0.5:41374, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:43260") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:46442->172.17.0.5:43260, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:63754") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36384->172.17.0.5:63754, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:11216") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:35360->172.17.0.5:11216, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:61394") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36532->172.17.0.5:61394, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:17585") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:41492->172.17.0.5:17585, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:26669") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:56938->172.17.0.5:26669, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:26216") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53922->172.17.0.5:26216, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:60999") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:42634->172.17.0.5:60999, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:33171") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:58698->172.17.0.5:33171, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:41226") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:52218->172.17.0.5:41226, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:64003") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:51186->172.17.0.5:64003, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:63967") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:51886->172.17.0.5:63967, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:42905") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:59608->172.17.0.5:42905, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:43211") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40366->172.17.0.5:43211, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:23870") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:38700->172.17.0.5:23870, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:16435") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:50046->172.17.0.5:16435, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:2126") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:39878->172.17.0.5:2126, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:45171") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:44732->172.17.0.5:45171, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:28479") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:50008->172.17.0.5:28479, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:30044") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:42108->172.17.0.5:30044, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:41970") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:55392->172.17.0.5:41970, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:57284") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:46550->172.17.0.5:57284, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:50928") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:57288->172.17.0.5:50928, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:52668") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:47836->172.17.0.5:52668, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:57786") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:32818->172.17.0.5:57786, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:43895") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43708->172.17.0.5:43895, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:57618") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:45332->172.17.0.5:57618, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:35996") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:41992->172.17.0.5:35996, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:64317") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36716->172.17.0.5:64317, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:42345") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:59298->172.17.0.5:42345, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:11836") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:42472->172.17.0.5:11836, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:42043") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36698->172.17.0.5:42043, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:40564") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53016->172.17.0.5:40564, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:22029") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53394->172.17.0.5:22029, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:37902") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:51206->172.17.0.5:37902, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:57125") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40190->172.17.0.5:57125, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:47674") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:55930->172.17.0.5:47674, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:63095") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:39092->172.17.0.5:63095, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:52589") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:58358->172.17.0.5:52589, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:15311") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40456->172.17.0.5:15311, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:38786") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:58676->172.17.0.5:38786, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:51058") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:46716->172.17.0.5:51058, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:13744") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:51010->172.17.0.5:13744, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:16156") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:46238->172.17.0.5:16156, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:28735") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:42374->172.17.0.5:28735, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:36590") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:46252->172.17.0.5:36590, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:31568") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:59714->172.17.0.5:31568, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:29838") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:38898->172.17.0.5:29838, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:16226") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:60332->172.17.0.5:16226, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:59024") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:58636->172.17.0.5:59024, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:58859") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:37252->172.17.0.5:58859, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:57173") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:38182->172.17.0.5:57173, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:50773") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:34700->172.17.0.5:50773, err= 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:44253") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:58462->172.17.0.5:44253, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:44306") 2025/11/18 04:49:33 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:58436->172.17.0.5:44306, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: Connecting to FTP server 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53606->172.17.0.5:21, err= 2025/11/18 04:49:34 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred: * potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:32572") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:48386->172.17.0.5:32572, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:60037") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:35030->172.17.0.5:60037, 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/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:35724") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:60476->172.17.0.5:35724, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:6652") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:60566->172.17.0.5:6652, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:5447") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36772->172.17.0.5:5447, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:39747") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43628->172.17.0.5:39747, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:35314") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:50796->172.17.0.5:35314, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:29630") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:55904->172.17.0.5:29630, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:63725") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:51718->172.17.0.5:63725, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:45483") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:39082->172.17.0.5:45483, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:33874") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:56378->172.17.0.5:33874, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:18389") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:56580->172.17.0.5:18389, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:54256") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:57370->172.17.0.5:54256, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:55745") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36740->172.17.0.5:55745, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:59322") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:55028->172.17.0.5:59322, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:50185") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:50800->172.17.0.5:50185, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:6062") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:57742->172.17.0.5:6062, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:36456") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:35234->172.17.0.5:36456, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:38363") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43288->172.17.0.5:38363, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/11/18 04:49:34 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/11/18 04:49:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/11/18 04:49:34 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:34 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{57oW9}" suffix to name 2025/11/18 04:49:34 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:34 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21: Connecting to FTP server 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:53612->172.17.0.5:21, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:49520") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:59562->172.17.0.5:49520, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:4215") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:39144->172.17.0.5:4215, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:43008") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43118->172.17.0.5:43008, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:39341") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:59156->172.17.0.5:39341, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:45440") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:39326->172.17.0.5:45440, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:40249") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53530->172.17.0.5:40249, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:38493") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:58530->172.17.0.5:38493, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:41679") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:52494->172.17.0.5:41679, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:8952") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:54818->172.17.0.5:8952, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:61906") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:49930->172.17.0.5:61906, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1121: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:5358") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:49024->172.17.0.5:5358, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:51490") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:52378->172.17.0.5:51490, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:9986") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:60124->172.17.0.5:9986, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:36907") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40342->172.17.0.5:36907, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:45993") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:58362->172.17.0.5:45993, 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/11/18 04:49:34 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-zuqocug5nado/hello? sausage/êé" 2025/11/18 04:49:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/11/18 04:49:34 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:34 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{57oW9}" suffix to name 2025/11/18 04:49:34 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:34 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: Connecting to FTP server 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:53628->172.17.0.5:21, err= 2025/11/18 04:49:34 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-zuqocug5nado/hello? sausage/êé" to be canonical "TestFTPProftpd{57oW9}:rclone-test-zuqocug5nado/hello? sausage/êé" 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:47758") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:48052->172.17.0.5:47758, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:1406") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:54248->172.17.0.5:1406, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: Connecting to FTP server 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:53640->172.17.0.5:21, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:52957") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:59168->172.17.0.5:52957, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:38071") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:46596->172.17.0.5:38071, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:11907") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:33702->172.17.0.5:11907, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:20448") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:48162->172.17.0.5:20448, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:58398") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:53874->172.17.0.5:58398, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:36606") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:53436->172.17.0.5:36606, err= 2025/11/18 04:49:34 DEBUG : Waiting for deletions to finish 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:42106") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:45418->172.17.0.5:42106, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:10602") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:32916->172.17.0.5:10602, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:17222") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:41894->172.17.0.5:17222, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:62696") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:50750->172.17.0.5:62696, err= 2025/11/18 04:49:34 INFO : a/b/c/d/e.txt: Deleted 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:51735") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:41130->172.17.0.5:51735, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:41631") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:37094->172.17.0.5:41631, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:12736") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:37188->172.17.0.5:12736, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: dial("tcp","172.17.0.5:23620") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé: > dial: conn=172.17.0.1:44764->172.17.0.5:23620, err= 2025/11/18 04:49:34 DEBUG : removing 1 level 4 directories 2025/11/18 04:49:34 INFO : a/b/c/d: Removing directory 2025/11/18 04:49:34 DEBUG : removing 1 level 3 directories 2025/11/18 04:49:34 INFO : a/b/c: Removing directory 2025/11/18 04:49:34 DEBUG : removing 1 level 2 directories 2025/11/18 04:49:34 INFO : a/b: Removing directory 2025/11/18 04:49:34 DEBUG : removing 1 level 1 directories 2025/11/18 04:49:34 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1254: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:44686") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:50190->172.17.0.5:44686, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:42535") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:60838->172.17.0.5:42535, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:50416") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40242->172.17.0.5:50416, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:18899") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:44530->172.17.0.5:18899, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:12315") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:46542->172.17.0.5:12315, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:14511") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:33118->172.17.0.5:14511, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: Connecting to FTP server 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53648->172.17.0.5:21, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:17090") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:33150->172.17.0.5:17090, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:39337") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:34276->172.17.0.5:39337, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:56774") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:45190->172.17.0.5:56774, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:37722") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:52416->172.17.0.5:37722, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:47362") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43706->172.17.0.5:47362, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:4736") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:57748->172.17.0.5:4736, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:34455") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:60174->172.17.0.5:34455, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:18121") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:32906->172.17.0.5:18121, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:45496") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:33848->172.17.0.5:45496, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:65405") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:45826->172.17.0.5:65405, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:37572") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:44112->172.17.0.5:37572, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:37608") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:55960->172.17.0.5:37608, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:28242") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:59976->172.17.0.5:28242, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:23535") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:42270->172.17.0.5:23535, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:30146") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40288->172.17.0.5:30146, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:61365") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53572->172.17.0.5:61365, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:42611") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:46996->172.17.0.5:42611, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/11/18 04:49:34 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-joqoqiz3yaze" 2025/11/18 04:49:34 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/11/18 04:49:34 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:34 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:34 DEBUG : TestFTPProftpd: detected overridden config - adding "{57oW9}" suffix to name 2025/11/18 04:49:34 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:34 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:34 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:34 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: Connecting to FTP server 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:53662->172.17.0.5:21, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:5179") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:50526->172.17.0.5:5179, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:20063") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:43464->172.17.0.5:20063, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:32859") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:50308->172.17.0.5:32859, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:24139") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:42040->172.17.0.5:24139, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:48773") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:37906->172.17.0.5:48773, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:20752") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:43692->172.17.0.5:20752, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:44408") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:44978->172.17.0.5:44408, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:32351") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:55874->172.17.0.5:32351, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:63675") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36960->172.17.0.5:63675, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:13012") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:57902->172.17.0.5:13012, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:2975") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:40264->172.17.0.5:2975, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:21563") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:47358->172.17.0.5:21563, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:45803") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:42662->172.17.0.5:45803, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:63431") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:52512->172.17.0.5:63431, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:23624") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:49728->172.17.0.5:23624, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:23768") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:52392->172.17.0.5:23768, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: dial("tcp","172.17.0.5:47865") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: > dial: conn=172.17.0.1:42628->172.17.0.5:47865, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: Purge dir "new_name" 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-joqoqiz3yaze: 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/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:23101") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:46224->172.17.0.5:23101, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:23191") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:51956->172.17.0.5:23191, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:49343") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:34820->172.17.0.5:49343, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:41148") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:39082->172.17.0.5:41148, err= 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:11518") 2025/11/18 04:49:34 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43902->172.17.0.5:11518, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:41292") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:57794->172.17.0.5:41292, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:25708") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:59012->172.17.0.5:25708, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:15863") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:38890->172.17.0.5:15863, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:14250") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:54724->172.17.0.5:14250, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/11/18 04:49:35 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-zuqocug5nado/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/11/18 04:49:35 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : TestFTPProftpd: detected overridden config - adding "{57oW9}" suffix to name 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:53676->172.17.0.5:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.5:7307") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:35848->172.17.0.5:7307, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/11/18 04:49:35 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-zuqocug5nado/not found.txt" 2025/11/18 04:49:35 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : TestFTPProftpd: detected overridden config - adding "{57oW9}" suffix to name 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/not found.txt: Connecting to FTP server 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/not found.txt: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/not found.txt: > dial: conn=172.17.0.1:53690->172.17.0.5:21, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/not found.txt: dial("tcp","172.17.0.5:14666") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/not found.txt: > dial: conn=172.17.0.1:34072->172.17.0.5:14666, err= 2025/11/18 04:49:35 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestFTPProftpd:" path "rclone-test-zuqocug5nado" from "TestFTPProftpd:rclone-test-zuqocug5nado" 2025/11/18 04:49:35 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/11/18 04:49:35 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : TestFTPProftpd: detected overridden config - adding "{57oW9}" suffix to name 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: Connecting to FTP server 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:53694->172.17.0.5:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:33766") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:42414->172.17.0.5:33766, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:62717") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:32906->172.17.0.5:62717, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:55336") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:43008->172.17.0.5:55336, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:48766") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:49114->172.17.0.5:48766, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:8944") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:58376->172.17.0.5:8944, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:31866") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:53932->172.17.0.5:31866, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1945: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:60243") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:58504->172.17.0.5:60243, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:21990") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:57432->172.17.0.5:21990, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:9229") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:45588->172.17.0.5:9229, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:39754") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:51544->172.17.0.5:39754, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:37020") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:60730->172.17.0.5:37020, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:51081") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:52482->172.17.0.5:51081, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:44602") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:39460->172.17.0.5:44602, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:56147") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:42646->172.17.0.5:56147, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:65037") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:60922->172.17.0.5:65037, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:25599") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:54254->172.17.0.5:25599, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: dial("tcp","172.17.0.5:19478") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21: > dial: conn=172.17.0.1:54980->172.17.0.5:19478, 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/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:9037") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:49686->172.17.0.5:9037, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:1852") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:54914->172.17.0.5:1852, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:16793") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53466->172.17.0.5:16793, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:23791") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:39152->172.17.0.5:23791, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:12728") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36054->172.17.0.5:12728, 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/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:11809") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:42008->172.17.0.5:11809, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:10621") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:44260->172.17.0.5:10621, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2025/11/18 04:49:35 DEBUG : Creating backend with remote "TestFTPProftpd,concurrency='0',shut_timeout='1s':rclone-test-zuqocug5nado" 2025/11/18 04:49:35 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : TestFTPProftpd: detected overridden config - adding "{m1c1C}" suffix to name 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: Connecting to FTP server 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53700->172.17.0.5:21, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:41446") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36060->172.17.0.5:41446, 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/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:50469") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53340->172.17.0.5:50469, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:25931") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:56772->172.17.0.5:25931, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:6187") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:47966->172.17.0.5:6187, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/11/18 04:49:35 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-zuqocug5nado/deeper/nonexisting/directory" 2025/11/18 04:49:35 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : TestFTPProftpd: detected overridden config - adding "{57oW9}" suffix to name 2025/11/18 04:49:35 DEBUG : Setting host="172.17.0.5" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/11/18 04:49:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/11/18 04:49:35 DEBUG : Setting pass="40n7ep6HerXrQiOQIYb67S7QWOb3OlqhIPxPK2ug1UIXsQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/11/18 04:49:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/deeper/nonexisting/directory: Connecting to FTP server 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/deeper/nonexisting/directory: dial("tcp","172.17.0.5:21") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado/deeper/nonexisting/directory: > dial: conn=172.17.0.1:53708->172.17.0.5:21, err= === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:2625: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:2668: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory fstests.go:2725: FS has no Directory methods and doesn't Wrap 2025/11/18 04:49:35 DEBUG : Waiting for deletions to finish 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:10051") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:34186->172.17.0.5:10051, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:9747") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:50378->172.17.0.5:9747, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:37516") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:33470->172.17.0.5:37516, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:19584") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:34006->172.17.0.5:19584, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:11794") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:41640->172.17.0.5:11794, err= 2025/11/18 04:49:35 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:42070") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:41888->172.17.0.5:42070, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:58161") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:47636->172.17.0.5:58161, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:54716") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:36534->172.17.0.5:54716, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:26885") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:59588->172.17.0.5:26885, err= 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:63247") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43372->172.17.0.5:63247, err= 2025/11/18 04:49:35 DEBUG : removing 1 level 4 directories 2025/11/18 04:49:35 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2025/11/18 04:49:35 DEBUG : removing 1 level 3 directories 2025/11/18 04:49:35 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2025/11/18 04:49:35 DEBUG : removing 1 level 2 directories 2025/11/18 04:49:35 INFO : hello? sausage/êé: Removing directory 2025/11/18 04:49:35 DEBUG : removing 1 level 1 directories 2025/11/18 04:49:35 INFO : hello? sausage: Removing directory 2025/11/18 04:49:35 DEBUG : removing 1 level 0 directories 2025/11/18 04:49:35 INFO : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: Removing directory 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:19070") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:43298->172.17.0.5:19070, err= 2025/11/18 04:49:35 ERROR : error listing: directory not found 2025/11/18 04:49:35 DEBUG : Waiting for deletions to finish 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:14333") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:53394->172.17.0.5:14333, err= 2025/11/18 04:49:35 ERROR : error listing: directory not found 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: dial("tcp","172.17.0.5:39597") 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: > dial: conn=172.17.0.1:37074->172.17.0.5:39597, err= 2025/11/18 04:49:35 ERROR : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: Failed to list "": directory not found 2025/11/18 04:49:35 DEBUG : removing 1 level 0 directories 2025/11/18 04:49:35 INFO : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: Removing directory 2025/11/18 04:49:35 ERROR : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2025/11/18 04:49:35 DEBUG : ftp://172.17.0.5:21/rclone-test-zuqocug5nado: closing 2 unused connections 2025/11/18 04:49:35 NOTICE: TestFTPProftpd: Stopping server --- PASS: TestIntegration (4.04s) --- 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.74s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.03s) --- 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.27s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (0.01s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutError (1.03s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (0.01s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenChunkWriter (0.00s) --- SKIP: TestIntegration/FsMkdir/FsChangeNotify (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (1.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.02s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.02s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (0.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.02s) --- 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.09s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.10s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.09s) --- 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.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (0.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (0.76s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (0.76s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.01s) --- SKIP: TestIntegration/FsMkdir/FsDirSetModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsMkdirMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsDirectory (0.00s) --- PASS: TestIntegration/FsShutdown (0.00s) === RUN TestIntegrationProftpd ftp_test.go:23: skipping as -remote is set --- SKIP: TestIntegrationProftpd (0.00s) === RUN TestIntegrationPureftpd ftp_test.go:34: skipping as -remote is set --- SKIP: TestIntegrationPureftpd (0.00s) === RUN TestIntegrationVsftpd ftp_test.go:45: skipping as -remote is set --- SKIP: TestIntegrationVsftpd (0.00s) PASS ok github.com/rclone/rclone/backend/ftp 4.069s "go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Finished OK in 4.899585564s (try 1/5)