"./operations.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose" - Starting (try 5/5) 2025/12/05 01:47:18 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-satijeh5quye" 2025/12/05 01:47:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:18 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: Connecting to FTP server 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:50360->172.17.0.8:21, err= 2025/12/05 01:47:18 DEBUG : Creating backend with remote "/tmp/rclone2881991198" === 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-satijeh5quye", Local "Local file system at /tmp/rclone2881991198", Modify Window "1s" multithread_test.go:119: multithread writing not supported 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:33781") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:48950->172.17.0.8:33781, err= 2025/12/05 01:47:18 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopy (0.00s) === RUN TestMultithreadCopyAbort run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-satijeh5quye", Local "Local file system at /tmp/rclone2881991198", Modify Window "1s" multithread_test.go:119: multithread writing not supported 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:38221") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:44212->172.17.0.8:38221, err= 2025/12/05 01:47:18 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopyAbort (0.00s) === RUN TestSizeDiffers 2025/12/05 01:47:18 DEBUG : a: size = 0 OK 2025/12/05 01:47:18 DEBUG : a: size = 1 (memory) 2025/12/05 01:47:18 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2025/12/05 01:47:18 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2025/12/05 01:47:18 DEBUG : potato: Seek from 10 to 0 2025/12/05 01:47:18 DEBUG : potato: Seek from 10 to 0 2025/12/05 01:47:18 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2025/12/05 01:47:18 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Seek from 5 to 2 2025/12/05 01:47:18 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2025/12/05 01:47:18 DEBUG : potato: Seek from 7 to 0 2025/12/05 01:47:18 DEBUG : potato: Seek from 7 to 0 2025/12/05 01:47:18 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2025/12/05 01:47:18 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Seek from 5 to 2 2025/12/05 01:47:18 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2025/12/05 01:47:18 DEBUG : potato: Seek from 8 to 0 2025/12/05 01:47:18 DEBUG : potato: Seek from 8 to 0 2025/12/05 01:47:18 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2025/12/05 01:47:18 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2025/12/05 01:47:18 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2025/12/05 01:47:18 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2025/12/05 01:47:18 DEBUG : potato: Seek from 5 to 2 2025/12/05 01:47:18 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2025/12/05 01:47:18 DEBUG : potato: Seek from 9 to 0 2025/12/05 01:47:18 DEBUG : potato: Seek from 9 to 0 2025/12/05 01:47:18 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-satijeh5quye", Local "Local file system at /tmp/rclone2881991198", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:38987") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:54012->172.17.0.8:38987, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:33958") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:57842->172.17.0.8:33958, err= === RUN TestCheck/1 === RUN TestCheck/2 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:32554") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:52342->172.17.0.8:32554, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:36255") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:47016->172.17.0.8:36255, err= === RUN TestCheck/3 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:33937") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:40104->172.17.0.8:33937, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:39596") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:57390->172.17.0.8:39596, err= === RUN TestCheck/4 === RUN TestCheck/5 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:36292") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:52500->172.17.0.8:36292, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:39198") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:36960->172.17.0.8:39198, err= === RUN TestCheck/6 === RUN TestCheck/7 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:35920") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:35838->172.17.0.8:35920, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:39739") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:44690->172.17.0.8:39739, err= --- PASS: TestCheck (0.06s) --- 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:18 DEBUG : Creating backend with remote "nonexistent" 2025/12/05 01:47:18 DEBUG : Creating backend with remote "nonexistent" 2025/12/05 01:47:18 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:18 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:18 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found 2025/12/05 01:47:18 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-satijeh5quye", Local "Local file system at /tmp/rclone2881991198", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:33902") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:58626->172.17.0.8:33902, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:34048") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:39866->172.17.0.8:34048, err= === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:39450") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:47202->172.17.0.8:39450, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:30623") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:52506->172.17.0.8:30623, err= === RUN TestCheckDownload/3 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:34119") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:60064->172.17.0.8:34119, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:36957") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:39922->172.17.0.8:36957, err= === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:36503") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:51448->172.17.0.8:36503, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:39564") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:56876->172.17.0.8:39564, err= === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:37779") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:48254->172.17.0.8:37779, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:35389") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:42408->172.17.0.8:35389, err= --- PASS: TestCheckDownload (0.14s) --- PASS: TestCheckDownload/1 (0.00s) --- PASS: TestCheckDownload/2 (0.00s) --- PASS: TestCheckDownload/3 (0.01s) --- PASS: TestCheckDownload/4 (0.02s) --- PASS: TestCheckDownload/5 (0.02s) --- PASS: TestCheckDownload/6 (0.01s) --- PASS: TestCheckDownload/7 (0.02s) === RUN TestCheckSizeOnly run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-satijeh5quye", Local "Local file system at /tmp/rclone2881991198", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:37005") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:46504->172.17.0.8:37005, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:39254") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:60220->172.17.0.8:39254, err= === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:36325") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:34106->172.17.0.8:36325, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:33354") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:55788->172.17.0.8:33354, err= === RUN TestCheckSizeOnly/3 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:34285") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:53062->172.17.0.8:34285, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:39888") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:49620->172.17.0.8:39888, err= === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:38985") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:45872->172.17.0.8:38985, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:37765") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:34018->172.17.0.8:37765, err= === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:35371") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:60136->172.17.0.8:35371, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:32329") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:45524->172.17.0.8:32329, err= --- PASS: TestCheckSizeOnly (0.06s) --- 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:185: Remote "ftp://172.17.0.8:21/rclone-test-satijeh5quye", Local "Local file system at /tmp/rclone2881991198", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:37360") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:47160->172.17.0.8:37360, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:32555") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:46536->172.17.0.8:32555, err= 2025/12/05 01:47:18 NOTICE: test.sum: improperly formatted checksum line 4 2025/12/05 01:47:18 NOTICE: test.sum: improperly formatted checksum line 5 2025/12/05 01:47:18 NOTICE: test.sum: improperly formatted checksum line 6 2025/12/05 01:47:18 NOTICE: test.sum: 2 warning(s) suppressed... 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:32755") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:41432->172.17.0.8:32755, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:33161") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:56650->172.17.0.8:33161, err= 2025/12/05 01:47:18 NOTICE: test.sum: improperly formatted checksum line 4 2025/12/05 01:47:18 NOTICE: test.sum: improperly formatted checksum line 5 2025/12/05 01:47:18 NOTICE: test.sum: improperly formatted checksum line 6 2025/12/05 01:47:18 NOTICE: test.sum: 2 warning(s) suppressed... 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:38700") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:41886->172.17.0.8:38700, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:32662") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:39108->172.17.0.8:32662, err= --- PASS: TestParseSumFile (0.02s) === RUN TestCheckSum run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-satijeh5quye", Local "Local file system at /tmp/rclone2881991198", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-satijeh5quye/data" 2025/12/05 01:47:18 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye/data: Connecting to FTP server 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye/data: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye/data: > dial: conn=172.17.0.1:50368->172.17.0.8:21, err= check_test.go:350: ftp://172.17.0.8:21/rclone-test-satijeh5quye/data lacks md5, skipping 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:33527") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:59504->172.17.0.8:33527, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:37309") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:36080->172.17.0.8:37309, err= --- SKIP: TestCheckSum (0.02s) === RUN TestCheckSumDownload run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-satijeh5quye", Local "Local file system at /tmp/rclone2881991198", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-satijeh5quye/data" 2025/12/05 01:47:18 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye/data: Connecting to FTP server 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye/data: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye/data: > dial: conn=172.17.0.1:50384->172.17.0.8:21, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:39333") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:33170->172.17.0.8:39333, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:35973") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:33352->172.17.0.8:35973, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:36790") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:49016->172.17.0.8:36790, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:38831") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:46530->172.17.0.8:38831, err= === RUN TestCheckSumDownload/subtest1 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:32729") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:45260->172.17.0.8:32729, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:31296") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:44544->172.17.0.8:31296, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:38421") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:38038->172.17.0.8:38421, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:36726") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:58644->172.17.0.8:36726, err= === RUN TestCheckSumDownload/subtest2 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:35327") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:60372->172.17.0.8:35327, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:38747") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:59192->172.17.0.8:38747, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:37978") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:46014->172.17.0.8:37978, err= === RUN TestCheckSumDownload/subtest3 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:38233") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:46386->172.17.0.8:38233, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:30555") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:42758->172.17.0.8:30555, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:32531") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:37790->172.17.0.8:32531, err= === RUN TestCheckSumDownload/subtest4 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:30992") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:44960->172.17.0.8:30992, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:35905") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:42104->172.17.0.8:35905, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:36811") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:36036->172.17.0.8:36811, err= === RUN TestCheckSumDownload/subtest5 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:35516") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:55980->172.17.0.8:35516, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:31502") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:59316->172.17.0.8:31502, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:35336") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:56156->172.17.0.8:35336, err= === RUN TestCheckSumDownload/subtest6 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:30978") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:52284->172.17.0.8:30978, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:33214") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:41048->172.17.0.8:33214, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:32565") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:57618->172.17.0.8:32565, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:34456") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:55616->172.17.0.8:34456, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:33442") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:57552->172.17.0.8:33442, err= === RUN TestCheckSumDownload/subtest7 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:38193") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:51728->172.17.0.8:38193, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:34203") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:50414->172.17.0.8:34203, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: dial("tcp","172.17.0.8:37787") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-satijeh5quye: > dial: conn=172.17.0.1:53400->172.17.0.8:37787, err= --- PASS: TestCheckSumDownload (0.17s) --- PASS: TestCheckSumDownload/subtest1 (0.00s) --- PASS: TestCheckSumDownload/subtest2 (0.00s) --- PASS: TestCheckSumDownload/subtest3 (0.02s) --- PASS: TestCheckSumDownload/subtest4 (0.02s) --- PASS: TestCheckSumDownload/subtest5 (0.01s) --- PASS: TestCheckSumDownload/subtest6 (0.02s) --- PASS: TestCheckSumDownload/subtest7 (0.02s) === RUN TestApplyTransforms 2025/12/05 01:47:18 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-vakonay6gice" 2025/12/05 01:47:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:18 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: Connecting to FTP server 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: > dial: conn=172.17.0.1:50388->172.17.0.8:21, err= 2025/12/05 01:47:18 DEBUG : Creating backend with remote "/tmp/rclone296029501" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-vakonay6gice", Local "Local file system at /tmp/rclone296029501", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: dial("tcp","172.17.0.8:30146") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: > dial: conn=172.17.0.1:52554->172.17.0.8:30146, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: dial("tcp","172.17.0.8:34656") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: > dial: conn=172.17.0.1:39694->172.17.0.8:34656, err= upper checkfile vs. lower remote (without normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: dial("tcp","172.17.0.8:32502") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: > dial: conn=172.17.0.1:60098->172.17.0.8:32502, err= 2025/12/05 01:47:18 ERROR : hello, world!: sum not found 2025/12/05 01:47:18 ERROR : HELLO, WORLD!: file not in ftp://172.17.0.8:21/rclone-test-vakonay6gice 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-vakonay6gice: 1 files missing 2025/12/05 01:47:18 NOTICE: 1 hashes missing 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-vakonay6gice: 1 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-vakonay6gice: 2 errors while checking upper checkfile vs. lower remote (with normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: dial("tcp","172.17.0.8:38468") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: > dial: conn=172.17.0.1:51290->172.17.0.8:38468, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: dial("tcp","172.17.0.8:35573") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-vakonay6gice: > dial: conn=172.17.0.1:43452->172.17.0.8:35573, err= 2025/12/05 01:47:18 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-vakonay6gice: 0 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-vakonay6gice: 1 matching files 2025/12/05 01:47:18 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-sixakub0nocu" 2025/12/05 01:47:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:18 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: Connecting to FTP server 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: > dial: conn=172.17.0.1:50392->172.17.0.8:21, err= 2025/12/05 01:47:18 DEBUG : Creating backend with remote "/tmp/rclone2996788787" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-sixakub0nocu", Local "Local file system at /tmp/rclone2996788787", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: dial("tcp","172.17.0.8:31464") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: > dial: conn=172.17.0.1:53530->172.17.0.8:31464, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: dial("tcp","172.17.0.8:31069") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: > dial: conn=172.17.0.1:41360->172.17.0.8:31069, err= lower checkfile vs. upper remote (without normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: dial("tcp","172.17.0.8:33262") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: > dial: conn=172.17.0.1:41458->172.17.0.8:33262, err= 2025/12/05 01:47:18 ERROR : HELLO, WORLD!: sum not found 2025/12/05 01:47:18 ERROR : hello, world!: file not in ftp://172.17.0.8:21/rclone-test-sixakub0nocu 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-sixakub0nocu: 1 files missing 2025/12/05 01:47:18 NOTICE: 1 hashes missing 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-sixakub0nocu: 1 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-sixakub0nocu: 2 errors while checking lower checkfile vs. upper remote (with normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: dial("tcp","172.17.0.8:33295") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: > dial: conn=172.17.0.1:33788->172.17.0.8:33295, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: dial("tcp","172.17.0.8:35358") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-sixakub0nocu: > dial: conn=172.17.0.1:43106->172.17.0.8:35358, err= 2025/12/05 01:47:18 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-sixakub0nocu: 0 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-sixakub0nocu: 1 matching files 2025/12/05 01:47:18 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-fotiyek7fumo" 2025/12/05 01:47:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:18 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: Connecting to FTP server 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: > dial: conn=172.17.0.1:50402->172.17.0.8:21, err= 2025/12/05 01:47:18 DEBUG : Creating backend with remote "/tmp/rclone1795359529" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-fotiyek7fumo", Local "Local file system at /tmp/rclone1795359529", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: dial("tcp","172.17.0.8:34918") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: > dial: conn=172.17.0.1:36496->172.17.0.8:34918, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: dial("tcp","172.17.0.8:37566") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: > dial: conn=172.17.0.1:45438->172.17.0.8:37566, err= lower checkfile vs. upperlowermixed remote (without normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: dial("tcp","172.17.0.8:35738") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: > dial: conn=172.17.0.1:57660->172.17.0.8:35738, err= 2025/12/05 01:47:18 ERROR : HeLlO, wOrLd!: sum not found 2025/12/05 01:47:18 ERROR : hello, world!: file not in ftp://172.17.0.8:21/rclone-test-fotiyek7fumo 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: 1 files missing 2025/12/05 01:47:18 NOTICE: 1 hashes missing 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: 1 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: dial("tcp","172.17.0.8:30450") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: > dial: conn=172.17.0.1:38052->172.17.0.8:30450, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: dial("tcp","172.17.0.8:37826") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: > dial: conn=172.17.0.1:60358->172.17.0.8:37826, err= 2025/12/05 01:47:18 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: 0 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-fotiyek7fumo: 1 matching files 2025/12/05 01:47:18 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-rewobot3bela" 2025/12/05 01:47:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:18 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: Connecting to FTP server 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: > dial: conn=172.17.0.1:50404->172.17.0.8:21, err= 2025/12/05 01:47:18 DEBUG : Creating backend with remote "/tmp/rclone4053305178" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-rewobot3bela", Local "Local file system at /tmp/rclone4053305178", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: dial("tcp","172.17.0.8:37225") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: > dial: conn=172.17.0.1:50636->172.17.0.8:37225, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: dial("tcp","172.17.0.8:37205") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: > dial: conn=172.17.0.1:52360->172.17.0.8:37205, err= upperlowermixed checkfile vs. upper remote (without normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: dial("tcp","172.17.0.8:38460") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: > dial: conn=172.17.0.1:60246->172.17.0.8:38460, err= 2025/12/05 01:47:18 ERROR : HELLO, WORLD!: sum not found 2025/12/05 01:47:18 ERROR : HeLlO, wOrLd!: file not in ftp://172.17.0.8:21/rclone-test-rewobot3bela 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-rewobot3bela: 1 files missing 2025/12/05 01:47:18 NOTICE: 1 hashes missing 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-rewobot3bela: 1 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-rewobot3bela: 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: dial("tcp","172.17.0.8:31300") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: > dial: conn=172.17.0.1:36838->172.17.0.8:31300, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: dial("tcp","172.17.0.8:36479") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-rewobot3bela: > dial: conn=172.17.0.1:43784->172.17.0.8:36479, err= 2025/12/05 01:47:18 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-rewobot3bela: 0 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-rewobot3bela: 1 matching files 2025/12/05 01:47:18 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-xolivap0toyo" 2025/12/05 01:47:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:18 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: Connecting to FTP server 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: > dial: conn=172.17.0.1:50410->172.17.0.8:21, err= 2025/12/05 01:47:18 DEBUG : Creating backend with remote "/tmp/rclone3044138957" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-xolivap0toyo", Local "Local file system at /tmp/rclone3044138957", Modify Window "1s" 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: dial("tcp","172.17.0.8:39112") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: > dial: conn=172.17.0.1:58676->172.17.0.8:39112, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: dial("tcp","172.17.0.8:37940") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: > dial: conn=172.17.0.1:47020->172.17.0.8:37940, err= NFD checkfile vs. NFC remote (without normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: dial("tcp","172.17.0.8:37772") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: > dial: conn=172.17.0.1:53464->172.17.0.8:37772, err= 2025/12/05 01:47:18 ERROR : 測試_Русский___ě_áñ: sum not found 2025/12/05 01:47:18 ERROR : 測試_Русский___ě_áñ: file not in ftp://172.17.0.8:21/rclone-test-xolivap0toyo 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-xolivap0toyo: 1 files missing 2025/12/05 01:47:18 NOTICE: 1 hashes missing 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-xolivap0toyo: 1 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-xolivap0toyo: 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: dial("tcp","172.17.0.8:39536") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: > dial: conn=172.17.0.1:44258->172.17.0.8:39536, err= 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: dial("tcp","172.17.0.8:34377") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-xolivap0toyo: > dial: conn=172.17.0.1:54394->172.17.0.8:34377, err= 2025/12/05 01:47:18 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-xolivap0toyo: 0 differences found 2025/12/05 01:47:18 NOTICE: ftp://172.17.0.8:21/rclone-test-xolivap0toyo: 1 matching files 2025/12/05 01:47:18 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-wapotof8biju" 2025/12/05 01:47:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:18 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:18 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:18 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:18 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:18 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: Connecting to FTP server 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:18 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: > dial: conn=172.17.0.1:50420->172.17.0.8:21, err= 2025/12/05 01:47:19 DEBUG : Creating backend with remote "/tmp/rclone1826160259" run.go:185: Remote "ftp://172.17.0.8:21/rclone-test-wapotof8biju", Local "Local file system at /tmp/rclone1826160259", Modify Window "1s" 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: dial("tcp","172.17.0.8:31041") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: > dial: conn=172.17.0.1:49328->172.17.0.8:31041, err= 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: dial("tcp","172.17.0.8:39842") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: > dial: conn=172.17.0.1:43448->172.17.0.8:39842, err= NFC checkfile vs. NFD remote (without normalization) 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: dial("tcp","172.17.0.8:35917") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: > dial: conn=172.17.0.1:60220->172.17.0.8:35917, err= 2025/12/05 01:47:19 ERROR : 測試_Русский___ě_áñ: sum not found 2025/12/05 01:47:19 ERROR : 測試_Русский___ě_áñ: file not in ftp://172.17.0.8:21/rclone-test-wapotof8biju 2025/12/05 01:47:19 NOTICE: ftp://172.17.0.8:21/rclone-test-wapotof8biju: 1 files missing 2025/12/05 01:47:19 NOTICE: 1 hashes missing 2025/12/05 01:47:19 NOTICE: ftp://172.17.0.8:21/rclone-test-wapotof8biju: 1 differences found 2025/12/05 01:47:19 NOTICE: ftp://172.17.0.8:21/rclone-test-wapotof8biju: 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: dial("tcp","172.17.0.8:30767") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: > dial: conn=172.17.0.1:59158->172.17.0.8:30767, err= 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: dial("tcp","172.17.0.8:38368") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-wapotof8biju: > dial: conn=172.17.0.1:37246->172.17.0.8:38368, err= 2025/12/05 01:47:19 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2025/12/05 01:47:19 NOTICE: ftp://172.17.0.8:21/rclone-test-wapotof8biju: 0 differences found 2025/12/05 01:47:19 NOTICE: ftp://172.17.0.8:21/rclone-test-wapotof8biju: 1 matching files 2025/12/05 01:47:19 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-gocojus1cufe" 2025/12/05 01:47:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/05 01:47:19 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/12/05 01:47:19 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:19 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:19 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:19 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:19 DEBUG : TestFTPPureftpd: detected overridden config - adding "{hk6ja}" suffix to name 2025/12/05 01:47:19 DEBUG : Setting host="172.17.0.8" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/12/05 01:47:19 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/12/05 01:47:19 DEBUG : Setting pass="cUF9JaqhqCrCtMdb_Vu2JfhHNwdp3VtjTr9bSySXGx0" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/12/05 01:47:19 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: Connecting to FTP server 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50430->172.17.0.8:21, err= 2025/12/05 01:47:19 DEBUG : pacer: low level retry 1/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50444->172.17.0.8:21, err= 2025/12/05 01:47:19 DEBUG : pacer: low level retry 2/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:19 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50454->172.17.0.8:21, err= 2025/12/05 01:47:19 DEBUG : pacer: low level retry 3/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:19 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50460->172.17.0.8:21, err= 2025/12/05 01:47:19 DEBUG : pacer: low level retry 4/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:19 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50470->172.17.0.8:21, err= 2025/12/05 01:47:19 DEBUG : pacer: low level retry 5/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:19 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50494->172.17.0.8:21, err= 2025/12/05 01:47:19 DEBUG : pacer: low level retry 6/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:19 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:19 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50512->172.17.0.8:21, err= 2025/12/05 01:47:19 DEBUG : pacer: low level retry 7/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:19 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/12/05 01:47:20 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:20 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50518->172.17.0.8:21, err= 2025/12/05 01:47:20 DEBUG : pacer: low level retry 8/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:20 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/12/05 01:47:21 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:21 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50542->172.17.0.8:21, err= 2025/12/05 01:47:21 DEBUG : pacer: low level retry 9/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:23 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: dial("tcp","172.17.0.8:21") 2025/12/05 01:47:23 DEBUG : ftp://172.17.0.8:21/rclone-test-gocojus1cufe: > dial: conn=172.17.0.1:50554->172.17.0.8:21, err= 2025/12/05 01:47:23 DEBUG : pacer: low level retry 10/10 (error 421 50 users (the maximum) are already logged in, sorry) 2025/12/05 01:47:23 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 5.361020057s (try 5/5): exit status 1: Failed []