"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose" - Starting (try 1/5) 2026/09/01 01:26:43 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-geyifus5cupi" 2026/09/01 01:26:43 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/01 01:26:43 DEBUG : Linkbox root 'rclone-test-geyifus5cupi': Using cached web token 2026/09/01 01:26:44 DEBUG : Creating backend with remote "/tmp/rclone2560918340" 2026/09/01 01:26:44 DEBUG : Config file has changed externally - reloading === 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-geyifus5cupi'", Local "Local file system at /tmp/rclone2560918340", Modify Window "876000h0m0s" multithread_test.go:119: multithread writing not supported 2026/09/01 01:26:44 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopy (0.36s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Linkbox root 'rclone-test-geyifus5cupi'", Local "Local file system at /tmp/rclone2560918340", Modify Window "876000h0m0s" multithread_test.go:119: multithread writing not supported 2026/09/01 01:26:44 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopyAbort (0.37s) === RUN TestSizeDiffers 2026/09/01 01:26:44 DEBUG : a: size = 0 OK 2026/09/01 01:26:44 DEBUG : a: size = 1 (memory) 2026/09/01 01:26:44 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/09/01 01:26:44 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/09/01 01:26:44 DEBUG : potato: Seek from 10 to 0 2026/09/01 01:26:44 DEBUG : potato: Seek from 10 to 0 2026/09/01 01:26:44 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/09/01 01:26:44 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Seek from 5 to 2 2026/09/01 01:26:44 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/09/01 01:26:44 DEBUG : potato: Seek from 7 to 0 2026/09/01 01:26:44 DEBUG : potato: Seek from 7 to 0 2026/09/01 01:26:44 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/09/01 01:26:44 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Seek from 5 to 2 2026/09/01 01:26:44 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/09/01 01:26:44 DEBUG : potato: Seek from 8 to 0 2026/09/01 01:26:44 DEBUG : potato: Seek from 8 to 0 2026/09/01 01:26:44 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/09/01 01:26:44 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/01 01:26:44 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/01 01:26:44 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/01 01:26:44 DEBUG : potato: Seek from 5 to 2 2026/09/01 01:26:44 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/09/01 01:26:44 DEBUG : potato: Seek from 9 to 0 2026/09/01 01:26:44 DEBUG : potato: Seek from 9 to 0 2026/09/01 01:26:44 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:198: Remote "Linkbox root 'rclone-test-geyifus5cupi'", Local "Local file system at /tmp/rclone2560918340", Modify Window "876000h0m0s" === RUN TestCheck/1 === RUN TestCheck/2 === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 2026/09/01 01:27:00 DEBUG : pacer: Reducing sleep to 900ms 2026/09/01 01:27:02 DEBUG : pacer: Reducing sleep to 675ms 2026/09/01 01:27:03 DEBUG : pacer: Reducing sleep to 506.25ms 2026/09/01 01:27:05 DEBUG : pacer: Reducing sleep to 379.6875ms 2026/09/01 01:27:06 DEBUG : pacer: Reducing sleep to 284.765625ms 2026/09/01 01:27:06 DEBUG : pacer: Reducing sleep to 213.574218ms --- PASS: TestCheck (21.60s) --- PASS: TestCheck/1 (0.21s) --- PASS: TestCheck/2 (0.20s) --- PASS: TestCheck/3 (0.20s) --- PASS: TestCheck/4 (0.22s) --- PASS: TestCheck/5 (0.20s) --- PASS: TestCheck/6 (0.20s) --- PASS: TestCheck/7 (1.61s) === RUN TestCheckFsError 2026/09/01 01:27:06 DEBUG : Creating backend with remote "nonexistent" 2026/09/01 01:27:06 DEBUG : Creating backend with remote "nonexistent" 2026/09/01 01:27:06 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/09/01 01:27:06 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/09/01 01:27:06 NOTICE: Local file system at nonexistent: 0 differences found 2026/09/01 01:27:06 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "Linkbox root 'rclone-test-geyifus5cupi'", Local "Local file system at /tmp/rclone2560918340", Modify Window "876000h0m0s" 2026/09/01 01:27:06 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 (18.35s) --- PASS: TestCheckDownload/1 (0.39s) --- PASS: TestCheckDownload/2 (0.30s) --- PASS: TestCheckDownload/3 (0.32s) --- PASS: TestCheckDownload/4 (0.45s) --- PASS: TestCheckDownload/5 (0.59s) --- PASS: TestCheckDownload/6 (0.44s) --- PASS: TestCheckDownload/7 (0.60s) === RUN TestCheckSizeOnly run.go:198: Remote "Linkbox root 'rclone-test-geyifus5cupi'", Local "Local file system at /tmp/rclone2560918340", Modify Window "876000h0m0s" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 2026/09/01 01:27:31 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:31 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/01 01:27:32 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:32 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/09/01 01:27:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:32 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/09/01 01:27:33 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:33 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/09/01 01:27:34 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:36 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:38 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:40 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:42 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:44 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) === NAME TestCheckSizeOnly run.go:299: Retry Put of "potato2" to Linkbox root 'rclone-test-geyifus5cupi': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/09/01 01:27:46 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:48 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:50 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:52 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:54 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:56 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:27:59 DEBUG : pacer: Reducing sleep to 1.5s 2026/09/01 01:27:59 DEBUG : potato2: Update: removing old file 2026/09/01 01:28:02 DEBUG : pacer: Reducing sleep to 1.125s 2026/09/01 01:28:02 DEBUG : pacer: Reducing sleep to 843.75ms 2026/09/01 01:28:04 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/09/01 01:28:04 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/09/01 01:28:05 DEBUG : pacer: Reducing sleep to 355.957031ms === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/09/01 01:28:06 DEBUG : pacer: Reducing sleep to 200ms === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (49.91s) --- PASS: TestCheckSizeOnly/1 (0.21s) --- PASS: TestCheckSizeOnly/2 (0.21s) --- PASS: TestCheckSizeOnly/3 (0.21s) --- PASS: TestCheckSizeOnly/4 (0.48s) --- PASS: TestCheckSizeOnly/5 (0.35s) --- PASS: TestCheckSizeOnly/6 (0.21s) --- PASS: TestCheckSizeOnly/7 (0.21s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Linkbox root 'rclone-test-geyifus5cupi'", Local "Local file system at /tmp/rclone2560918340", Modify Window "876000h0m0s" 2026/09/01 01:28:17 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/01 01:28:17 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/01 01:28:17 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/01 01:28:17 NOTICE: test.sum: 2 warning(s) suppressed... 2026/09/01 01:28:17 DEBUG : test.sum: Update: removing old file 2026/09/01 01:28:20 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/01 01:28:20 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/01 01:28:20 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/01 01:28:20 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (7.88s) === RUN TestCheckSum run.go:198: Remote "Linkbox root 'rclone-test-geyifus5cupi'", Local "Local file system at /tmp/rclone2560918340", Modify Window "876000h0m0s" 2026/09/01 01:28:22 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-geyifus5cupi/data" 2026/09/01 01:28:22 DEBUG : Config file has changed externally - reloading 2026/09/01 01:28:22 DEBUG : Linkbox root 'rclone-test-geyifus5cupi/data': Using cached web token check_test.go:354: Linkbox root 'rclone-test-geyifus5cupi/data' lacks md5, skipping --- SKIP: TestCheckSum (1.75s) === RUN TestCheckSumDownload run.go:198: Remote "Linkbox root 'rclone-test-geyifus5cupi'", Local "Local file system at /tmp/rclone2560918340", Modify Window "876000h0m0s" 2026/09/01 01:28:24 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-geyifus5cupi/data" 2026/09/01 01:28:24 DEBUG : Linkbox root 'rclone-test-geyifus5cupi/data': Using cached web token === RUN TestCheckSumDownload/subtest1 2026/09/01 01:28:34 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest2 === NAME TestCheckSumDownload 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:432 Error: Not equal: expected: 1 actual : 0 Test: TestCheckSumDownload Messages: wrong error count in run 2 check_test.go:417: Error Trace: /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:432 Error: "" does not contain "matching files" Test: TestCheckSumDownload Messages: missing matching files in run 2 check_test.go:419: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:419 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: 2 actual : 0 Test: TestCheckSumDownload Messages: wrong number of checks in run 2 check_test.go:388: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:388 /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:432 Error: Not equal: expected: []string{"", "- potato", "= banana"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) "", - (string) (len=8) "- potato", - (string) (len=8) "= banana" +([]string) { } Test: TestCheckSumDownload Messages: wrong combined result in run 2 check_test.go:388: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:388 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:422 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 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 missingonsrc result in run 2 check_test.go:388: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:388 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:424 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 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 match result in run 2 2026/09/01 01:28:53 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:28:55 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:28:57 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:28:59 DEBUG : pacer: Reducing sleep to 1.5s 2026/09/01 01:28:59 DEBUG : test.sum: Update: removing old file 2026/09/01 01:29:02 DEBUG : pacer: Reducing sleep to 1.125s 2026/09/01 01:29:03 DEBUG : pacer: Reducing sleep to 843.75ms 2026/09/01 01:29:05 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/09/01 01:29:05 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/09/01 01:29:06 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/09/01 01:29:06 DEBUG : pacer: Reducing sleep to 266.967773ms === RUN TestCheckSumDownload/subtest3 2026/09/01 01:29:07 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest4 2026/09/01 01:29:12 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest5 2026/09/01 01:29:16 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest6 2026/09/01 01:29:21 DEBUG : data/banana: Update: removing old file 2026/09/01 01:29:24 DEBUG : data/potato: Update: removing old file 2026/09/01 01:29:27 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest7 2026/09/01 01:29:35 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:35 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/01 01:29:35 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:35 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/09/01 01:29:35 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:35 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/09/01 01:29:36 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:36 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/09/01 01:29:38 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:40 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:42 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:44 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:46 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:48 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) === NAME TestCheckSumDownload run.go:173: removing file "data/potato" failed - try 1/3: sleeping 1s: could not Remove: invalid character '<' looking for beginning of value 2026/09/01 01:29:50 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:52 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:54 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:56 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:29:58 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:01 DEBUG : pacer: Reducing sleep to 1.5s 2026/09/01 01:30:02 DEBUG : pacer: Reducing sleep to 1.125s 2026/09/01 01:30:04 DEBUG : pacer: Reducing sleep to 843.75ms 2026/09/01 01:30:05 DEBUG : pacer: Reducing sleep to 632.8125ms --- FAIL: TestCheckSumDownload (100.65s) --- PASS: TestCheckSumDownload/subtest1 (0.73s) --- PASS: TestCheckSumDownload/subtest2 (13.04s) --- PASS: TestCheckSumDownload/subtest3 (1.03s) --- PASS: TestCheckSumDownload/subtest4 (0.67s) --- PASS: TestCheckSumDownload/subtest5 (0.67s) --- PASS: TestCheckSumDownload/subtest6 (0.74s) --- PASS: TestCheckSumDownload/subtest7 (0.67s) === RUN TestCheckSumConcurrency 2026/09/01 01:30:05 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/01 01:30:05 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/01 01:30:05 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/01 01:30:05 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/01 01:30:05 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/01 01:30:05 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/09/01 01:30:05 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/01 01:30:05 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/01 01:30:05 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/01 01:30:05 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/01 01:30:05 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/01 01:30:05 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/09/01 01:30:05 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vohukik0sofe" 2026/09/01 01:30:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/01 01:30:05 DEBUG : Linkbox root 'rclone-test-vohukik0sofe': Using cached web token 2026/09/01 01:30:06 DEBUG : Creating backend with remote "/tmp/rclone2924493791" run.go:198: Remote "Linkbox root 'rclone-test-vohukik0sofe'", Local "Local file system at /tmp/rclone2924493791", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/09/01 01:30:10 ERROR : hello, world!: sum not found 2026/09/01 01:30:10 ERROR : HELLO, WORLD!: file not in Linkbox root 'rclone-test-vohukik0sofe' 2026/09/01 01:30:10 NOTICE: Linkbox root 'rclone-test-vohukik0sofe': 1 files missing 2026/09/01 01:30:10 NOTICE: 1 hashes missing 2026/09/01 01:30:10 NOTICE: Linkbox root 'rclone-test-vohukik0sofe': 1 differences found 2026/09/01 01:30:10 NOTICE: Linkbox root 'rclone-test-vohukik0sofe': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/09/01 01:30:10 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/01 01:30:10 NOTICE: Linkbox root 'rclone-test-vohukik0sofe': 0 differences found 2026/09/01 01:30:10 NOTICE: Linkbox root 'rclone-test-vohukik0sofe': 1 matching files 2026/09/01 01:30:10 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-limaquj4huqe" 2026/09/01 01:30:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/01 01:30:10 DEBUG : Linkbox root 'rclone-test-limaquj4huqe': Using cached web token 2026/09/01 01:30:11 DEBUG : Creating backend with remote "/tmp/rclone1586425470" run.go:198: Remote "Linkbox root 'rclone-test-limaquj4huqe'", Local "Local file system at /tmp/rclone1586425470", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/09/01 01:30:15 ERROR : HELLO, WORLD!: sum not found 2026/09/01 01:30:15 ERROR : hello, world!: file not in Linkbox root 'rclone-test-limaquj4huqe' 2026/09/01 01:30:15 NOTICE: Linkbox root 'rclone-test-limaquj4huqe': 1 files missing 2026/09/01 01:30:15 NOTICE: 1 hashes missing 2026/09/01 01:30:15 NOTICE: Linkbox root 'rclone-test-limaquj4huqe': 1 differences found 2026/09/01 01:30:15 NOTICE: Linkbox root 'rclone-test-limaquj4huqe': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/09/01 01:30:15 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/01 01:30:15 NOTICE: Linkbox root 'rclone-test-limaquj4huqe': 0 differences found 2026/09/01 01:30:15 NOTICE: Linkbox root 'rclone-test-limaquj4huqe': 1 matching files 2026/09/01 01:30:15 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jifayun1muhu" 2026/09/01 01:30:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/01 01:30:15 DEBUG : Linkbox root 'rclone-test-jifayun1muhu': Using cached web token 2026/09/01 01:30:16 DEBUG : Creating backend with remote "/tmp/rclone780756803" run.go:198: Remote "Linkbox root 'rclone-test-jifayun1muhu'", Local "Local file system at /tmp/rclone780756803", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/09/01 01:30:20 ERROR : HeLlO, wOrLd!: sum not found 2026/09/01 01:30:20 ERROR : hello, world!: file not in Linkbox root 'rclone-test-jifayun1muhu' 2026/09/01 01:30:20 NOTICE: Linkbox root 'rclone-test-jifayun1muhu': 1 files missing 2026/09/01 01:30:20 NOTICE: 1 hashes missing 2026/09/01 01:30:20 NOTICE: Linkbox root 'rclone-test-jifayun1muhu': 1 differences found 2026/09/01 01:30:20 NOTICE: Linkbox root 'rclone-test-jifayun1muhu': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/09/01 01:30:21 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/01 01:30:21 NOTICE: Linkbox root 'rclone-test-jifayun1muhu': 0 differences found 2026/09/01 01:30:21 NOTICE: Linkbox root 'rclone-test-jifayun1muhu': 1 matching files 2026/09/01 01:30:21 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-laqojot6kute" 2026/09/01 01:30:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/01 01:30:21 DEBUG : Linkbox root 'rclone-test-laqojot6kute': Using cached web token 2026/09/01 01:30:21 DEBUG : Creating backend with remote "/tmp/rclone4120483784" run.go:198: Remote "Linkbox root 'rclone-test-laqojot6kute'", Local "Local file system at /tmp/rclone4120483784", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/09/01 01:30:26 ERROR : HELLO, WORLD!: sum not found 2026/09/01 01:30:26 ERROR : HeLlO, wOrLd!: file not in Linkbox root 'rclone-test-laqojot6kute' 2026/09/01 01:30:26 NOTICE: Linkbox root 'rclone-test-laqojot6kute': 1 files missing 2026/09/01 01:30:26 NOTICE: 1 hashes missing 2026/09/01 01:30:26 NOTICE: Linkbox root 'rclone-test-laqojot6kute': 1 differences found 2026/09/01 01:30:26 NOTICE: Linkbox root 'rclone-test-laqojot6kute': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/09/01 01:30:26 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/01 01:30:26 NOTICE: Linkbox root 'rclone-test-laqojot6kute': 0 differences found 2026/09/01 01:30:26 NOTICE: Linkbox root 'rclone-test-laqojot6kute': 1 matching files 2026/09/01 01:30:26 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-napiyef3sixi" 2026/09/01 01:30:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/01 01:30:26 DEBUG : Linkbox root 'rclone-test-napiyef3sixi': Using cached web token 2026/09/01 01:30:26 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:26 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/01 01:30:27 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:27 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/09/01 01:30:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/09/01 01:30:28 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/09/01 01:30:29 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:31 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:33 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:35 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:37 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:39 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/09/01 01:30:39 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 3m56.813381015s (try 1/5): exit status 1: Failed [TestCheckSumDownload]