"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestFTPProftpd:" 2025/10/08 01:00:24 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:25 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 2/10 2025/10/08 01:00:25 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:26 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 3/10 2025/10/08 01:00:26 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:27 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 4/10 2025/10/08 01:00:27 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:28 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 5/10 2025/10/08 01:00:28 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:29 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 6/10 2025/10/08 01:00:29 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:30 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 7/10 2025/10/08 01:00:31 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:32 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 8/10 2025/10/08 01:00:32 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:33 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 9/10 2025/10/08 01:00:33 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:34 NOTICE: TestFTPProftpd: Attempting to stop TestFTPProftpd try 10/10 2025/10/08 01:00:34 NOTICE: TestFTPProftpd: Stopping server 2025/10/08 01:00:34 NOTICE: TestFTPProftpd: Starting server 2025/10/08 01:00:35 NOTICE: TestFTPProftpd: Attempting to connect to "172.17.0.2:21" try 1/100 2025/10/08 01:00:35 DEBUG : TestFTPProftpd: Read 1, error: 2025/10/08 01:00:35 NOTICE: TestFTPProftpd: Started server and connected to "172.17.0.2:21" 2025/10/08 01:00:35 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-pizurar7caqo" 2025/10/08 01:00:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/10/08 01:00:35 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/08 01:00:35 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:35 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:35 DEBUG : TestFTPProftpd: detected overridden config - adding "{wFkLi}" suffix to name 2025/10/08 01:00:35 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:35 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:35 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:35 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:35 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: Connecting to FTP server 2025/10/08 01:00:35 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:35 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51092->172.17.0.2:21, err= 2025/10/08 01:00:36 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE === RUN TestIntegration/FsCheckWrap fstests.go:479: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:507: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:12514") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:55226->172.17.0.2:12514, err= === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:42620") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:59900->172.17.0.2:42620, err= === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/10/08 01:00:36 INFO : dir/subdir: Making directory 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:29430") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:42462->172.17.0.2:29430, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:52338") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:33536->172.17.0.2:52338, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:34985") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:33842->172.17.0.2:34985, err= 2025/10/08 01:00:36 INFO : dir/subdir: Removing directory 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:32740") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:45894->172.17.0.2:32740, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:23490") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:52330->172.17.0.2:23490, err= 2025/10/08 01:00:36 INFO : dir: Removing directory 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:6842") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:40356->172.17.0.2:6842, err= === RUN TestIntegration/FsMkdir/FsListEmpty 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:33594") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:41664->172.17.0.2:33594, err= === RUN TestIntegration/FsMkdir/FsListDirEmpty 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:57863") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:48370->172.17.0.2:57863, err= === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:30420") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:40286->172.17.0.2:30420, err= 2025/10/08 01:00:36 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:39959") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:40264->172.17.0.2:39959, err= === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:2452") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:42492->172.17.0.2:2452, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:23603") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:39696->172.17.0.2:23603, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:55875") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:41990->172.17.0.2:55875, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:7870") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:34898->172.17.0.2:7870, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:8664") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:39502->172.17.0.2:8664, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:19320") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51600->172.17.0.2:19320, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:34629") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51110->172.17.0.2:34629, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:53328") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:35096->172.17.0.2:53328, err= === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:11981") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:43114->172.17.0.2:11981, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:35784") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:46348->172.17.0.2:35784, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:26464") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:59724->172.17.0.2:26464, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:50535") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:55052->172.17.0.2:50535, err= === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:46456") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:45298->172.17.0.2:46456, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:51668") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:36260->172.17.0.2:51668, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:37393") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:39152->172.17.0.2:37393, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:61862") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:43916->172.17.0.2:61862, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:13561") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51194->172.17.0.2:13561, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:10101") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:60790->172.17.0.2:10101, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:10829") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:37788->172.17.0.2:10829, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:25052") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:56122->172.17.0.2:25052, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:51698") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:33968->172.17.0.2:51698, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:39235") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:47574->172.17.0.2:39235, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:11856") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:60664->172.17.0.2:11856, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:21148") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:41916->172.17.0.2:21148, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:6440") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:52092->172.17.0.2:6440, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:34322") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58124->172.17.0.2:34322, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:26966") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:57670->172.17.0.2:26966, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:39010") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:39604->172.17.0.2:39010, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:26651") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:57592->172.17.0.2:26651, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:56362") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:52288->172.17.0.2:56362, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:13435") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:53334->172.17.0.2:13435, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:28080") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:38814->172.17.0.2:28080, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:14431") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:41936->172.17.0.2:14431, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:3775") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:35074->172.17.0.2:3775, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:34926") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:44156->172.17.0.2:34926, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:22071") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:48056->172.17.0.2:22071, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:22072") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:57380->172.17.0.2:22072, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:4021") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:40200->172.17.0.2:4021, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:9864") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:36920->172.17.0.2:9864, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:33030") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:48566->172.17.0.2:33030, err= === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:38781") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:44010->172.17.0.2:38781, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:35305") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:55706->172.17.0.2:35305, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:18030") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:44418->172.17.0.2:18030, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:19703") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:47502->172.17.0.2:19703, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:21439") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:34644->172.17.0.2:21439, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:54400") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:55674->172.17.0.2:54400, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:16030") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:34260->172.17.0.2:16030, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:33977") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:54918->172.17.0.2:33977, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:63477") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:55124->172.17.0.2:63477, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:25836") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:39506->172.17.0.2:25836, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:58005") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:47522->172.17.0.2:58005, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:49641") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58588->172.17.0.2:49641, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:64047") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:33896->172.17.0.2:64047, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:4327") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:50146->172.17.0.2:4327, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:5254") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58138->172.17.0.2:5254, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:4953") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:53544->172.17.0.2:4953, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:37626") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:44506->172.17.0.2:37626, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:31197") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:50584->172.17.0.2:31197, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:42940") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:59600->172.17.0.2:42940, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:63253") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:55602->172.17.0.2:63253, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:22025") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:49512->172.17.0.2:22025, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:55351") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51626->172.17.0.2:55351, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:25798") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:41518->172.17.0.2:25798, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:35432") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:37216->172.17.0.2:35432, err= === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:58103") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:42252->172.17.0.2:58103, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:59701") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:46800->172.17.0.2:59701, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:56480") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51378->172.17.0.2:56480, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:14641") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:48608->172.17.0.2:14641, err= === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:62698") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:33060->172.17.0.2:62698, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:65320") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:40808->172.17.0.2:65320, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:46647") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:48438->172.17.0.2:46647, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:35944") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:34348->172.17.0.2:35944, err= === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:35090") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:53724->172.17.0.2:35090, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:63653") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:60800->172.17.0.2:63653, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:54623") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:54516->172.17.0.2:54623, err= 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:55505") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:41950->172.17.0.2:55505, err= === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:52519") 2025/10/08 01:00:36 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:39328->172.17.0.2:52519, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: Connecting to FTP server 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51104->172.17.0.2:21, err= 2025/10/08 01:00:37 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred: * potato === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:2600") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:60342->172.17.0.2:2600, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:6887") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:46492->172.17.0.2:6887, err= === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:786: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:819: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:868: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:46952") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:42334->172.17.0.2:46952, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:20837") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:34260->172.17.0.2:20837, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:63142") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:43366->172.17.0.2:63142, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:38650") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:47978->172.17.0.2:38650, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:61433") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:56290->172.17.0.2:61433, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:48205") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51550->172.17.0.2:48205, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:38269") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:40398->172.17.0.2:38269, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:49283") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:37958->172.17.0.2:49283, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:13953") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:53016->172.17.0.2:13953, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:2258") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:59152->172.17.0.2:2258, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:22730") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:56044->172.17.0.2:22730, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:37168") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:57870->172.17.0.2:37168, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:52421") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:38994->172.17.0.2:52421, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:12890") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:40220->172.17.0.2:12890, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:47825") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:53918->172.17.0.2:47825, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:37462") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:56220->172.17.0.2:37462, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:30262") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:34608->172.17.0.2:30262, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/10/08 01:00:37 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/10/08 01:00:37 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/08 01:00:37 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:37 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:37 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:37 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:37 DEBUG : TestFTPProftpd: detected overridden config - adding "{wFkLi}" suffix to name 2025/10/08 01:00:37 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:37 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:37 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:37 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21: Connecting to FTP server 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:51108->172.17.0.2:21, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:42621") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:53004->172.17.0.2:42621, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:25875") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:50216->172.17.0.2:25875, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:19763") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:45204->172.17.0.2:19763, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:60472") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:53032->172.17.0.2:60472, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:48323") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:59794->172.17.0.2:48323, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:25892") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:49588->172.17.0.2:25892, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:58017") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:35982->172.17.0.2:58017, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:4503") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:57620->172.17.0.2:4503, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:27052") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:55986->172.17.0.2:27052, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:50812") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:49430->172.17.0.2:50812, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1121: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:53831") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:33168->172.17.0.2:53831, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:28870") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:56988->172.17.0.2:28870, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:11309") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:55018->172.17.0.2:11309, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:55407") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:59704->172.17.0.2:55407, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:34734") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:37572->172.17.0.2:34734, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge fstests.go:1153: FS has no Purge interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot fstests.go:1190: FS has no Purge interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/10/08 01:00:37 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-pizurar7caqo/hello? sausage/êé" 2025/10/08 01:00:37 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/08 01:00:37 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:37 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:37 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:37 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:37 DEBUG : TestFTPProftpd: detected overridden config - adding "{wFkLi}" suffix to name 2025/10/08 01:00:37 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:37 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:37 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:37 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: Connecting to FTP server 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:51112->172.17.0.2:21, err= 2025/10/08 01:00:37 DEBUG : fs cache: renaming cache item "TestFTPProftpd:rclone-test-pizurar7caqo/hello? sausage/êé" to be canonical "TestFTPProftpd{wFkLi}:rclone-test-pizurar7caqo/hello? sausage/êé" 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:30764") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:56632->172.17.0.2:30764, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:32602") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:39198->172.17.0.2:32602, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: Connecting to FTP server 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:51124->172.17.0.2:21, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:63713") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:36274->172.17.0.2:63713, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:7840") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:51066->172.17.0.2:7840, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:51827") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:40480->172.17.0.2:51827, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:57007") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:37194->172.17.0.2:57007, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:21267") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:60792->172.17.0.2:21267, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:34228") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:33984->172.17.0.2:34228, err= 2025/10/08 01:00:37 DEBUG : Waiting for deletions to finish 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:28931") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:37098->172.17.0.2:28931, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:24899") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:58798->172.17.0.2:24899, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:57518") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:59064->172.17.0.2:57518, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:42231") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:39254->172.17.0.2:42231, err= 2025/10/08 01:00:37 INFO : a/b/c/d/e.txt: Deleted 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:40797") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:42682->172.17.0.2:40797, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:27203") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:36166->172.17.0.2:27203, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:56685") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:45792->172.17.0.2:56685, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: dial("tcp","172.17.0.2:25976") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé: > dial: conn=172.17.0.1:56204->172.17.0.2:25976, err= 2025/10/08 01:00:37 DEBUG : removing 1 level 4 directories 2025/10/08 01:00:37 INFO : a/b/c/d: Removing directory 2025/10/08 01:00:37 DEBUG : removing 1 level 3 directories 2025/10/08 01:00:37 INFO : a/b/c: Removing directory 2025/10/08 01:00:37 DEBUG : removing 1 level 2 directories 2025/10/08 01:00:37 INFO : a/b: Removing directory 2025/10/08 01:00:37 DEBUG : removing 1 level 1 directories 2025/10/08 01:00:37 INFO : a: Removing directory === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy fstests.go:1254: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:57238") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51612->172.17.0.2:57238, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:10710") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:34110->172.17.0.2:10710, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:32342") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:49878->172.17.0.2:32342, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:30354") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:38848->172.17.0.2:30354, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:6608") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:37764->172.17.0.2:6608, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:15012") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:39896->172.17.0.2:15012, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: Connecting to FTP server 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:3088") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51126->172.17.0.2:21, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:46358->172.17.0.2:3088, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:22432") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:50878->172.17.0.2:22432, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:54868") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58806->172.17.0.2:54868, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:50532") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:53060->172.17.0.2:50532, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:27942") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:52760->172.17.0.2:27942, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:9988") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58252->172.17.0.2:9988, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:4322") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:59516->172.17.0.2:4322, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:49372") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:37502->172.17.0.2:49372, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:12063") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:57910->172.17.0.2:12063, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:32771") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:33936->172.17.0.2:32771, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:64086") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:44466->172.17.0.2:64086, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:52772") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:54066->172.17.0.2:52772, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:55720") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:37002->172.17.0.2:55720, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:52604") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:57872->172.17.0.2:52604, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:23930") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:43838->172.17.0.2:23930, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:43803") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:54462->172.17.0.2:43803, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:46377") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:45202->172.17.0.2:46377, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/10/08 01:00:37 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-yibapuz6legu" 2025/10/08 01:00:37 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/08 01:00:37 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:37 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:37 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:37 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:37 DEBUG : TestFTPProftpd: detected overridden config - adding "{wFkLi}" suffix to name 2025/10/08 01:00:37 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:37 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:37 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:37 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: Connecting to FTP server 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:51128->172.17.0.2:21, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:35398") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58208->172.17.0.2:35398, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:44600") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:58570->172.17.0.2:44600, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:46250") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:36760->172.17.0.2:46250, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:3208") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:37748->172.17.0.2:3208, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:17120") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:53884->172.17.0.2:17120, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:4639") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:36362->172.17.0.2:4639, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:8742") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:41048->172.17.0.2:8742, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:15851") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:34206->172.17.0.2:15851, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:27647") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:45384->172.17.0.2:27647, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:40586") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58704->172.17.0.2:40586, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:53168") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:39234->172.17.0.2:53168, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:49709") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:49648->172.17.0.2:49709, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:6718") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:48046->172.17.0.2:6718, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:56151") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:34594->172.17.0.2:56151, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:4349") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:46600->172.17.0.2:4349, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:15068") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:44804->172.17.0.2:15068, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: dial("tcp","172.17.0.2:38979") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: > dial: conn=172.17.0.1:40676->172.17.0.2:38979, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: Purge dir "new_name" 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-yibapuz6legu: Purge dir "" === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1592: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1623: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1694: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:26397") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:54942->172.17.0.2:26397, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:63799") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:54700->172.17.0.2:63799, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:17222") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:36776->172.17.0.2:17222, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:46387") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:57214->172.17.0.2:46387, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:23290") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:40350->172.17.0.2:23290, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:48106") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51812->172.17.0.2:48106, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:54017") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:38306->172.17.0.2:54017, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:6744") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:56634->172.17.0.2:6744, err= 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:40588") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58696->172.17.0.2:40588, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/10/08 01:00:37 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-pizurar7caqo/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/10/08 01:00:37 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/08 01:00:37 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:37 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:37 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:37 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:37 DEBUG : TestFTPProftpd: detected overridden config - adding "{wFkLi}" suffix to name 2025/10/08 01:00:37 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:37 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:37 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:37 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Connecting to FTP server 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:37 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:51130->172.17.0.2:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: dial("tcp","172.17.0.2:34654") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: > dial: conn=172.17.0.1:59176->172.17.0.2:34654, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/10/08 01:00:38 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-pizurar7caqo/not found.txt" 2025/10/08 01:00:38 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/08 01:00:38 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:38 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:38 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:38 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:38 DEBUG : TestFTPProftpd: detected overridden config - adding "{wFkLi}" suffix to name 2025/10/08 01:00:38 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:38 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:38 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:38 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/not found.txt: Connecting to FTP server 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/not found.txt: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/not found.txt: > dial: conn=172.17.0.1:51136->172.17.0.2:21, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/not found.txt: dial("tcp","172.17.0.2:63172") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/not found.txt: > dial: conn=172.17.0.1:38790->172.17.0.2:63172, err= 2025/10/08 01:00:38 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestFTPProftpd:" path "rclone-test-pizurar7caqo" from "TestFTPProftpd:rclone-test-pizurar7caqo" 2025/10/08 01:00:38 DEBUG : Creating backend with remote "TestFTPProftpd:" 2025/10/08 01:00:38 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/08 01:00:38 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:38 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:38 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:38 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:38 DEBUG : TestFTPProftpd: detected overridden config - adding "{wFkLi}" suffix to name 2025/10/08 01:00:38 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:38 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:38 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:38 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: Connecting to FTP server 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:51144->172.17.0.2:21, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:47586") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:37366->172.17.0.2:47586, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:32398") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:35542->172.17.0.2:32398, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:39955") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:53790->172.17.0.2:39955, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:17131") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:45734->172.17.0.2:17131, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:56123") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:43480->172.17.0.2:56123, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:59281") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:39932->172.17.0.2:59281, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1945: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:22119") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:37234->172.17.0.2:22119, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:64696") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:45728->172.17.0.2:64696, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:6749") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:48904->172.17.0.2:6749, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:18680") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:40386->172.17.0.2:18680, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:23612") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:59580->172.17.0.2:23612, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:50541") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:58406->172.17.0.2:50541, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:50942") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:51952->172.17.0.2:50942, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:63354") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:55540->172.17.0.2:63354, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:26521") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:45106->172.17.0.2:26521, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:32845") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:48466->172.17.0.2:32845, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: dial("tcp","172.17.0.2:47957") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21: > dial: conn=172.17.0.1:52026->172.17.0.2:47957, err= === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2004: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:412: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2109: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2125: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:27723") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:57470->172.17.0.2:27723, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:57497") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58386->172.17.0.2:57497, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:22675") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:46072->172.17.0.2:22675, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:32773") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:33490->172.17.0.2:32773, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:11721") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:47564->172.17.0.2:11721, err= === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout fstests.go:2281: FS does not support About === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:20859") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:56328->172.17.0.2:20859, err= === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:25866") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:44890->172.17.0.2:25866, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout 2025/10/08 01:00:38 DEBUG : Creating backend with remote "TestFTPProftpd,concurrency='0',shut_timeout='1s':rclone-test-pizurar7caqo" 2025/10/08 01:00:38 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/08 01:00:38 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:38 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:38 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:38 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:38 DEBUG : TestFTPProftpd: detected overridden config - adding "{TgiNz}" suffix to name 2025/10/08 01:00:38 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:38 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:38 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:38 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: Connecting to FTP server 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51150->172.17.0.2:21, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:5752") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:60512->172.17.0.2:5752, err= === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TimePrecision === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2364: *ftp.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsCopyChunked fstests.go:2476: FS has no Copier interface === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:64927") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:37826->172.17.0.2:64927, err= === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:59610") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:45136->172.17.0.2:59610, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:45793") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:52690->172.17.0.2:45793, err= === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/10/08 01:00:38 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-pizurar7caqo/deeper/nonexisting/directory" 2025/10/08 01:00:38 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE 2025/10/08 01:00:38 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:38 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:38 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:38 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:38 DEBUG : TestFTPProftpd: detected overridden config - adding "{wFkLi}" suffix to name 2025/10/08 01:00:38 DEBUG : Setting host="172.17.0.2" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST 2025/10/08 01:00:38 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER 2025/10/08 01:00:38 DEBUG : Setting pass="e-AQu197GkGL6c-anvnIvWZwLnric4uqEGwoSxnIQ8ST6g" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS 2025/10/08 01:00:38 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/deeper/nonexisting/directory: Connecting to FTP server 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/deeper/nonexisting/directory: dial("tcp","172.17.0.2:21") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo/deeper/nonexisting/directory: > dial: conn=172.17.0.1:51154->172.17.0.2:21, err= === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:2625: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:2668: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory fstests.go:2725: FS has no Directory methods and doesn't Wrap 2025/10/08 01:00:38 DEBUG : Waiting for deletions to finish 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:62358") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:51536->172.17.0.2:62358, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:60620") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:47280->172.17.0.2:60620, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:51725") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:38100->172.17.0.2:51725, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:36152") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:58480->172.17.0.2:36152, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:11169") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:40032->172.17.0.2:11169, err= 2025/10/08 01:00:38 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Deleted 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:35666") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:34822->172.17.0.2:35666, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:15161") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:60194->172.17.0.2:15161, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:29819") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:43406->172.17.0.2:29819, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:45829") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:37192->172.17.0.2:45829, err= 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:27855") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:52654->172.17.0.2:27855, err= 2025/10/08 01:00:38 DEBUG : removing 1 level 4 directories 2025/10/08 01:00:38 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory 2025/10/08 01:00:38 DEBUG : removing 1 level 3 directories 2025/10/08 01:00:38 INFO : hello? sausage/êé/Hello, 世界: Removing directory 2025/10/08 01:00:38 DEBUG : removing 1 level 2 directories 2025/10/08 01:00:38 INFO : hello? sausage/êé: Removing directory 2025/10/08 01:00:38 DEBUG : removing 1 level 1 directories 2025/10/08 01:00:38 INFO : hello? sausage: Removing directory 2025/10/08 01:00:38 DEBUG : removing 1 level 0 directories 2025/10/08 01:00:38 INFO : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: Removing directory 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:45353") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:46920->172.17.0.2:45353, err= 2025/10/08 01:00:38 ERROR : error listing: directory not found 2025/10/08 01:00:38 DEBUG : Waiting for deletions to finish 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:65159") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:44422->172.17.0.2:65159, err= 2025/10/08 01:00:38 ERROR : error listing: directory not found 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: dial("tcp","172.17.0.2:61138") 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: > dial: conn=172.17.0.1:44946->172.17.0.2:61138, err= 2025/10/08 01:00:38 ERROR : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: Failed to list "": directory not found 2025/10/08 01:00:38 DEBUG : removing 1 level 0 directories 2025/10/08 01:00:38 INFO : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: Removing directory 2025/10/08 01:00:38 ERROR : Failed to rmdir: directory not found === RUN TestIntegration/FsShutdown 2025/10/08 01:00:38 DEBUG : ftp://172.17.0.2:21/rclone-test-pizurar7caqo: closing 2 unused connections 2025/10/08 01:00:39 NOTICE: TestFTPProftpd: Stopping server --- PASS: TestIntegration (15.14s) --- 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.76s) --- 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.31s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (0.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (0.02s) --- 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.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.02s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.01s) --- 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.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.08s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (0.09s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (0.06s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (0.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (0.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (0.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (0.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (0.03s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (0.01s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (0.02s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (0.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (0.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/UploadTimeout (0.78s) --- 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 15.157s "go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose" - Finished OK in 16.21574716s (try 1/5)