"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose" - Starting (try 1/5) 2026/05/10 03:45:41 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-jeqamob8caha" 2026/05/10 03:45:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/10 03:45:41 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/10 03:45:41 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:41 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:41 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:41 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:41 DEBUG : TestFTPPureftpd: detected overridden config - adding "{PZ35u}" suffix to name 2026/05/10 03:45:41 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:41 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:41 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:41 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:41 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: Connecting to FTP server 2026/05/10 03:45:41 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:41 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:59772->172.17.0.3:21, err= 2026/05/10 03:45:44 DEBUG : Creating backend with remote "/tmp/rclone1048287439" === 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:198: Remote "ftp://172.17.0.3:21/rclone-test-jeqamob8caha", Local "Local file system at /tmp/rclone1048287439", Modify Window "1s" multithread_test.go:119: multithread writing not supported 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:32018") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:33116->172.17.0.3:32018, err= 2026/05/10 03:45:44 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopy (0.00s) === RUN TestMultithreadCopyAbort run.go:198: Remote "ftp://172.17.0.3:21/rclone-test-jeqamob8caha", Local "Local file system at /tmp/rclone1048287439", Modify Window "1s" multithread_test.go:119: multithread writing not supported 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:35291") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:50448->172.17.0.3:35291, err= 2026/05/10 03:45:44 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopyAbort (0.00s) === RUN TestSizeDiffers 2026/05/10 03:45:44 DEBUG : a: size = 0 OK 2026/05/10 03:45:44 DEBUG : a: size = 1 (memory) 2026/05/10 03:45:44 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/05/10 03:45:44 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/05/10 03:45:44 DEBUG : potato: Seek from 10 to 0 2026/05/10 03:45:44 DEBUG : potato: Seek from 10 to 0 2026/05/10 03:45:44 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/05/10 03:45:44 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Seek from 5 to 2 2026/05/10 03:45:44 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/05/10 03:45:44 DEBUG : potato: Seek from 7 to 0 2026/05/10 03:45:44 DEBUG : potato: Seek from 7 to 0 2026/05/10 03:45:44 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/05/10 03:45:44 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Seek from 5 to 2 2026/05/10 03:45:44 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/05/10 03:45:44 DEBUG : potato: Seek from 8 to 0 2026/05/10 03:45:44 DEBUG : potato: Seek from 8 to 0 2026/05/10 03:45:44 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/05/10 03:45:44 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/05/10 03:45:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/05/10 03:45:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/05/10 03:45:44 DEBUG : potato: Seek from 5 to 2 2026/05/10 03:45:44 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/05/10 03:45:44 DEBUG : potato: Seek from 9 to 0 2026/05/10 03:45:44 DEBUG : potato: Seek from 9 to 0 2026/05/10 03:45:44 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:198: Remote "ftp://172.17.0.3:21/rclone-test-jeqamob8caha", Local "Local file system at /tmp/rclone1048287439", Modify Window "1s" 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:32033") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:50256->172.17.0.3:32033, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:39480") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:57280->172.17.0.3:39480, err= === RUN TestCheck/1 === RUN TestCheck/2 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:36552") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:49556->172.17.0.3:36552, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:39189") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:45476->172.17.0.3:39189, err= === RUN TestCheck/3 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:32653") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:56098->172.17.0.3:32653, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:35867") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:47918->172.17.0.3:35867, err= === RUN TestCheck/4 === RUN TestCheck/5 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:33101") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:33320->172.17.0.3:33101, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:30519") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:56082->172.17.0.3:30519, err= === RUN TestCheck/6 === RUN TestCheck/7 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34219") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:56438->172.17.0.3:34219, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:37416") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:53328->172.17.0.3:37416, 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 2026/05/10 03:45:44 DEBUG : Creating backend with remote "nonexistent" 2026/05/10 03:45:44 DEBUG : Creating backend with remote "nonexistent" 2026/05/10 03:45:44 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish 2026/05/10 03:45:44 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found 2026/05/10 03:45:44 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2026/05/10 03:45:44 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:198: Remote "ftp://172.17.0.3:21/rclone-test-jeqamob8caha", Local "Local file system at /tmp/rclone1048287439", Modify Window "1s" 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:37428") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:49026->172.17.0.3:37428, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:32063") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:36806->172.17.0.3:32063, err= === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:35556") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:50410->172.17.0.3:35556, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:37498") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:52872->172.17.0.3:37498, err= === RUN TestCheckDownload/3 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:33226") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:38782->172.17.0.3:33226, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34658") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:49704->172.17.0.3:34658, err= === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:32468") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:38444->172.17.0.3:32468, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34285") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:43328->172.17.0.3:34285, err= === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:33868") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:42680->172.17.0.3:33868, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:32987") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:47084->172.17.0.3:32987, err= --- PASS: TestCheckDownload (0.13s) --- PASS: TestCheckDownload/1 (0.00s) --- PASS: TestCheckDownload/2 (0.01s) --- PASS: TestCheckDownload/3 (0.01s) --- PASS: TestCheckDownload/4 (0.02s) --- PASS: TestCheckDownload/5 (0.01s) --- PASS: TestCheckDownload/6 (0.02s) --- PASS: TestCheckDownload/7 (0.02s) === RUN TestCheckSizeOnly run.go:198: Remote "ftp://172.17.0.3:21/rclone-test-jeqamob8caha", Local "Local file system at /tmp/rclone1048287439", Modify Window "1s" 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34062") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:32876->172.17.0.3:34062, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:32934") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:60218->172.17.0.3:32934, err= === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:31353") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:60638->172.17.0.3:31353, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:36149") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:48300->172.17.0.3:36149, err= === RUN TestCheckSizeOnly/3 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:35587") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:50964->172.17.0.3:35587, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34834") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:59162->172.17.0.3:34834, err= === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:35361") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:49668->172.17.0.3:35361, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:33861") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:34240->172.17.0.3:33861, err= === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:36533") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:53578->172.17.0.3:36533, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:31906") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:48550->172.17.0.3:31906, err= --- PASS: TestCheckSizeOnly (0.03s) --- 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.00s) === RUN TestParseSumFile run.go:198: Remote "ftp://172.17.0.3:21/rclone-test-jeqamob8caha", Local "Local file system at /tmp/rclone1048287439", Modify Window "1s" 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:39932") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:38246->172.17.0.3:39932, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34170") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:40866->172.17.0.3:34170, err= 2026/05/10 03:45:44 NOTICE: test.sum: improperly formatted checksum line 4 2026/05/10 03:45:44 NOTICE: test.sum: improperly formatted checksum line 5 2026/05/10 03:45:44 NOTICE: test.sum: improperly formatted checksum line 6 2026/05/10 03:45:44 NOTICE: test.sum: 2 warning(s) suppressed... 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:38065") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:45598->172.17.0.3:38065, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:31782") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:50028->172.17.0.3:31782, err= 2026/05/10 03:45:44 NOTICE: test.sum: improperly formatted checksum line 4 2026/05/10 03:45:44 NOTICE: test.sum: improperly formatted checksum line 5 2026/05/10 03:45:44 NOTICE: test.sum: improperly formatted checksum line 6 2026/05/10 03:45:44 NOTICE: test.sum: 2 warning(s) suppressed... 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:30579") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:54992->172.17.0.3:30579, err= 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34130") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:40546->172.17.0.3:34130, err= --- PASS: TestParseSumFile (0.02s) === RUN TestCheckSum run.go:198: Remote "ftp://172.17.0.3:21/rclone-test-jeqamob8caha", Local "Local file system at /tmp/rclone1048287439", Modify Window "1s" 2026/05/10 03:45:44 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-jeqamob8caha/data" 2026/05/10 03:45:44 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/10 03:45:44 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:44 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:44 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:44 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:44 DEBUG : TestFTPPureftpd: detected overridden config - adding "{PZ35u}" suffix to name 2026/05/10 03:45:44 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:44 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:44 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:44 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha/data: Connecting to FTP server 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha/data: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:44 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha/data: > dial: conn=172.17.0.1:59796->172.17.0.3:21, err= check_test.go:350: ftp://172.17.0.3:21/rclone-test-jeqamob8caha/data lacks md5, skipping 2026/05/10 03:45:47 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:33164") 2026/05/10 03:45:47 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:37854->172.17.0.3:33164, err= 2026/05/10 03:45:47 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:35662") 2026/05/10 03:45:47 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:55604->172.17.0.3:35662, err= --- SKIP: TestCheckSum (2.65s) === RUN TestCheckSumDownload run.go:198: Remote "ftp://172.17.0.3:21/rclone-test-jeqamob8caha", Local "Local file system at /tmp/rclone1048287439", Modify Window "1s" 2026/05/10 03:45:47 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-jeqamob8caha/data" 2026/05/10 03:45:47 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/10 03:45:47 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:47 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:47 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:47 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:47 DEBUG : TestFTPPureftpd: detected overridden config - adding "{PZ35u}" suffix to name 2026/05/10 03:45:47 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:47 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:47 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:47 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:47 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha/data: Connecting to FTP server 2026/05/10 03:45:47 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha/data: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:47 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha/data: > dial: conn=172.17.0.1:59806->172.17.0.3:21, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:39316") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:43928->172.17.0.3:39316, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:33227") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:60610->172.17.0.3:33227, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34911") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:58680->172.17.0.3:34911, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:36536") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:52936->172.17.0.3:36536, err= === RUN TestCheckSumDownload/subtest1 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:31538") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:58674->172.17.0.3:31538, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:33512") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:49718->172.17.0.3:33512, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:30732") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:33826->172.17.0.3:30732, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:32346") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:59392->172.17.0.3:32346, err= === RUN TestCheckSumDownload/subtest2 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:31997") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:47834->172.17.0.3:31997, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:31395") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:33978->172.17.0.3:31395, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34730") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:54706->172.17.0.3:34730, err= === RUN TestCheckSumDownload/subtest3 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:31334") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:42200->172.17.0.3:31334, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:30648") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:54330->172.17.0.3:30648, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:31219") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:33184->172.17.0.3:31219, err= === RUN TestCheckSumDownload/subtest4 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:31602") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:45018->172.17.0.3:31602, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34358") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:53418->172.17.0.3:34358, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:36670") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:47450->172.17.0.3:36670, err= === RUN TestCheckSumDownload/subtest5 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:37420") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:59078->172.17.0.3:37420, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:32406") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:34574->172.17.0.3:32406, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:36427") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:36578->172.17.0.3:36427, err= === RUN TestCheckSumDownload/subtest6 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:35188") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:55064->172.17.0.3:35188, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:35014") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:40734->172.17.0.3:35014, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:36517") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:45986->172.17.0.3:36517, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34338") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:50820->172.17.0.3:34338, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:34622") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:42296->172.17.0.3:34622, err= === RUN TestCheckSumDownload/subtest7 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:39018") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:44030->172.17.0.3:39018, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:38132") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:45178->172.17.0.3:38132, err= 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: dial("tcp","172.17.0.3:33623") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-jeqamob8caha: > dial: conn=172.17.0.1:47844->172.17.0.3:33623, err= --- PASS: TestCheckSumDownload (2.38s) --- PASS: TestCheckSumDownload/subtest1 (0.00s) --- PASS: TestCheckSumDownload/subtest2 (0.01s) --- PASS: TestCheckSumDownload/subtest3 (0.02s) --- PASS: TestCheckSumDownload/subtest4 (0.02s) --- PASS: TestCheckSumDownload/subtest5 (0.02s) --- PASS: TestCheckSumDownload/subtest6 (0.02s) --- PASS: TestCheckSumDownload/subtest7 (0.02s) === RUN TestApplyTransforms 2026/05/10 03:45:49 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-popiqav3biya" 2026/05/10 03:45:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/10 03:45:49 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/10 03:45:49 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:49 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:49 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:49 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:49 DEBUG : TestFTPPureftpd: detected overridden config - adding "{PZ35u}" suffix to name 2026/05/10 03:45:49 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:49 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:49 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:49 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: Connecting to FTP server 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:49 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: > dial: conn=172.17.0.1:59836->172.17.0.3:21, err= 2026/05/10 03:45:51 DEBUG : Creating backend with remote "/tmp/rclone212540977" run.go:198: Remote "ftp://172.17.0.3:21/rclone-test-popiqav3biya", Local "Local file system at /tmp/rclone212540977", Modify Window "1s" 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: dial("tcp","172.17.0.3:31992") 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: > dial: conn=172.17.0.1:41212->172.17.0.3:31992, err= 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: dial("tcp","172.17.0.3:33987") 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: > dial: conn=172.17.0.1:42778->172.17.0.3:33987, err= upper checkfile vs. lower remote (without normalization) 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: dial("tcp","172.17.0.3:32539") 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: > dial: conn=172.17.0.1:60290->172.17.0.3:32539, err= 2026/05/10 03:45:51 ERROR : hello, world!: sum not found 2026/05/10 03:45:51 ERROR : HELLO, WORLD!: file not in ftp://172.17.0.3:21/rclone-test-popiqav3biya 2026/05/10 03:45:51 NOTICE: ftp://172.17.0.3:21/rclone-test-popiqav3biya: 1 files missing 2026/05/10 03:45:51 NOTICE: 1 hashes missing 2026/05/10 03:45:51 NOTICE: ftp://172.17.0.3:21/rclone-test-popiqav3biya: 1 differences found 2026/05/10 03:45:51 NOTICE: ftp://172.17.0.3:21/rclone-test-popiqav3biya: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: dial("tcp","172.17.0.3:31017") 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: > dial: conn=172.17.0.1:47490->172.17.0.3:31017, err= 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: dial("tcp","172.17.0.3:38394") 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-popiqav3biya: > dial: conn=172.17.0.1:48126->172.17.0.3:38394, err= 2026/05/10 03:45:51 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/10 03:45:51 NOTICE: ftp://172.17.0.3:21/rclone-test-popiqav3biya: 0 differences found 2026/05/10 03:45:51 NOTICE: ftp://172.17.0.3:21/rclone-test-popiqav3biya: 1 matching files 2026/05/10 03:45:51 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-kopalid1favi" 2026/05/10 03:45:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/10 03:45:51 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/10 03:45:51 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:51 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:51 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:51 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:51 DEBUG : TestFTPPureftpd: detected overridden config - adding "{PZ35u}" suffix to name 2026/05/10 03:45:51 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:51 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:51 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:51 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: Connecting to FTP server 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:51 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: > dial: conn=172.17.0.1:36780->172.17.0.3:21, err= 2026/05/10 03:45:54 DEBUG : Creating backend with remote "/tmp/rclone3335872330" run.go:198: Remote "ftp://172.17.0.3:21/rclone-test-kopalid1favi", Local "Local file system at /tmp/rclone3335872330", Modify Window "1s" 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: dial("tcp","172.17.0.3:39935") 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: > dial: conn=172.17.0.1:47360->172.17.0.3:39935, err= 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: dial("tcp","172.17.0.3:36051") 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: > dial: conn=172.17.0.1:57658->172.17.0.3:36051, err= lower checkfile vs. upper remote (without normalization) 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: dial("tcp","172.17.0.3:31382") 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: > dial: conn=172.17.0.1:39432->172.17.0.3:31382, err= 2026/05/10 03:45:54 ERROR : HELLO, WORLD!: sum not found 2026/05/10 03:45:54 ERROR : hello, world!: file not in ftp://172.17.0.3:21/rclone-test-kopalid1favi 2026/05/10 03:45:54 NOTICE: ftp://172.17.0.3:21/rclone-test-kopalid1favi: 1 files missing 2026/05/10 03:45:54 NOTICE: 1 hashes missing 2026/05/10 03:45:54 NOTICE: ftp://172.17.0.3:21/rclone-test-kopalid1favi: 1 differences found 2026/05/10 03:45:54 NOTICE: ftp://172.17.0.3:21/rclone-test-kopalid1favi: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: dial("tcp","172.17.0.3:33839") 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: > dial: conn=172.17.0.1:54154->172.17.0.3:33839, err= 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: dial("tcp","172.17.0.3:32445") 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-kopalid1favi: > dial: conn=172.17.0.1:57912->172.17.0.3:32445, err= 2026/05/10 03:45:54 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/10 03:45:54 NOTICE: ftp://172.17.0.3:21/rclone-test-kopalid1favi: 0 differences found 2026/05/10 03:45:54 NOTICE: ftp://172.17.0.3:21/rclone-test-kopalid1favi: 1 matching files 2026/05/10 03:45:54 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-pijukan8fipo" 2026/05/10 03:45:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/10 03:45:54 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/10 03:45:54 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:54 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:54 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:54 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:54 DEBUG : TestFTPPureftpd: detected overridden config - adding "{PZ35u}" suffix to name 2026/05/10 03:45:54 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:54 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:54 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:54 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: Connecting to FTP server 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:54 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: > dial: conn=172.17.0.1:36824->172.17.0.3:21, err= 2026/05/10 03:45:56 DEBUG : Creating backend with remote "/tmp/rclone3519849891" run.go:198: Remote "ftp://172.17.0.3:21/rclone-test-pijukan8fipo", Local "Local file system at /tmp/rclone3519849891", Modify Window "1s" 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: dial("tcp","172.17.0.3:32157") 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: > dial: conn=172.17.0.1:56692->172.17.0.3:32157, err= 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: dial("tcp","172.17.0.3:30785") 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: > dial: conn=172.17.0.1:58482->172.17.0.3:30785, err= lower checkfile vs. upperlowermixed remote (without normalization) 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: dial("tcp","172.17.0.3:33999") 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: > dial: conn=172.17.0.1:34648->172.17.0.3:33999, err= 2026/05/10 03:45:56 ERROR : HeLlO, wOrLd!: sum not found 2026/05/10 03:45:56 ERROR : hello, world!: file not in ftp://172.17.0.3:21/rclone-test-pijukan8fipo 2026/05/10 03:45:56 NOTICE: ftp://172.17.0.3:21/rclone-test-pijukan8fipo: 1 files missing 2026/05/10 03:45:56 NOTICE: 1 hashes missing 2026/05/10 03:45:56 NOTICE: ftp://172.17.0.3:21/rclone-test-pijukan8fipo: 1 differences found 2026/05/10 03:45:56 NOTICE: ftp://172.17.0.3:21/rclone-test-pijukan8fipo: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: dial("tcp","172.17.0.3:37724") 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: > dial: conn=172.17.0.1:46636->172.17.0.3:37724, err= 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: dial("tcp","172.17.0.3:30152") 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-pijukan8fipo: > dial: conn=172.17.0.1:52786->172.17.0.3:30152, err= 2026/05/10 03:45:56 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/10 03:45:56 NOTICE: ftp://172.17.0.3:21/rclone-test-pijukan8fipo: 0 differences found 2026/05/10 03:45:56 NOTICE: ftp://172.17.0.3:21/rclone-test-pijukan8fipo: 1 matching files 2026/05/10 03:45:56 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-gasixew3danu" 2026/05/10 03:45:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/10 03:45:56 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/10 03:45:56 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:56 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:56 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:56 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:56 DEBUG : TestFTPPureftpd: detected overridden config - adding "{PZ35u}" suffix to name 2026/05/10 03:45:56 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:56 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:56 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:56 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: Connecting to FTP server 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:56 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: > dial: conn=172.17.0.1:36836->172.17.0.3:21, err= 2026/05/10 03:45:59 DEBUG : Creating backend with remote "/tmp/rclone488178767" run.go:198: Remote "ftp://172.17.0.3:21/rclone-test-gasixew3danu", Local "Local file system at /tmp/rclone488178767", Modify Window "1s" 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: dial("tcp","172.17.0.3:32207") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: > dial: conn=172.17.0.1:56194->172.17.0.3:32207, err= 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: dial("tcp","172.17.0.3:36537") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: > dial: conn=172.17.0.1:60048->172.17.0.3:36537, err= upperlowermixed checkfile vs. upper remote (without normalization) 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: dial("tcp","172.17.0.3:35835") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: > dial: conn=172.17.0.1:53266->172.17.0.3:35835, err= 2026/05/10 03:45:59 ERROR : HELLO, WORLD!: sum not found 2026/05/10 03:45:59 ERROR : HeLlO, wOrLd!: file not in ftp://172.17.0.3:21/rclone-test-gasixew3danu 2026/05/10 03:45:59 NOTICE: ftp://172.17.0.3:21/rclone-test-gasixew3danu: 1 files missing 2026/05/10 03:45:59 NOTICE: 1 hashes missing 2026/05/10 03:45:59 NOTICE: ftp://172.17.0.3:21/rclone-test-gasixew3danu: 1 differences found 2026/05/10 03:45:59 NOTICE: ftp://172.17.0.3:21/rclone-test-gasixew3danu: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: dial("tcp","172.17.0.3:34602") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: > dial: conn=172.17.0.1:40538->172.17.0.3:34602, err= 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: dial("tcp","172.17.0.3:36938") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-gasixew3danu: > dial: conn=172.17.0.1:58962->172.17.0.3:36938, err= 2026/05/10 03:45:59 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/05/10 03:45:59 NOTICE: ftp://172.17.0.3:21/rclone-test-gasixew3danu: 0 differences found 2026/05/10 03:45:59 NOTICE: ftp://172.17.0.3:21/rclone-test-gasixew3danu: 1 matching files 2026/05/10 03:45:59 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-yezunuz4cidu" 2026/05/10 03:45:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/10 03:45:59 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2026/05/10 03:45:59 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:59 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:59 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:59 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:59 DEBUG : TestFTPPureftpd: detected overridden config - adding "{PZ35u}" suffix to name 2026/05/10 03:45:59 DEBUG : Setting host="172.17.0.3" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2026/05/10 03:45:59 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2026/05/10 03:45:59 DEBUG : Setting pass="iKplZCpV79dkkr-yKFo6HnbbNBaHpShF76DYAdpxwBA" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2026/05/10 03:45:59 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: Connecting to FTP server 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:36880->172.17.0.3:21, err= 2026/05/10 03:45:59 DEBUG : pacer: low level retry 1/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:45:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:36884->172.17.0.3:21, err= 2026/05/10 03:45:59 DEBUG : pacer: low level retry 2/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:45:59 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:36906->172.17.0.3:21, err= 2026/05/10 03:45:59 DEBUG : pacer: low level retry 3/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:45:59 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:36918->172.17.0.3:21, err= 2026/05/10 03:45:59 DEBUG : pacer: low level retry 4/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:45:59 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:36930->172.17.0.3:21, err= 2026/05/10 03:45:59 DEBUG : pacer: low level retry 5/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:45:59 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:36938->172.17.0.3:21, err= 2026/05/10 03:45:59 DEBUG : pacer: low level retry 6/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:45:59 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:45:59 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:36954->172.17.0.3:21, err= 2026/05/10 03:45:59 DEBUG : pacer: low level retry 7/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:45:59 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/05/10 03:46:00 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:46:00 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:36958->172.17.0.3:21, err= 2026/05/10 03:46:00 DEBUG : pacer: low level retry 8/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:46:00 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/05/10 03:46:01 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:46:01 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:55812->172.17.0.3:21, err= 2026/05/10 03:46:01 DEBUG : pacer: low level retry 9/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:46:03 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: dial("tcp","172.17.0.3:21") 2026/05/10 03:46:03 DEBUG : ftp://172.17.0.3:21/rclone-test-yezunuz4cidu: > dial: conn=172.17.0.1:55826->172.17.0.3:21, err= 2026/05/10 03:46:03 DEBUG : pacer: low level retry 10/10 (error 421 50 users (the maximum) are already logged in, sorry) 2026/05/10 03:46:03 NOTICE: Failed to open remote "TestFTPPureftpd:": NewFs: failed to make FTP connection to "172.17.0.3:21": 421 50 users (the maximum) are already logged in, sorry "./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose" - Finished ERROR in 22.141024361s (try 1/5): exit status 1: Failed []