"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose" - Starting (try 4/5) 2025/12/05 01:47:01 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-qihawey7kuri" 2025/12/05 01:47:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:01 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:01 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:01 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:01 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:01 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:01 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:01 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:01 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:01 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:01 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: Connecting to FTP server 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33894->172.17.0.8:21, err= 2025/12/05 01:47:01 DEBUG : pacer: low level retry 1/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:01 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33904->172.17.0.8:21, err= 2025/12/05 01:47:01 DEBUG : pacer: low level retry 2/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:01 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33914->172.17.0.8:21, err= 2025/12/05 01:47:01 DEBUG : pacer: low level retry 3/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:01 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33930->172.17.0.8:21, err= 2025/12/05 01:47:01 DEBUG : pacer: low level retry 4/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:01 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:01 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33940->172.17.0.8:21, err= 2025/12/05 01:47:01 DEBUG : pacer: low level retry 5/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:01 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/12/05 01:47:02 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:02 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33946->172.17.0.8:21, err= 2025/12/05 01:47:02 DEBUG : pacer: low level retry 6/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:02 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/12/05 01:47:02 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:02 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33952->172.17.0.8:21, err= 2025/12/05 01:47:02 DEBUG : pacer: low level retry 7/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:02 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/12/05 01:47:02 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:02 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33974->172.17.0.8:21, err= 2025/12/05 01:47:02 DEBUG : pacer: low level retry 8/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:02 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:35866->172.17.0.8:21, err= 2025/12/05 01:47:04 DEBUG : pacer: Reducing sleep to 1.5s 2025/12/05 01:47:04 DEBUG : Creating backend with remote "/tmp/rclone2869924492" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateNumChunks === RUN TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} === RUN TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} === RUN TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} --- PASS: TestMultithreadCalculateNumChunks (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s) === RUN TestMultithreadCopy run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-qihawey7kuri", Local "Local file system at /tmp/rclone2869924492", Modify Window "1s" multithread_test.go:119: multithread writing not supported 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:38934") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:50474->172.17.0.8:38934, err= 2025/12/05 01:47:04 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopy (0.00s) === RUN TestMultithreadCopyAbort run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-qihawey7kuri", Local "Local file system at /tmp/rclone2869924492", Modify Window "1s" multithread_test.go:119: multithread writing not supported 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:37673") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:54772->172.17.0.8:37673, err= 2025/12/05 01:47:04 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopyAbort (0.00s) === RUN TestSizeDiffers 2025/12/05 01:47:04 DEBUG : a: size = 0 OK 2025/12/05 01:47:04 DEBUG : a: size = 1 (memory) 2025/12/05 01:47:04 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/12/05 01:47:04 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/12/05 01:47:04 DEBUG : potato: Seek from 10 to 0 2025/12/05 01:47:04 DEBUG : potato: Seek from 10 to 0 2025/12/05 01:47:04 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/12/05 01:47:04 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Seek from 5 to 2 2025/12/05 01:47:04 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/12/05 01:47:04 DEBUG : potato: Seek from 7 to 0 2025/12/05 01:47:04 DEBUG : potato: Seek from 7 to 0 2025/12/05 01:47:04 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/12/05 01:47:04 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Seek from 5 to 2 2025/12/05 01:47:04 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/12/05 01:47:04 DEBUG : potato: Seek from 8 to 0 2025/12/05 01:47:04 DEBUG : potato: Seek from 8 to 0 2025/12/05 01:47:04 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/12/05 01:47:04 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/12/05 01:47:04 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:04 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:04 DEBUG : potato: Seek from 5 to 2 2025/12/05 01:47:04 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/12/05 01:47:04 DEBUG : potato: Seek from 9 to 0 2025/12/05 01:47:04 DEBUG : potato: Seek from 9 to 0 2025/12/05 01:47:04 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-qihawey7kuri", Local "Local file system at /tmp/rclone2869924492", Modify Window "1s" 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:39482") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:36832->172.17.0.8:39482, err= 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:37813") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:43132->172.17.0.8:37813, err= === RUN TestCheck/1 === RUN TestCheck/2 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:36805") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33096->172.17.0.8:36805, err= 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:37801") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:46514->172.17.0.8:37801, err= === RUN TestCheck/3 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:30794") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:43400->172.17.0.8:30794, err= 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:35607") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:53492->172.17.0.8:35607, err= === RUN TestCheck/4 === RUN TestCheck/5 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:36788") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:37180->172.17.0.8:36788, err= 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:33367") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:35052->172.17.0.8:33367, err= === RUN TestCheck/6 === RUN TestCheck/7 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:37736") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:51718->172.17.0.8:37736, err= 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:38398") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:53380->172.17.0.8:38398, err= --- PASS: TestCheck (0.04s) --- PASS: TestCheck/1 (0.00s) --- PASS: TestCheck/2 (0.00s) --- PASS: TestCheck/3 (0.00s) --- PASS: TestCheck/4 (0.00s) --- PASS: TestCheck/5 (0.00s) --- PASS: TestCheck/6 (0.00s) --- PASS: TestCheck/7 (0.00s) === RUN TestCheckFsError 2025/12/05 01:47:04 DEBUG : Creating backend with remote "nonexistent" 2025/12/05 01:47:04 DEBUG : Creating backend with remote "nonexistent" 2025/12/05 01:47:04 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2025/12/05 01:47:04 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2025/12/05 01:47:04 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2025/12/05 01:47:04 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-qihawey7kuri", Local "Local file system at /tmp/rclone2869924492", Modify Window "1s" 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:32167") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:60686->172.17.0.8:32167, err= 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:30937") 2025/12/05 01:47:04 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:60612->172.17.0.8:30937, err= === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2025/12/05 01:47:07 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:35469") 2025/12/05 01:47:07 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:38022->172.17.0.8:35469, err= 2025/12/05 01:47:07 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:32566") 2025/12/05 01:47:07 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:39412->172.17.0.8:32566, err= === RUN TestCheckDownload/3 2025/12/05 01:47:08 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:32783") 2025/12/05 01:47:08 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:60282->172.17.0.8:32783, err= 2025/12/05 01:47:08 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:35558") 2025/12/05 01:47:08 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:47754->172.17.0.8:35558, err= === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:30897") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:37954->172.17.0.8:30897, err= 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:33060") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:54654->172.17.0.8:33060, err= === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:38396") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:41792->172.17.0.8:38396, err= 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:32429") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:39098->172.17.0.8:32429, err= --- PASS: TestCheckDownload (7.61s) --- PASS: TestCheckDownload/1 (1.93s) --- PASS: TestCheckDownload/2 (1.49s) --- PASS: TestCheckDownload/3 (1.12s) --- PASS: TestCheckDownload/4 (1.47s) --- PASS: TestCheckDownload/5 (0.81s) --- PASS: TestCheckDownload/6 (0.46s) --- PASS: TestCheckDownload/7 (0.26s) === RUN TestCheckSizeOnly run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-qihawey7kuri", Local "Local file system at /tmp/rclone2869924492", Modify Window "1s" 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:39889") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:36052->172.17.0.8:39889, err= 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:31779") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33388->172.17.0.8:31779, err= === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:30351") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:35366->172.17.0.8:30351, err= 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:38933") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:40588->172.17.0.8:38933, err= === RUN TestCheckSizeOnly/3 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:33654") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:36718->172.17.0.8:33654, err= 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:35942") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:50610->172.17.0.8:35942, err= === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:36810") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:41216->172.17.0.8:36810, err= 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:32780") 2025/12/05 01:47:11 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:44364->172.17.0.8:32780, err= === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:33386") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:55636->172.17.0.8:33386, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:39914") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:45320->172.17.0.8:39914, err= --- PASS: TestCheckSizeOnly (0.07s) --- PASS: TestCheckSizeOnly/1 (0.00s) --- PASS: TestCheckSizeOnly/2 (0.00s) --- PASS: TestCheckSizeOnly/3 (0.00s) --- PASS: TestCheckSizeOnly/4 (0.00s) --- PASS: TestCheckSizeOnly/5 (0.00s) --- PASS: TestCheckSizeOnly/6 (0.00s) --- PASS: TestCheckSizeOnly/7 (0.00s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.01s) === RUN TestParseSumFile run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-qihawey7kuri", Local "Local file system at /tmp/rclone2869924492", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:37068") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:42588->172.17.0.8:37068, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:30656") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:42410->172.17.0.8:30656, err= 2025/12/05 01:47:12 DEBUG : pacer: Reducing sleep to 47.514527ms 2025/12/05 01:47:12 NOTICE: test.sum: improperly formatted checksum line 4 2025/12/05 01:47:12 NOTICE: test.sum: improperly formatted checksum line 5 2025/12/05 01:47:12 NOTICE: test.sum: improperly formatted checksum line 6 2025/12/05 01:47:12 NOTICE: test.sum: 2 warning(s) suppressed... 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:39204") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:56088->172.17.0.8:39204, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:34775") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:47392->172.17.0.8:34775, err= 2025/12/05 01:47:12 DEBUG : pacer: Reducing sleep to 35.635895ms 2025/12/05 01:47:12 NOTICE: test.sum: improperly formatted checksum line 4 2025/12/05 01:47:12 NOTICE: test.sum: improperly formatted checksum line 5 2025/12/05 01:47:12 NOTICE: test.sum: improperly formatted checksum line 6 2025/12/05 01:47:12 NOTICE: test.sum: 2 warning(s) suppressed... 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:38850") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:58182->172.17.0.8:38850, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:32285") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:40714->172.17.0.8:32285, err= --- PASS: TestParseSumFile (0.07s) === RUN TestCheckSum run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-qihawey7kuri", Local "Local file system at /tmp/rclone2869924492", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-qihawey7kuri/data" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri/data: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri/data: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri/data: > dial: conn=172.17.0.1:35944->172.17.0.8:21, err= check_test.go:350: ftp://172.17.0.8:21/rclone-test-qihawey7kuri/data lacks md5, skipping 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:32654") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:44746->172.17.0.8:32654, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:36461") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:38798->172.17.0.8:36461, err= --- SKIP: TestCheckSum (0.01s) === RUN TestCheckSumDownload run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-qihawey7kuri", Local "Local file system at /tmp/rclone2869924492", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-qihawey7kuri/data" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri/data: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri/data: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri/data: > dial: conn=172.17.0.1:35958->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:38901") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:41770->172.17.0.8:38901, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:34439") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:39016->172.17.0.8:34439, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:30967") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33862->172.17.0.8:30967, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:32285") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:40728->172.17.0.8:32285, err= === RUN TestCheckSumDownload/subtest1 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:31854") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:53702->172.17.0.8:31854, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:34816") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:39020->172.17.0.8:34816, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:31098") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:34934->172.17.0.8:31098, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:33845") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:54098->172.17.0.8:33845, err= === RUN TestCheckSumDownload/subtest2 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:31722") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:47526->172.17.0.8:31722, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:30865") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:34138->172.17.0.8:30865, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:36732") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:36568->172.17.0.8:36732, err= === RUN TestCheckSumDownload/subtest3 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:38908") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:42712->172.17.0.8:38908, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:34552") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:59326->172.17.0.8:34552, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:31328") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:53856->172.17.0.8:31328, err= === RUN TestCheckSumDownload/subtest4 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:35061") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:54342->172.17.0.8:35061, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:39080") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:39242->172.17.0.8:39080, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:30403") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:48880->172.17.0.8:30403, err= === RUN TestCheckSumDownload/subtest5 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:38433") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:58758->172.17.0.8:38433, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:37182") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:57638->172.17.0.8:37182, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:36018") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:44492->172.17.0.8:36018, err= === RUN TestCheckSumDownload/subtest6 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:35651") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:47354->172.17.0.8:35651, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:39808") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:35282->172.17.0.8:39808, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:37177") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:33286->172.17.0.8:37177, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:34810") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:45794->172.17.0.8:34810, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:37949") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:50552->172.17.0.8:37949, err= === RUN TestCheckSumDownload/subtest7 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:37255") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:34904->172.17.0.8:37255, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:34926") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:46572->172.17.0.8:34926, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: dial("tcp","172.17.0.8:35330") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qihawey7kuri: > dial: conn=172.17.0.1:45752->172.17.0.8:35330, err= --- PASS: TestCheckSumDownload (0.20s) --- PASS: TestCheckSumDownload/subtest1 (0.01s) --- PASS: TestCheckSumDownload/subtest2 (0.03s) --- PASS: TestCheckSumDownload/subtest3 (0.03s) --- PASS: TestCheckSumDownload/subtest4 (0.02s) --- PASS: TestCheckSumDownload/subtest5 (0.02s) --- PASS: TestCheckSumDownload/subtest6 (0.02s) --- PASS: TestCheckSumDownload/subtest7 (0.01s) === RUN TestApplyTransforms 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-bojivis2tamo" 2025/12/05 01:47:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: > dial: conn=172.17.0.1:35972->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : Creating backend with remote "/tmp/rclone2655072978" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-bojivis2tamo", Local "Local file system at /tmp/rclone2655072978", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: dial("tcp","172.17.0.8:35534") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: > dial: conn=172.17.0.1:33476->172.17.0.8:35534, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: dial("tcp","172.17.0.8:37845") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: > dial: conn=172.17.0.1:40254->172.17.0.8:37845, err= upper checkfile vs. lower remote (without normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: dial("tcp","172.17.0.8:37889") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: > dial: conn=172.17.0.1:55330->172.17.0.8:37889, err= 2025/12/05 01:47:12 ERROR : hello, world!: sum not found 2025/12/05 01:47:12 ERROR : HELLO, WORLD!: file not in ftp://172.17.0.8:21/rclone-test-bojivis2tamo 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-bojivis2tamo: 1 files missing 2025/12/05 01:47:12 NOTICE: 1 hashes missing 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-bojivis2tamo: 1 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-bojivis2tamo: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: dial("tcp","172.17.0.8:34561") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: > dial: conn=172.17.0.1:60782->172.17.0.8:34561, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: dial("tcp","172.17.0.8:39111") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-bojivis2tamo: > dial: conn=172.17.0.1:53324->172.17.0.8:39111, err= 2025/12/05 01:47:12 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-bojivis2tamo: 0 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-bojivis2tamo: 1 matching files 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-reguyum0hoko" 2025/12/05 01:47:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: > dial: conn=172.17.0.1:35984->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : Creating backend with remote "/tmp/rclone422323863" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-reguyum0hoko", Local "Local file system at /tmp/rclone422323863", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: dial("tcp","172.17.0.8:33061") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: > dial: conn=172.17.0.1:58408->172.17.0.8:33061, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: dial("tcp","172.17.0.8:34882") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: > dial: conn=172.17.0.1:57246->172.17.0.8:34882, err= lower checkfile vs. upper remote (without normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: dial("tcp","172.17.0.8:31679") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: > dial: conn=172.17.0.1:42382->172.17.0.8:31679, err= 2025/12/05 01:47:12 ERROR : HELLO, WORLD!: sum not found 2025/12/05 01:47:12 ERROR : hello, world!: file not in ftp://172.17.0.8:21/rclone-test-reguyum0hoko 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-reguyum0hoko: 1 files missing 2025/12/05 01:47:12 NOTICE: 1 hashes missing 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-reguyum0hoko: 1 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-reguyum0hoko: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: dial("tcp","172.17.0.8:36845") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: > dial: conn=172.17.0.1:54572->172.17.0.8:36845, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: dial("tcp","172.17.0.8:30501") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-reguyum0hoko: > dial: conn=172.17.0.1:49426->172.17.0.8:30501, err= 2025/12/05 01:47:12 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-reguyum0hoko: 0 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-reguyum0hoko: 1 matching files 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-pifedad1fado" 2025/12/05 01:47:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: > dial: conn=172.17.0.1:36000->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : Creating backend with remote "/tmp/rclone1353184760" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-pifedad1fado", Local "Local file system at /tmp/rclone1353184760", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: dial("tcp","172.17.0.8:33308") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: > dial: conn=172.17.0.1:54282->172.17.0.8:33308, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: dial("tcp","172.17.0.8:36047") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: > dial: conn=172.17.0.1:55854->172.17.0.8:36047, err= lower checkfile vs. upperlowermixed remote (without normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: dial("tcp","172.17.0.8:31842") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: > dial: conn=172.17.0.1:58946->172.17.0.8:31842, err= 2025/12/05 01:47:12 ERROR : HeLlO, wOrLd!: sum not found 2025/12/05 01:47:12 ERROR : hello, world!: file not in ftp://172.17.0.8:21/rclone-test-pifedad1fado 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-pifedad1fado: 1 files missing 2025/12/05 01:47:12 NOTICE: 1 hashes missing 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-pifedad1fado: 1 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-pifedad1fado: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: dial("tcp","172.17.0.8:37651") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: > dial: conn=172.17.0.1:51090->172.17.0.8:37651, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: dial("tcp","172.17.0.8:30254") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-pifedad1fado: > dial: conn=172.17.0.1:42456->172.17.0.8:30254, err= 2025/12/05 01:47:12 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-pifedad1fado: 0 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-pifedad1fado: 1 matching files 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-zamojax9weme" 2025/12/05 01:47:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: > dial: conn=172.17.0.1:36006->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : Creating backend with remote "/tmp/rclone1725103027" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-zamojax9weme", Local "Local file system at /tmp/rclone1725103027", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: dial("tcp","172.17.0.8:32384") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: > dial: conn=172.17.0.1:60388->172.17.0.8:32384, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: dial("tcp","172.17.0.8:33836") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: > dial: conn=172.17.0.1:46426->172.17.0.8:33836, err= upperlowermixed checkfile vs. upper remote (without normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: dial("tcp","172.17.0.8:34119") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: > dial: conn=172.17.0.1:58668->172.17.0.8:34119, err= 2025/12/05 01:47:12 ERROR : HELLO, WORLD!: sum not found 2025/12/05 01:47:12 ERROR : HeLlO, wOrLd!: file not in ftp://172.17.0.8:21/rclone-test-zamojax9weme 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-zamojax9weme: 1 files missing 2025/12/05 01:47:12 NOTICE: 1 hashes missing 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-zamojax9weme: 1 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-zamojax9weme: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: dial("tcp","172.17.0.8:38281") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: > dial: conn=172.17.0.1:44246->172.17.0.8:38281, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: dial("tcp","172.17.0.8:38759") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-zamojax9weme: > dial: conn=172.17.0.1:50920->172.17.0.8:38759, err= 2025/12/05 01:47:12 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-zamojax9weme: 0 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-zamojax9weme: 1 matching files 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-cojotug0mubo" 2025/12/05 01:47:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: > dial: conn=172.17.0.1:36014->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : Creating backend with remote "/tmp/rclone1442144025" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-cojotug0mubo", Local "Local file system at /tmp/rclone1442144025", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: dial("tcp","172.17.0.8:37168") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: > dial: conn=172.17.0.1:45244->172.17.0.8:37168, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: dial("tcp","172.17.0.8:33376") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: > dial: conn=172.17.0.1:34172->172.17.0.8:33376, err= NFD checkfile vs. NFC remote (without normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: dial("tcp","172.17.0.8:31269") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: > dial: conn=172.17.0.1:38700->172.17.0.8:31269, err= 2025/12/05 01:47:12 ERROR : 測試_Русский___ě_áñ: sum not found 2025/12/05 01:47:12 ERROR : 測試_Русский___ě_áñ: file not in ftp://172.17.0.8:21/rclone-test-cojotug0mubo 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-cojotug0mubo: 1 files missing 2025/12/05 01:47:12 NOTICE: 1 hashes missing 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-cojotug0mubo: 1 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-cojotug0mubo: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: dial("tcp","172.17.0.8:32646") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: > dial: conn=172.17.0.1:55434->172.17.0.8:32646, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: dial("tcp","172.17.0.8:32627") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-cojotug0mubo: > dial: conn=172.17.0.1:48460->172.17.0.8:32627, err= 2025/12/05 01:47:12 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-cojotug0mubo: 0 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-cojotug0mubo: 1 matching files 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-keyelen2jisa" 2025/12/05 01:47:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: > dial: conn=172.17.0.1:36016->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : Creating backend with remote "/tmp/rclone695252730" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-keyelen2jisa", Local "Local file system at /tmp/rclone695252730", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: dial("tcp","172.17.0.8:33560") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: > dial: conn=172.17.0.1:56982->172.17.0.8:33560, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: dial("tcp","172.17.0.8:37309") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: > dial: conn=172.17.0.1:42676->172.17.0.8:37309, err= NFC checkfile vs. NFD remote (without normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: dial("tcp","172.17.0.8:31008") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: > dial: conn=172.17.0.1:50708->172.17.0.8:31008, err= 2025/12/05 01:47:12 ERROR : 測試_Русский___ě_áñ: sum not found 2025/12/05 01:47:12 ERROR : 測試_Русский___ě_áñ: file not in ftp://172.17.0.8:21/rclone-test-keyelen2jisa 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-keyelen2jisa: 1 files missing 2025/12/05 01:47:12 NOTICE: 1 hashes missing 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-keyelen2jisa: 1 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-keyelen2jisa: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: dial("tcp","172.17.0.8:34955") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: > dial: conn=172.17.0.1:47640->172.17.0.8:34955, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: dial("tcp","172.17.0.8:32105") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-keyelen2jisa: > dial: conn=172.17.0.1:56568->172.17.0.8:32105, err= 2025/12/05 01:47:12 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-keyelen2jisa: 0 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-keyelen2jisa: 1 matching files 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-qocobot2poya" 2025/12/05 01:47:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: > dial: conn=172.17.0.1:36022->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : Creating backend with remote "/tmp/rclone3092138944" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-qocobot2poya", Local "Local file system at /tmp/rclone3092138944", Modify Window "1s" 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: dial("tcp","172.17.0.8:35805") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: > dial: conn=172.17.0.1:36600->172.17.0.8:35805, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: dial("tcp","172.17.0.8:32283") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: > dial: conn=172.17.0.1:41110->172.17.0.8:32283, err= NFDx2 checkfile vs. both remote (without normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: dial("tcp","172.17.0.8:35769") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: > dial: conn=172.17.0.1:43746->172.17.0.8:35769, err= 2025/12/05 01:47:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2025/12/05 01:47:12 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in ftp://172.17.0.8:21/rclone-test-qocobot2poya 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-qocobot2poya: 1 files missing 2025/12/05 01:47:12 NOTICE: 1 hashes missing 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-qocobot2poya: 1 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-qocobot2poya: 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: dial("tcp","172.17.0.8:31630") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: > dial: conn=172.17.0.1:54736->172.17.0.8:31630, err= 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: dial("tcp","172.17.0.8:33001") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-qocobot2poya: > dial: conn=172.17.0.1:57756->172.17.0.8:33001, err= 2025/12/05 01:47:12 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-qocobot2poya: 0 differences found 2025/12/05 01:47:12 NOTICE: ftp://172.17.0.8:21/rclone-test-qocobot2poya: 1 matching files 2025/12/05 01:47:12 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-vizegid9vuba" 2025/12/05 01:47:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:12 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:12 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:12 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:12 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:12 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: Connecting to FTP server 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:36038->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : pacer: low level retry 1/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:36040->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : pacer: low level retry 2/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:12 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:36050->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : pacer: low level retry 3/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:12 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:36064->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : pacer: low level retry 4/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:12 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:36066->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : pacer: low level retry 5/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:12 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:12 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:36078->172.17.0.8:21, err= 2025/12/05 01:47:12 DEBUG : pacer: low level retry 6/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:12 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/12/05 01:47:13 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:13 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:36082->172.17.0.8:21, err= 2025/12/05 01:47:13 DEBUG : pacer: low level retry 7/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:13 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/12/05 01:47:13 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:13 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:36096->172.17.0.8:21, err= 2025/12/05 01:47:13 DEBUG : pacer: low level retry 8/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:13 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/12/05 01:47:15 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:15 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:50342->172.17.0.8:21, err= 2025/12/05 01:47:15 DEBUG : pacer: low level retry 9/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:17 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:17 DEBUG : ftp://172.17.0.8:21/rclone-test-vizegid9vuba: > dial: conn=172.17.0.1:50348->172.17.0.8:21, err= 2025/12/05 01:47:17 DEBUG : pacer: low level retry 10/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:17 NOTICE: Failed to open remote "TestFTPPureftpd:": NewFs: failed to make FTP connection to "172.17.0.8:21": 421 50 users (the maximum) are already logged in, sorry "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose" - Finished ERROR in 15.453844177s (try 4/5): exit status 1: Failed []