"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestFTPProftpd:" 2025/12/05 02:30:00 NOTICE: TestFTPProftpd: Starting server 2025/12/05 02:30:01 NOTICE: TestFTPProftpd: Attempting to connect to "172.17.0.9:21" try 1/100 2025/12/05 02:30:01 DEBUG : TestFTPProftpd: Read 1, error: 2025/12/05 02:30:01 NOTICE: TestFTPProftpd: Started server and connected to "172.17.0.9:21" 2025/12/05 02:30:01 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-budiyuh5jaqu" 2025/12/05 02:30:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 02:30:01 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/12/05 02:30:01 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:01 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:01 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:01 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:01 DEBUG : TestFTPProftpd: detected overridden config - adding "{Ws_Vy}" suffix to name 2025/12/05 02:30:01 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:01 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:01 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:01 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: Connecting to FTP server 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57620->172.17.0.9:21, err= 2025/12/05 02:30:01 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/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:29373") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:46584->172.17.0.9:29373, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:11057") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33142->172.17.0.9:11057, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/12/05 02:30:01 INFO : dir/subdir: Making directory 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:7087") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:41008->172.17.0.9:7087, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:4012") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:58076->172.17.0.9:4012, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:16655") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:46736->172.17.0.9:16655, err= 2025/12/05 02:30:01 INFO : dir/subdir: Removing directory 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:54834") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:44766->172.17.0.9:54834, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:26578") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:58134->172.17.0.9:26578, err= 2025/12/05 02:30:01 INFO : dir: Removing directory 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:44218") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:46358->172.17.0.9:44218, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:15952") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:50852->172.17.0.9:15952, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:46050") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:51400->172.17.0.9:46050, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:43296") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:37138->172.17.0.9:43296, err= 2025/12/05 02:30:01 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/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:34266") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:46262->172.17.0.9:34266, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:38718") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:36032->172.17.0.9:38718, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:3651") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57204->172.17.0.9:3651, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:33107") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:46380->172.17.0.9:33107, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:5563") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:39416->172.17.0.9:5563, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:30390") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:36208->172.17.0.9:30390, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:3294") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:39376->172.17.0.9:3294, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:6133") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:34178->172.17.0.9:6133, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:29204") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:38304->172.17.0.9:29204, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:28514") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:55538->172.17.0.9:28514, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:35903") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33722->172.17.0.9:35903, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:56388") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:50584->172.17.0.9:56388, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:27951") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:48656->172.17.0.9:27951, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:44560") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:50078->172.17.0.9:44560, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:35274") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33504->172.17.0.9:35274, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:39458") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:51082->172.17.0.9:39458, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:38099") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:35236->172.17.0.9:38099, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:63315") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:50920->172.17.0.9:63315, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:11904") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:43212->172.17.0.9:11904, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:12862") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33420->172.17.0.9:12862, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:27154") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56938->172.17.0.9:27154, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:21938") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57362->172.17.0.9:21938, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:18925") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:52978->172.17.0.9:18925, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:30142") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:46840->172.17.0.9:30142, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:37569") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:45764->172.17.0.9:37569, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:8225") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:53362->172.17.0.9:8225, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:55696") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:50246->172.17.0.9:55696, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:16253") 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:37168->172.17.0.9:16253, err= 2025/12/05 02:30:01 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:23153") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33170->172.17.0.9:23153, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:36212") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33296->172.17.0.9:36212, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:58525") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:49506->172.17.0.9:58525, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:56396") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56224->172.17.0.9:56396, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:9395") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:38192->172.17.0.9:9395, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:61152") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:44152->172.17.0.9:61152, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:23968") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:48042->172.17.0.9:23968, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:13935") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:37036->172.17.0.9:13935, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:26007") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:39678->172.17.0.9:26007, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:26238") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:49182->172.17.0.9:26238, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:19044") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:51270->172.17.0.9:19044, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:54188") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:38370->172.17.0.9:54188, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:53728") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:59614->172.17.0.9:53728, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:53924") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:52150->172.17.0.9:53924, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:45041") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:52652->172.17.0.9:45041, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:16145") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:58264->172.17.0.9:16145, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:32950") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:45748->172.17.0.9:32950, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:14781") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:34214->172.17.0.9:14781, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:54579") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:58910->172.17.0.9:54579, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:5514") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:42386->172.17.0.9:5514, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:12561") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:54016->172.17.0.9:12561, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:65459") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56596->172.17.0.9:65459, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:17353") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:60344->172.17.0.9:17353, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:38691") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:44694->172.17.0.9:38691, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:21863") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:59092->172.17.0.9:21863, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:35255") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:53812->172.17.0.9:35255, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:3298") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:34220->172.17.0.9:3298, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:58408") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:43208->172.17.0.9:58408, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:42456") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:47734->172.17.0.9:42456, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:57971") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56652->172.17.0.9:57971, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:9126") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:43486->172.17.0.9:9126, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:64586") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:44812->172.17.0.9:64586, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:28648") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:55488->172.17.0.9:28648, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:2117") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:59864->172.17.0.9:2117, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:55447") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:37854->172.17.0.9:55447, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:37019") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56958->172.17.0.9:37019, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:62245") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:60906->172.17.0.9:62245, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:13881") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:39852->172.17.0.9:13881, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:49930") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33910->172.17.0.9:49930, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:22718") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:49076->172.17.0.9:22718, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:39096") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:46950->172.17.0.9:39096, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:3440") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33748->172.17.0.9:3440, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:11372") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33234->172.17.0.9:11372, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:27289") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:40398->172.17.0.9:27289, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:56340") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:41364->172.17.0.9:56340, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:55390") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:54964->172.17.0.9:55390, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:42410") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:43568->172.17.0.9:42410, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:23756") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:48462->172.17.0.9:23756, err= 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:4636") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33398->172.17.0.9:4636, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:31454") 2025/12/05 02:30:02 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:36778->172.17.0.9:31454, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57624->172.17.0.9:21, err= 2025/12/05 02:30:03 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred: * potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:47234") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:59572->172.17.0.9:47234, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:11653") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:54442->172.17.0.9:11653, 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/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:41363") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:53514->172.17.0.9:41363, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:29994") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:49362->172.17.0.9:29994, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:50198") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:60082->172.17.0.9:50198, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:58802") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:42652->172.17.0.9:58802, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:44788") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:54228->172.17.0.9:44788, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:3326") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33298->172.17.0.9:3326, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:57463") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:45352->172.17.0.9:57463, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:54014") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:42692->172.17.0.9:54014, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:18336") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:53288->172.17.0.9:18336, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:14628") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:59204->172.17.0.9:14628, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:3402") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:55916->172.17.0.9:3402, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:25508") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56336->172.17.0.9:25508, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:37630") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:60584->172.17.0.9:37630, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:11965") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33062->172.17.0.9:11965, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:35767") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:51402->172.17.0.9:35767, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:63626") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56530->172.17.0.9:63626, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:51442") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:40504->172.17.0.9:51442, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/12/05 02:30:03 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/12/05 02:30:03 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{Ws_Vy}" suffix to name 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:57640->172.17.0.9:21, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:39896") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:59668->172.17.0.9:39896, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:25372") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:53146->172.17.0.9:25372, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:63165") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57852->172.17.0.9:63165, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:41266") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:54174->172.17.0.9:41266, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:58371") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:41000->172.17.0.9:58371, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:7500") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:36518->172.17.0.9:7500, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:19295") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:55108->172.17.0.9:19295, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:41854") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57312->172.17.0.9:41854, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:49831") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:55096->172.17.0.9:49831, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:22573") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:35616->172.17.0.9:22573, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1121: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:6361") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:53048->172.17.0.9:6361, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:32369") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:58928->172.17.0.9:32369, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:2493") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:43268->172.17.0.9:2493, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:52572") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:42698->172.17.0.9:52572, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:42999") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56762->172.17.0.9:42999, 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/12/05 02:30:03 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-budiyuh5jaqu/hello? sausage/êé" 2025/12/05 02:30:03 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{Ws_Vy}" suffix to name 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:57648->172.17.0.9:21, err= 2025/12/05 02:30:03 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-budiyuh5jaqu/hello? sausage/êé" to be canonical "TestFTPProftpd{Ws_Vy}:rclone-test-budiyuh5jaqu/hello? sausage/êé" 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:51750") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:59256->172.17.0.9:51750, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:1937") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:42420->172.17.0.9:1937, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:57652->172.17.0.9:21, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:24579") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:41610->172.17.0.9:24579, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:29568") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:48420->172.17.0.9:29568, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:38121") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:50934->172.17.0.9:38121, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:32784") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:55782->172.17.0.9:32784, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:28997") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:48550->172.17.0.9:28997, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:42522") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:47822->172.17.0.9:42522, err= 2025/12/05 02:30:03 DEBUG : Waiting for deletions to finish 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:10986") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:45170->172.17.0.9:10986, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:52275") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:58560->172.17.0.9:52275, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:49763") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:41276->172.17.0.9:49763, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:11665") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:59234->172.17.0.9:11665, err= 2025/12/05 02:30:03 INFO : a/b/c/d/e.txt: Deleted 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:23502") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:52068->172.17.0.9:23502, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:56777") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:35436->172.17.0.9:56777, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:45663") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:48046->172.17.0.9:45663, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: dial("tcp","172.17.0.9:51984") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé: > dial: conn=172.17.0.1:33014->172.17.0.9:51984, err= 2025/12/05 02:30:03 DEBUG : removing 1 level 4 directories 2025/12/05 02:30:03 INFO : a/b/c/d: Removing directory 2025/12/05 02:30:03 DEBUG : removing 1 level 3 directories 2025/12/05 02:30:03 INFO : a/b/c: Removing directory 2025/12/05 02:30:03 DEBUG : removing 1 level 2 directories 2025/12/05 02:30:03 INFO : a/b: Removing directory 2025/12/05 02:30:03 DEBUG : removing 1 level 1 directories 2025/12/05 02:30:03 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1254: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:42832") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:55978->172.17.0.9:42832, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:17031") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:49224->172.17.0.9:17031, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:27662") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:38204->172.17.0.9:27662, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:36100") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:59424->172.17.0.9:36100, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:60795") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:58258->172.17.0.9:60795, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:29965") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:54732->172.17.0.9:29965, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57662->172.17.0.9:21, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:28028") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:49642->172.17.0.9:28028, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:59034") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:37312->172.17.0.9:59034, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:49275") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:41556->172.17.0.9:49275, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:58621") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:52368->172.17.0.9:58621, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:47277") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:37674->172.17.0.9:47277, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:51109") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:35730->172.17.0.9:51109, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:41633") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:59538->172.17.0.9:41633, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:40564") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:46746->172.17.0.9:40564, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:51653") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:51982->172.17.0.9:51653, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:16636") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:50922->172.17.0.9:16636, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:7251") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:60748->172.17.0.9:7251, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:52647") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:53040->172.17.0.9:52647, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:16497") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:60234->172.17.0.9:16497, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:13728") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:37128->172.17.0.9:13728, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:46107") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:38026->172.17.0.9:46107, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:62595") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:54066->172.17.0.9:62595, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:33338") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:60310->172.17.0.9:33338, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/12/05 02:30:03 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-qodugar6xido" 2025/12/05 02:30:03 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{Ws_Vy}" suffix to name 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:57676->172.17.0.9:21, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:41994") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:38294->172.17.0.9:41994, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:56956") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:48462->172.17.0.9:56956, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:41860") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:44692->172.17.0.9:41860, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:52268") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:39246->172.17.0.9:52268, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:57766") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:44878->172.17.0.9:57766, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:20028") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:49684->172.17.0.9:20028, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:53027") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:41394->172.17.0.9:53027, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:38401") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:54570->172.17.0.9:38401, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:29907") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:60576->172.17.0.9:29907, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:11819") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:45512->172.17.0.9:11819, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:55899") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:33232->172.17.0.9:55899, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:48503") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:41486->172.17.0.9:48503, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:46690") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:37752->172.17.0.9:46690, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:30372") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:54244->172.17.0.9:30372, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:16703") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:60602->172.17.0.9:16703, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:48997") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:45892->172.17.0.9:48997, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: dial("tcp","172.17.0.9:3692") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: > dial: conn=172.17.0.1:42024->172.17.0.9:3692, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: Purge dir "new_name" 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-qodugar6xido: 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/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:1831") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:37016->172.17.0.9:1831, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:9449") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:40234->172.17.0.9:9449, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:24235") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:45278->172.17.0.9:24235, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:36480") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:44828->172.17.0.9:36480, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:59179") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:59266->172.17.0.9:59179, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:4363") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56630->172.17.0.9:4363, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:18188") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:45900->172.17.0.9:18188, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:34685") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:41604->172.17.0.9:34685, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:17141") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:55866->172.17.0.9:17141, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/12/05 02:30:03 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-budiyuh5jaqu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/12/05 02:30:03 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{Ws_Vy}" suffix to name 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:57690->172.17.0.9:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.9:1892") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:46908->172.17.0.9:1892, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/12/05 02:30:03 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-budiyuh5jaqu/not found.txt" 2025/12/05 02:30:03 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{Ws_Vy}" suffix to name 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/not found.txt: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/not found.txt: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/not found.txt: > dial: conn=172.17.0.1:57706->172.17.0.9:21, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/not found.txt: dial("tcp","172.17.0.9:32127") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/not found.txt: > dial: conn=172.17.0.1:45374->172.17.0.9:32127, err= 2025/12/05 02:30:03 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestFTPProftpd:" path "rclone-test-budiyuh5jaqu" from "TestFTPProftpd:rclone-test-budiyuh5jaqu" 2025/12/05 02:30:03 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/12/05 02:30:03 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{Ws_Vy}" suffix to name 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:57718->172.17.0.9:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:10021") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:38890->172.17.0.9:10021, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:9596") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:56824->172.17.0.9:9596, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:11382") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:37208->172.17.0.9:11382, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:39915") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:60234->172.17.0.9:39915, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:63119") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:46984->172.17.0.9:63119, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:38995") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:41774->172.17.0.9:38995, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1945: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:60449") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:44632->172.17.0.9:60449, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:61837") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:57594->172.17.0.9:61837, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:34767") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:47174->172.17.0.9:34767, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:44859") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:35482->172.17.0.9:44859, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:41370") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:54930->172.17.0.9:41370, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:32671") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:33960->172.17.0.9:32671, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:57665") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:44984->172.17.0.9:57665, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:15063") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:53284->172.17.0.9:15063, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:3359") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:40268->172.17.0.9:3359, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:56610") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:36284->172.17.0.9:56610, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: dial("tcp","172.17.0.9:1183") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21: > dial: conn=172.17.0.1:38982->172.17.0.9:1183, 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/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:15925") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:38600->172.17.0.9:15925, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:6701") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:45346->172.17.0.9:6701, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:42507") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56212->172.17.0.9:42507, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:37783") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:52226->172.17.0.9:37783, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:35499") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57818->172.17.0.9:35499, 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/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:64732") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57256->172.17.0.9:64732, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:2768") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57312->172.17.0.9:2768, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2025/12/05 02:30:03 DEBUG : Creating backend with remote "TestFTPProftpd,concurrency='0',shut_timeout='1s':rclone-test-budiyuh5jaqu" 2025/12/05 02:30:03 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : TestFTPProftpd: detected overridden config - adding "{GLtUJ}" suffix to name 2025/12/05 02:30:03 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:03 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:03 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:03 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: Connecting to FTP server 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57726->172.17.0.9:21, err= 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:7238") 2025/12/05 02:30:03 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57496->172.17.0.9:7238, 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/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:64056") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57912->172.17.0.9:64056, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:40836") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:47686->172.17.0.9:40836, err= 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:27612") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:48342->172.17.0.9:27612, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/12/05 02:30:04 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-budiyuh5jaqu/deeper/nonexisting/directory" 2025/12/05 02:30:04 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/12/05 02:30:04 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:04 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:04 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:04 DEBUG : TestFTPProftpd: detected overridden config - adding "{Ws_Vy}" suffix to name 2025/12/05 02:30:04 DEBUG : Setting host="172.17.0.9" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/12/05 02:30:04 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/12/05 02:30:04 DEBUG : Setting pass="QGJZG7lzvZWU0GbJLxjLz6w__vf9XI2U3fLdiECyemuL4g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/12/05 02:30:04 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/deeper/nonexisting/directory: Connecting to FTP server 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/deeper/nonexisting/directory: dial("tcp","172.17.0.9:21") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu/deeper/nonexisting/directory: > dial: conn=172.17.0.1:42370->172.17.0.9: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/12/05 02:30:04 DEBUG : Waiting for deletions to finish 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:31567") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:50476->172.17.0.9:31567, err= 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:47490") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:36934->172.17.0.9:47490, err= 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:65526") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:50118->172.17.0.9:65526, err= 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:29039") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:53216->172.17.0.9:29039, err= 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:27873") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:51958->172.17.0.9:27873, err= 2025/12/05 02:30:04 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:41220") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:40202->172.17.0.9:41220, err= 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:9032") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56996->172.17.0.9:9032, err= 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:37006") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:49054->172.17.0.9:37006, err= 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:42824") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:53918->172.17.0.9:42824, err= 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:40263") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:43140->172.17.0.9:40263, err= 2025/12/05 02:30:04 DEBUG : removing 1 level 4 directories 2025/12/05 02:30:04 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2025/12/05 02:30:04 DEBUG : removing 1 level 3 directories 2025/12/05 02:30:04 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2025/12/05 02:30:04 DEBUG : removing 1 level 2 directories 2025/12/05 02:30:04 INFO : hello? sausage/êé: Removing directory 2025/12/05 02:30:04 DEBUG : removing 1 level 1 directories 2025/12/05 02:30:04 INFO : hello? sausage: Removing directory 2025/12/05 02:30:04 DEBUG : removing 1 level 0 directories 2025/12/05 02:30:04 INFO : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: Removing directory 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:36831") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:57922->172.17.0.9:36831, err= 2025/12/05 02:30:04 ERROR : error listing: directory not found 2025/12/05 02:30:04 DEBUG : Waiting for deletions to finish 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:43880") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:56758->172.17.0.9:43880, err= 2025/12/05 02:30:04 ERROR : error listing: directory not found 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: dial("tcp","172.17.0.9:16362") 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: > dial: conn=172.17.0.1:42602->172.17.0.9:16362, err= 2025/12/05 02:30:04 ERROR : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: Failed to list "": directory not found 2025/12/05 02:30:04 DEBUG : removing 1 level 0 directories 2025/12/05 02:30:04 INFO : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: Removing directory 2025/12/05 02:30:04 ERROR : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2025/12/05 02:30:04 DEBUG : ftp://172.17.0.9:21/rclone-test-budiyuh5jaqu: closing 2 unused connections 2025/12/05 02:30:04 NOTICE: TestFTPProftpd: Stopping server --- PASS: TestIntegration (3.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.60s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (0.02s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.00s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.00s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (0.20s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.01s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (0.01s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutError (1.01s) --- 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.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.01s) --- 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.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPurge (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (0.05s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.06s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.01s) --- 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.90s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (0.90s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (0.01s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (0.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 3.893s "go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Finished OK in 4.575133382s (try 1/5)