"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose" - Starting (try 1/5) 2026/07/19 01:51:05 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kenuwer4tuwi" 2026/07/19 01:51:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/19 01:51:05 DEBUG : Linkbox root 'rclone-test-kenuwer4tuwi': Using cached web token 2026/07/19 01:51:06 DEBUG : Creating backend with remote "/tmp/rclone3554254983" === 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 "Linkbox root 'rclone-test-kenuwer4tuwi'", Local "Local file system at /tmp/rclone3554254983", Modify Window "876000h0m0s" multithread_test.go:119: multithread writing not supported 2026/07/19 01:51:06 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopy (0.25s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Linkbox root 'rclone-test-kenuwer4tuwi'", Local "Local file system at /tmp/rclone3554254983", Modify Window "876000h0m0s" multithread_test.go:119: multithread writing not supported 2026/07/19 01:51:07 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopyAbort (0.25s) === RUN TestSizeDiffers 2026/07/19 01:51:07 DEBUG : a: size = 0 OK 2026/07/19 01:51:07 DEBUG : a: size = 1 (memory) 2026/07/19 01:51:07 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/07/19 01:51:07 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/07/19 01:51:07 DEBUG : potato: Seek from 10 to 0 2026/07/19 01:51:07 DEBUG : potato: Seek from 10 to 0 2026/07/19 01:51:07 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/07/19 01:51:07 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Seek from 5 to 2 2026/07/19 01:51:07 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/07/19 01:51:07 DEBUG : potato: Seek from 7 to 0 2026/07/19 01:51:07 DEBUG : potato: Seek from 7 to 0 2026/07/19 01:51:07 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/07/19 01:51:07 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Seek from 5 to 2 2026/07/19 01:51:07 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/07/19 01:51:07 DEBUG : potato: Seek from 8 to 0 2026/07/19 01:51:07 DEBUG : potato: Seek from 8 to 0 2026/07/19 01:51:07 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/07/19 01:51:07 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/07/19 01:51:07 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/19 01:51:07 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/19 01:51:07 DEBUG : potato: Seek from 5 to 2 2026/07/19 01:51:07 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/07/19 01:51:07 DEBUG : potato: Seek from 9 to 0 2026/07/19 01:51:07 DEBUG : potato: Seek from 9 to 0 2026/07/19 01:51:07 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.01s) --- 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.01s) --- 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 "Linkbox root 'rclone-test-kenuwer4tuwi'", Local "Local file system at /tmp/rclone3554254983", Modify Window "876000h0m0s" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (17.80s) --- PASS: TestCheck/1 (0.20s) --- PASS: TestCheck/2 (0.20s) --- PASS: TestCheck/3 (0.21s) --- PASS: TestCheck/4 (0.20s) --- PASS: TestCheck/5 (0.20s) --- PASS: TestCheck/6 (0.22s) --- PASS: TestCheck/7 (0.20s) === RUN TestCheckFsError 2026/07/19 01:51:25 DEBUG : Creating backend with remote "nonexistent" 2026/07/19 01:51:25 DEBUG : Creating backend with remote "nonexistent" 2026/07/19 01:51:25 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/07/19 01:51:25 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/07/19 01:51:25 NOTICE: Local file system at nonexistent: 0 differences found 2026/07/19 01:51:25 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "Linkbox root 'rclone-test-kenuwer4tuwi'", Local "Local file system at /tmp/rclone3554254983", Modify Window "876000h0m0s" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/07/19 01:51:37 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:37 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/19 01:51:37 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:37 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/19 01:51:37 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:37 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/19 01:51:38 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:38 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/19 01:51:40 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:42 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:44 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:46 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:48 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:50 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:51:50 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value === NAME TestCheckDownload fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/19 01:51:52 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/19 01:51:55 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/19 01:51:56 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/19 01:51:58 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/19 01:51:59 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/19 01:51:59 DEBUG : pacer: Reducing sleep to 355.957031ms --- FAIL: TestCheckDownload (34.67s) --- PASS: TestCheckDownload/1 (1.14s) --- PASS: TestCheckDownload/2 (0.23s) --- PASS: TestCheckDownload/3 (0.23s) --- PASS: TestCheckDownload/4 (0.48s) --- PASS: TestCheckDownload/5 (0.60s) === RUN TestCheckSizeOnly run.go:198: Remote "Linkbox root 'rclone-test-kenuwer4tuwi'", Local "Local file system at /tmp/rclone3554254983", Modify Window "876000h0m0s" 2026/07/19 01:52:00 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/19 01:52:00 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/19 01:52:01 DEBUG : pacer: Reducing sleep to 200ms === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (16.47s) --- PASS: TestCheckSizeOnly/1 (0.21s) --- PASS: TestCheckSizeOnly/2 (0.20s) --- PASS: TestCheckSizeOnly/3 (0.22s) --- PASS: TestCheckSizeOnly/4 (0.21s) --- PASS: TestCheckSizeOnly/5 (0.20s) --- PASS: TestCheckSizeOnly/6 (0.20s) --- PASS: TestCheckSizeOnly/7 (0.21s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.01s) === RUN TestParseSumFile run.go:198: Remote "Linkbox root 'rclone-test-kenuwer4tuwi'", Local "Local file system at /tmp/rclone3554254983", Modify Window "876000h0m0s" 2026/07/19 01:52:18 NOTICE: test.sum: improperly formatted checksum line 4 2026/07/19 01:52:18 NOTICE: test.sum: improperly formatted checksum line 5 2026/07/19 01:52:18 NOTICE: test.sum: improperly formatted checksum line 6 2026/07/19 01:52:18 NOTICE: test.sum: 2 warning(s) suppressed... 2026/07/19 01:52:18 DEBUG : test.sum: Update: removing old file 2026/07/19 01:52:22 NOTICE: test.sum: improperly formatted checksum line 4 2026/07/19 01:52:22 NOTICE: test.sum: improperly formatted checksum line 5 2026/07/19 01:52:22 NOTICE: test.sum: improperly formatted checksum line 6 2026/07/19 01:52:22 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (7.94s) === RUN TestCheckSum run.go:198: Remote "Linkbox root 'rclone-test-kenuwer4tuwi'", Local "Local file system at /tmp/rclone3554254983", Modify Window "876000h0m0s" 2026/07/19 01:52:24 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kenuwer4tuwi/data" 2026/07/19 01:52:24 DEBUG : Config file has changed externally - reloading 2026/07/19 01:52:24 DEBUG : Linkbox root 'rclone-test-kenuwer4tuwi/data': Using cached web token check_test.go:350: Linkbox root 'rclone-test-kenuwer4tuwi/data' lacks md5, skipping --- SKIP: TestCheckSum (1.56s) === RUN TestCheckSumDownload run.go:198: Remote "Linkbox root 'rclone-test-kenuwer4tuwi'", Local "Local file system at /tmp/rclone3554254983", Modify Window "876000h0m0s" 2026/07/19 01:52:25 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-kenuwer4tuwi/data" 2026/07/19 01:52:25 DEBUG : Linkbox root 'rclone-test-kenuwer4tuwi/data': Using cached web token 2026/07/19 01:52:26 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:26 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/19 01:52:26 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:26 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/19 01:52:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/19 01:52:28 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/19 01:52:29 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:31 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:33 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:35 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:37 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:39 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) check_test.go:347: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:347 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Received unexpected error: getting files failed: invalid character '<' looking for beginning of value Test: TestCheckSumDownload 2026/07/19 01:52:39 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:39 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/19 01:52:39 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:39 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/19 01:52:40 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:40 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/19 01:52:41 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:41 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/19 01:52:42 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:44 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:46 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:48 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:50 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:52:52 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/19 01:52:54 DEBUG : pacer: Reducing sleep to 1.125s --- FAIL: TestCheckSumDownload (29.23s) === RUN TestApplyTransforms 2026/07/19 01:52:54 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-pubugiz1civa" 2026/07/19 01:52:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/19 01:52:54 DEBUG : Linkbox root 'rclone-test-pubugiz1civa': Using cached web token 2026/07/19 01:52:55 DEBUG : Creating backend with remote "/tmp/rclone3474104312" run.go:198: Remote "Linkbox root 'rclone-test-pubugiz1civa'", Local "Local file system at /tmp/rclone3474104312", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/07/19 01:52:59 ERROR : hello, world!: sum not found 2026/07/19 01:52:59 ERROR : HELLO, WORLD!: file not in Linkbox root 'rclone-test-pubugiz1civa' 2026/07/19 01:52:59 NOTICE: Linkbox root 'rclone-test-pubugiz1civa': 1 files missing 2026/07/19 01:52:59 NOTICE: 1 hashes missing 2026/07/19 01:52:59 NOTICE: Linkbox root 'rclone-test-pubugiz1civa': 1 differences found 2026/07/19 01:52:59 NOTICE: Linkbox root 'rclone-test-pubugiz1civa': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/07/19 01:52:59 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/19 01:52:59 NOTICE: Linkbox root 'rclone-test-pubugiz1civa': 0 differences found 2026/07/19 01:52:59 NOTICE: Linkbox root 'rclone-test-pubugiz1civa': 1 matching files 2026/07/19 01:52:59 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-lawajut0butu" 2026/07/19 01:52:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/19 01:52:59 DEBUG : Linkbox root 'rclone-test-lawajut0butu': Using cached web token 2026/07/19 01:53:00 DEBUG : Creating backend with remote "/tmp/rclone516010879" run.go:198: Remote "Linkbox root 'rclone-test-lawajut0butu'", Local "Local file system at /tmp/rclone516010879", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/07/19 01:53:04 ERROR : HELLO, WORLD!: sum not found 2026/07/19 01:53:04 ERROR : hello, world!: file not in Linkbox root 'rclone-test-lawajut0butu' 2026/07/19 01:53:04 NOTICE: Linkbox root 'rclone-test-lawajut0butu': 1 files missing 2026/07/19 01:53:04 NOTICE: 1 hashes missing 2026/07/19 01:53:04 NOTICE: Linkbox root 'rclone-test-lawajut0butu': 1 differences found 2026/07/19 01:53:04 NOTICE: Linkbox root 'rclone-test-lawajut0butu': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/07/19 01:53:05 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/19 01:53:05 NOTICE: Linkbox root 'rclone-test-lawajut0butu': 0 differences found 2026/07/19 01:53:05 NOTICE: Linkbox root 'rclone-test-lawajut0butu': 1 matching files 2026/07/19 01:53:05 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-ralibes6gure" 2026/07/19 01:53:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/19 01:53:05 DEBUG : Linkbox root 'rclone-test-ralibes6gure': Using cached web token 2026/07/19 01:53:06 DEBUG : Creating backend with remote "/tmp/rclone1694226377" run.go:198: Remote "Linkbox root 'rclone-test-ralibes6gure'", Local "Local file system at /tmp/rclone1694226377", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/07/19 01:53:10 ERROR : HeLlO, wOrLd!: sum not found 2026/07/19 01:53:10 ERROR : hello, world!: file not in Linkbox root 'rclone-test-ralibes6gure' 2026/07/19 01:53:10 NOTICE: Linkbox root 'rclone-test-ralibes6gure': 1 files missing 2026/07/19 01:53:10 NOTICE: 1 hashes missing 2026/07/19 01:53:10 NOTICE: Linkbox root 'rclone-test-ralibes6gure': 1 differences found 2026/07/19 01:53:10 NOTICE: Linkbox root 'rclone-test-ralibes6gure': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/07/19 01:53:11 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/19 01:53:11 NOTICE: Linkbox root 'rclone-test-ralibes6gure': 0 differences found 2026/07/19 01:53:11 NOTICE: Linkbox root 'rclone-test-ralibes6gure': 1 matching files 2026/07/19 01:53:11 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-xerefon9face" 2026/07/19 01:53:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/19 01:53:11 DEBUG : Linkbox root 'rclone-test-xerefon9face': Using cached web token 2026/07/19 01:53:11 DEBUG : Creating backend with remote "/tmp/rclone1861813577" run.go:198: Remote "Linkbox root 'rclone-test-xerefon9face'", Local "Local file system at /tmp/rclone1861813577", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/07/19 01:53:16 ERROR : HELLO, WORLD!: sum not found 2026/07/19 01:53:16 ERROR : HeLlO, wOrLd!: file not in Linkbox root 'rclone-test-xerefon9face' 2026/07/19 01:53:16 NOTICE: Linkbox root 'rclone-test-xerefon9face': 1 files missing 2026/07/19 01:53:16 NOTICE: 1 hashes missing 2026/07/19 01:53:16 NOTICE: Linkbox root 'rclone-test-xerefon9face': 1 differences found 2026/07/19 01:53:16 NOTICE: Linkbox root 'rclone-test-xerefon9face': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/07/19 01:53:16 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/19 01:53:16 NOTICE: Linkbox root 'rclone-test-xerefon9face': 0 differences found 2026/07/19 01:53:16 NOTICE: Linkbox root 'rclone-test-xerefon9face': 1 matching files 2026/07/19 01:53:16 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-momixum3laga" 2026/07/19 01:53:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/19 01:53:16 DEBUG : Linkbox root 'rclone-test-momixum3laga': Using cached web token 2026/07/19 01:53:16 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:16 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/19 01:53:17 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:17 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/19 01:53:17 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:17 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/19 01:53:18 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:18 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/19 01:53:19 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:21 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:23 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:26 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:27 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:29 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/19 01:53:29 NOTICE: Failed to open remote "TestLinkbox:": getting files failed: invalid character '<' looking for beginning of value "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose" - Finished ERROR in 2m24.080212946s (try 1/5): exit status 1: Failed [TestCheckDownload TestCheckSumDownload]