"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose" - Starting (try 1/5) 2026/07/18 02:50:32 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jojelom0toja" 2026/07/18 02:50:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/18 02:50:32 DEBUG : Linkbox root 'rclone-test-jojelom0toja': Using cached web token 2026/07/18 02:50:32 DEBUG : Creating backend with remote "/tmp/rclone1600274633" === 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-jojelom0toja'", Local "Local file system at /tmp/rclone1600274633", Modify Window "876000h0m0s" multithread_test.go:119: multithread writing not supported 2026/07/18 02:50:33 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopy (0.52s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Linkbox root 'rclone-test-jojelom0toja'", Local "Local file system at /tmp/rclone1600274633", Modify Window "876000h0m0s" multithread_test.go:119: multithread writing not supported 2026/07/18 02:50:33 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopyAbort (0.41s) === RUN TestSizeDiffers 2026/07/18 02:50:33 DEBUG : a: size = 0 OK 2026/07/18 02:50:33 DEBUG : a: size = 1 (memory) 2026/07/18 02:50:33 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/07/18 02:50:33 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/07/18 02:50:33 DEBUG : potato: Seek from 10 to 0 2026/07/18 02:50:33 DEBUG : potato: Seek from 10 to 0 2026/07/18 02:50:33 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/07/18 02:50:33 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Seek from 5 to 2 2026/07/18 02:50:33 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/07/18 02:50:33 DEBUG : potato: Seek from 7 to 0 2026/07/18 02:50:33 DEBUG : potato: Seek from 7 to 0 2026/07/18 02:50:33 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/07/18 02:50:33 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Seek from 5 to 2 2026/07/18 02:50:33 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/07/18 02:50:33 DEBUG : potato: Seek from 8 to 0 2026/07/18 02:50:33 DEBUG : potato: Seek from 8 to 0 2026/07/18 02:50:33 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/07/18 02:50:33 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/07/18 02:50:33 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/07/18 02:50:33 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/07/18 02:50:33 DEBUG : potato: Seek from 5 to 2 2026/07/18 02:50:33 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/07/18 02:50:33 DEBUG : potato: Seek from 9 to 0 2026/07/18 02:50:33 DEBUG : potato: Seek from 9 to 0 2026/07/18 02:50:33 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:198: Remote "Linkbox root 'rclone-test-jojelom0toja'", Local "Local file system at /tmp/rclone1600274633", Modify Window "876000h0m0s" === RUN TestCheck/1 === RUN TestCheck/2 2026/07/18 02:50:40 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:40 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/18 02:50:40 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:40 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/18 02:50:40 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:40 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/18 02:50:41 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:41 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/18 02:50:43 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:45 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:47 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:49 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:51 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:53 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:53 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value === NAME TestCheck fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/18 02:50:55 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:50:57 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/18 02:51:00 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/18 02:51:02 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/18 02:51:02 DEBUG : pacer: Reducing sleep to 632.8125ms --- FAIL: TestCheck (28.45s) --- PASS: TestCheck/1 (0.28s) --- PASS: TestCheck/2 (0.21s) === RUN TestCheckFsError 2026/07/18 02:51:02 DEBUG : Creating backend with remote "nonexistent" 2026/07/18 02:51:02 DEBUG : Creating backend with remote "nonexistent" 2026/07/18 02:51:02 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/07/18 02:51:02 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/07/18 02:51:02 NOTICE: Local file system at nonexistent: 0 differences found 2026/07/18 02:51:02 NOTICE: Local file system at nonexistent: 3 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "Linkbox root 'rclone-test-jojelom0toja'", Local "Local file system at /tmp/rclone1600274633", Modify Window "876000h0m0s" 2026/07/18 02:51:03 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/18 02:51:03 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/18 02:51:05 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/18 02:51:05 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/18 02:51:05 DEBUG : pacer: Reducing sleep to 200ms === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (19.74s) --- PASS: TestCheckDownload/1 (0.41s) --- PASS: TestCheckDownload/2 (0.25s) --- PASS: TestCheckDownload/3 (0.23s) --- PASS: TestCheckDownload/4 (0.45s) --- PASS: TestCheckDownload/5 (0.60s) --- PASS: TestCheckDownload/6 (0.45s) --- PASS: TestCheckDownload/7 (0.62s) === RUN TestCheckSizeOnly run.go:198: Remote "Linkbox root 'rclone-test-jojelom0toja'", Local "Local file system at /tmp/rclone1600274633", Modify Window "876000h0m0s" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 check_test.go:86: 5: Expecting 1 errors but got 4 check_test.go:92: 5: Expecting 3 total matching files but got 0 === NAME TestCheckSizeOnly check_test.go:52: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:52 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:56 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:94 Error: Not equal: expected: []string{"", "* empty space", "= potato2", "= rutabaga"} actual : []string{"", "+ empty space", "+ potato2", "+ rutabaga"} Diff: --- Expected +++ Actual @@ -2,5 +2,5 @@ (string) "", - (string) (len=13) "* empty space", - (string) (len=9) "= potato2", - (string) (len=10) "= rutabaga" + (string) (len=13) "+ empty space", + (string) (len=9) "+ potato2", + (string) (len=10) "+ rutabaga" } Test: TestCheckSizeOnly Messages: combined check_test.go:52: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:52 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:58 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:94 Error: Not equal: expected: []string{} actual : []string{"", "empty space", "potato2", "rutabaga"} Diff: --- Expected +++ Actual @@ -1,2 +1,6 @@ -([]string) { +([]string) (len=4) { + (string) "", + (string) (len=11) "empty space", + (string) (len=7) "potato2", + (string) (len=8) "rutabaga" } Test: TestCheckSizeOnly Messages: missingondst check_test.go:52: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:52 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:59 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:94 Error: Not equal: expected: []string{"", "potato2", "rutabaga"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) "", - (string) (len=7) "potato2", - (string) (len=8) "rutabaga" +([]string) { } Test: TestCheckSizeOnly Messages: match check_test.go:52: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:52 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:60 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:94 Error: Not equal: expected: []string{"", "empty space"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=11) "empty space" +([]string) { } Test: TestCheckSizeOnly Messages: differ 2026/07/18 02:51:44 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:51:46 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:51:48 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:51:50 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:51:52 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:51:54 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:51:56 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/18 02:51:58 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/18 02:52:01 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/18 02:52:01 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/18 02:52:02 DEBUG : pacer: Reducing sleep to 474.609375ms === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 2026/07/18 02:52:03 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/18 02:52:05 DEBUG : pacer: Reducing sleep to 200ms --- FAIL: TestCheckSizeOnly (47.40s) --- PASS: TestCheckSizeOnly/1 (0.20s) --- PASS: TestCheckSizeOnly/2 (0.20s) --- PASS: TestCheckSizeOnly/3 (0.22s) --- PASS: TestCheckSizeOnly/4 (0.20s) --- FAIL: TestCheckSizeOnly/5 (13.04s) --- PASS: TestCheckSizeOnly/6 (0.63s) --- PASS: TestCheckSizeOnly/7 (0.48s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Linkbox root 'rclone-test-jojelom0toja'", Local "Local file system at /tmp/rclone1600274633", Modify Window "876000h0m0s" 2026/07/18 02:52:11 NOTICE: test.sum: improperly formatted checksum line 4 2026/07/18 02:52:11 NOTICE: test.sum: improperly formatted checksum line 5 2026/07/18 02:52:11 NOTICE: test.sum: improperly formatted checksum line 6 2026/07/18 02:52:11 NOTICE: test.sum: 2 warning(s) suppressed... 2026/07/18 02:52:12 DEBUG : test.sum: Update: removing old file 2026/07/18 02:52:15 NOTICE: test.sum: improperly formatted checksum line 4 2026/07/18 02:52:15 NOTICE: test.sum: improperly formatted checksum line 5 2026/07/18 02:52:15 NOTICE: test.sum: improperly formatted checksum line 6 2026/07/18 02:52:15 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (8.00s) === RUN TestCheckSum run.go:198: Remote "Linkbox root 'rclone-test-jojelom0toja'", Local "Local file system at /tmp/rclone1600274633", Modify Window "876000h0m0s" 2026/07/18 02:52:17 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jojelom0toja/data" 2026/07/18 02:52:17 DEBUG : Linkbox root 'rclone-test-jojelom0toja/data': Using cached web token check_test.go:350: Linkbox root 'rclone-test-jojelom0toja/data' lacks md5, skipping --- SKIP: TestCheckSum (1.85s) === RUN TestCheckSumDownload run.go:198: Remote "Linkbox root 'rclone-test-jojelom0toja'", Local "Local file system at /tmp/rclone1600274633", Modify Window "876000h0m0s" 2026/07/18 02:52:19 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jojelom0toja/data" 2026/07/18 02:52:19 DEBUG : Linkbox root 'rclone-test-jojelom0toja/data': Using cached web token 2026/07/18 02:52:25 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) === RUN TestCheckSumDownload/subtest1 2026/07/18 02:52:29 DEBUG : test.sum: Update: removing old file 2026/07/18 02:52:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:29 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/18 02:52:29 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:29 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/18 02:52:30 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:30 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/18 02:52:30 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:30 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/18 02:52:32 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:34 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:36 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:38 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:40 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:42 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:42 ERROR : test.sum: Update: failed to remove existing file: could not Remove: invalid character '<' looking for beginning of value 2026/07/18 02:52:44 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:46 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:48 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:50 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:52 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:54 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:52:56 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/18 02:52:59 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/18 02:53:00 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/18 02:53:01 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/18 02:53:02 DEBUG : pacer: Reducing sleep to 474.609375ms === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/18 02:53:03 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/18 02:53:03 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/18 02:53:04 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/07/18 02:53:06 DEBUG : pacer: Reducing sleep to 200ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:450 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (41) got data/banana (13), data/potato (15), test(1).sum (41), test.sum (41) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:450 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" === RUN TestCheckSumDownload/subtest2 2026/07/18 02:53:12 DEBUG : test.sum: Update: removing old file === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:464 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (82) got data/banana (13), data/potato (15), test(1).sum (41), test.sum (82) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:464 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" === RUN TestCheckSumDownload/subtest3 2026/07/18 02:53:25 DEBUG : test.sum: Update: removing old file === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:478 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (82) got data/banana (13), data/potato (15), test(1).sum (41), test.sum (82) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:478 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" === RUN TestCheckSumDownload/subtest4 === NAME TestCheckSumDownload check_test.go:409: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:409 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: 1 actual : 0 Test: TestCheckSumDownload Messages: wrong error count in run 4 check_test.go:413: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:413 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: "" does not contain "matching files" Test: TestCheckSumDownload Messages: missing matching files in run 4 check_test.go:415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:415 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: 2 actual : 0 Test: TestCheckSumDownload Messages: wrong number of checks in run 4 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:417 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "* banana", "= potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) "", - (string) (len=8) "* banana", - (string) (len=8) "= potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong combined result in run 4 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:420 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong match result in run 4 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "banana"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "banana" +([]string) { } Test: TestCheckSumDownload Messages: wrong differ result in run 4 2026/07/18 02:53:53 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:53:55 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:53:57 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/18 02:53:59 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/18 02:53:59 DEBUG : test.sum: Update: removing old file 2026/07/18 02:54:01 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/18 02:54:02 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/18 02:54:03 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/18 02:54:04 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/18 02:54:04 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/18 02:54:05 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/18 02:54:06 DEBUG : pacer: Reducing sleep to 200ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (123) got data/banana (13), data/potato (15), test(1).sum (41), test.sum (123) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" === RUN TestCheckSumDownload/subtest5 2026/07/18 02:54:14 DEBUG : test.sum: Update: removing old file === NAME TestCheckSumDownload fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:508 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (123) got data/banana (13), data/potato (15), test(1).sum (41), test.sum (123) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:508 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" === RUN TestCheckSumDownload/subtest6 2026/07/18 02:54:28 DEBUG : data/banana: Update: removing old file 2026/07/18 02:54:32 DEBUG : data/potato: Update: removing old file 2026/07/18 02:54:35 DEBUG : test.sum: Update: removing old file 2026/07/18 02:54:38 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:38 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/18 02:54:39 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:39 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/18 02:54:39 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:39 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/18 02:54:40 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:40 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/18 02:54:41 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:43 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:45 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:47 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:50 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:51 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:51 ERROR : data: error listing: getting files failed: invalid character '<' looking for beginning of value === NAME TestCheckSumDownload fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/18 02:54:53 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:55 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:54:58 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/18 02:55:01 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/18 02:55:02 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/18 02:55:02 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/18 02:55:04 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/18 02:55:06 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/18 02:55:06 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/18 02:55:07 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/18 02:55:08 DEBUG : pacer: Reducing sleep to 200ms --- FAIL: TestCheckSumDownload (168.90s) --- PASS: TestCheckSumDownload/subtest1 (0.73s) --- PASS: TestCheckSumDownload/subtest2 (1.01s) --- PASS: TestCheckSumDownload/subtest3 (1.08s) --- PASS: TestCheckSumDownload/subtest4 (13.04s) --- PASS: TestCheckSumDownload/subtest5 (1.13s) --- PASS: TestCheckSumDownload/subtest6 (0.97s) === RUN TestApplyTransforms 2026/07/18 02:55:08 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-xanidos8raco" 2026/07/18 02:55:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/18 02:55:08 DEBUG : Linkbox root 'rclone-test-xanidos8raco': Using cached web token 2026/07/18 02:55:09 DEBUG : Creating backend with remote "/tmp/rclone4208744789" run.go:198: Remote "Linkbox root 'rclone-test-xanidos8raco'", Local "Local file system at /tmp/rclone4208744789", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/07/18 02:55:13 ERROR : hello, world!: sum not found 2026/07/18 02:55:13 ERROR : HELLO, WORLD!: file not in Linkbox root 'rclone-test-xanidos8raco' 2026/07/18 02:55:13 NOTICE: Linkbox root 'rclone-test-xanidos8raco': 1 files missing 2026/07/18 02:55:13 NOTICE: 1 hashes missing 2026/07/18 02:55:13 NOTICE: Linkbox root 'rclone-test-xanidos8raco': 1 differences found 2026/07/18 02:55:13 NOTICE: Linkbox root 'rclone-test-xanidos8raco': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/07/18 02:55:13 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/18 02:55:13 NOTICE: Linkbox root 'rclone-test-xanidos8raco': 0 differences found 2026/07/18 02:55:13 NOTICE: Linkbox root 'rclone-test-xanidos8raco': 1 matching files 2026/07/18 02:55:13 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dozepaw7faji" 2026/07/18 02:55:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/18 02:55:13 DEBUG : Linkbox root 'rclone-test-dozepaw7faji': Using cached web token 2026/07/18 02:55:14 DEBUG : Creating backend with remote "/tmp/rclone977408991" run.go:198: Remote "Linkbox root 'rclone-test-dozepaw7faji'", Local "Local file system at /tmp/rclone977408991", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/07/18 02:55:18 ERROR : HELLO, WORLD!: sum not found 2026/07/18 02:55:18 ERROR : hello, world!: file not in Linkbox root 'rclone-test-dozepaw7faji' 2026/07/18 02:55:18 NOTICE: Linkbox root 'rclone-test-dozepaw7faji': 1 files missing 2026/07/18 02:55:18 NOTICE: 1 hashes missing 2026/07/18 02:55:18 NOTICE: Linkbox root 'rclone-test-dozepaw7faji': 1 differences found 2026/07/18 02:55:18 NOTICE: Linkbox root 'rclone-test-dozepaw7faji': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/07/18 02:55:18 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/18 02:55:18 NOTICE: Linkbox root 'rclone-test-dozepaw7faji': 0 differences found 2026/07/18 02:55:18 NOTICE: Linkbox root 'rclone-test-dozepaw7faji': 1 matching files 2026/07/18 02:55:18 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jifukiq6bixi" 2026/07/18 02:55:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/18 02:55:18 DEBUG : Linkbox root 'rclone-test-jifukiq6bixi': Using cached web token 2026/07/18 02:55:19 DEBUG : Creating backend with remote "/tmp/rclone3494808650" run.go:198: Remote "Linkbox root 'rclone-test-jifukiq6bixi'", Local "Local file system at /tmp/rclone3494808650", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/07/18 02:55:23 ERROR : HeLlO, wOrLd!: sum not found 2026/07/18 02:55:23 ERROR : hello, world!: file not in Linkbox root 'rclone-test-jifukiq6bixi' 2026/07/18 02:55:23 NOTICE: Linkbox root 'rclone-test-jifukiq6bixi': 1 files missing 2026/07/18 02:55:23 NOTICE: 1 hashes missing 2026/07/18 02:55:23 NOTICE: Linkbox root 'rclone-test-jifukiq6bixi': 1 differences found 2026/07/18 02:55:23 NOTICE: Linkbox root 'rclone-test-jifukiq6bixi': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/07/18 02:55:24 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/18 02:55:24 NOTICE: Linkbox root 'rclone-test-jifukiq6bixi': 0 differences found 2026/07/18 02:55:24 NOTICE: Linkbox root 'rclone-test-jifukiq6bixi': 1 matching files 2026/07/18 02:55:24 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-wujefug1gaxo" 2026/07/18 02:55:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/18 02:55:24 DEBUG : Linkbox root 'rclone-test-wujefug1gaxo': Using cached web token 2026/07/18 02:55:25 DEBUG : Creating backend with remote "/tmp/rclone4080494224" run.go:198: Remote "Linkbox root 'rclone-test-wujefug1gaxo'", Local "Local file system at /tmp/rclone4080494224", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/07/18 02:55:29 ERROR : HELLO, WORLD!: sum not found 2026/07/18 02:55:29 ERROR : HeLlO, wOrLd!: file not in Linkbox root 'rclone-test-wujefug1gaxo' 2026/07/18 02:55:29 NOTICE: Linkbox root 'rclone-test-wujefug1gaxo': 1 files missing 2026/07/18 02:55:29 NOTICE: 1 hashes missing 2026/07/18 02:55:29 NOTICE: Linkbox root 'rclone-test-wujefug1gaxo': 1 differences found 2026/07/18 02:55:29 NOTICE: Linkbox root 'rclone-test-wujefug1gaxo': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/07/18 02:55:29 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/18 02:55:29 NOTICE: Linkbox root 'rclone-test-wujefug1gaxo': 0 differences found 2026/07/18 02:55:29 NOTICE: Linkbox root 'rclone-test-wujefug1gaxo': 1 matching files 2026/07/18 02:55:29 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-geqopog4ruco" 2026/07/18 02:55:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/18 02:55:29 DEBUG : Linkbox root 'rclone-test-geqopog4ruco': Using cached web token 2026/07/18 02:55:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:30 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/18 02:55:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:30 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/18 02:55:30 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:30 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/18 02:55:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/18 02:55:33 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:35 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:37 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:39 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:41 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:43 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/18 02:55:43 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 5m11.124419348s (try 1/5): exit status 1: Failed [TestCheck TestCheckSizeOnly/5 TestCheckSumDownload]