"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^TestCheckSumDownload$'" - Starting (try 3/5) 2026/08/18 03:08:04 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-cevemuf6foho" 2026/08/18 03:08:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/18 03:08:04 DEBUG : Linkbox root 'rclone-test-cevemuf6foho': Using cached web token 2026/08/18 03:08:05 DEBUG : Creating backend with remote "/tmp/rclone1815862701" === RUN TestCheckSumDownload run.go:198: Remote "Linkbox root 'rclone-test-cevemuf6foho'", Local "Local file system at /tmp/rclone1815862701", Modify Window "876000h0m0s" 2026/08/18 03:08:05 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-cevemuf6foho/data" 2026/08/18 03:08:05 DEBUG : Linkbox root 'rclone-test-cevemuf6foho/data': Using cached web token 2026/08/18 03:08:13 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:13 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/18 03:08:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/18 03:08:13 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:13 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/18 03:08:14 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:14 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/18 03:08:16 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:18 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:20 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:22 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:24 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:26 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "test.sum" to Linkbox root 'rclone-test-cevemuf6foho': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/08/18 03:08:28 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:34 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:08:36 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/18 03:08:36 DEBUG : test.sum: Update: removing old file 2026/08/18 03:08:39 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/18 03:08:40 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/18 03:08:42 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/18 03:08:42 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/18 03:08:43 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/18 03:08:43 DEBUG : pacer: Reducing sleep to 266.967773ms === RUN TestCheckSumDownload/subtest1 2026/08/18 03:08:47 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest2 2026/08/18 03:08:51 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest3 2026/08/18 03:08:55 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest4 2026/08/18 03:09:00 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest5 2026/08/18 03:09:04 DEBUG : test.sum: Update: removing old file === RUN TestCheckSumDownload/subtest6 2026/08/18 03:09:09 DEBUG : data/banana: Update: removing old file 2026/08/18 03:09:12 DEBUG : data/potato: Update: removing old file 2026/08/18 03:09:15 DEBUG : test.sum: Update: removing old file 2026/08/18 03:09:15 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:15 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/18 03:09:16 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:16 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/18 03:09:16 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:16 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/18 03:09:17 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:17 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/18 03:09:18 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:20 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:22 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:24 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:26 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:28 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:28 ERROR : test.sum: Update: failed to remove existing file: could not Remove: invalid character '<' looking for beginning of value 2026/08/18 03:09:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:32 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:34 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/18 03:09:37 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/18 03:09:40 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/18 03:09:40 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/18 03:09:41 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/18 03:09:42 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/08/18 03:09:43 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/18 03:09:44 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/18 03:09:44 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/08/18 03:09:46 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:529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:545 /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 (82), 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:529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:545 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /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:529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:545 Error: Not equal: expected: 82 actual : 123 Test: TestCheckSumDownload Messages: test.sum: size incorrect file=82 vs obj=123 === RUN TestCheckSumDownload/subtest7 === NAME TestCheckSumDownload check_test.go:408: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:408 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Received unexpected error: file not in Linkbox root 'rclone-test-cevemuf6foho/data' Test: TestCheckSumDownload Messages: unexpected error in run 7 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: 0 actual : 2 Test: TestCheckSumDownload Messages: wrong error count in run 7 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{"", "= banana", "= potato"} actual : []string{"", "* potato", "+ orange", "= banana"} Diff: --- Expected +++ Actual @@ -1,5 +1,6 @@ -([]string) (len=3) { +([]string) (len=4) { (string) "", - (string) (len=8) "= banana", - (string) (len=8) "= potato" + (string) (len=8) "* potato", + (string) (len=8) "+ orange", + (string) (len=8) "= banana" } Test: TestCheckSumDownload Messages: wrong combined result in run 7 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:423 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{} actual : []string{"", "orange"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) "", + (string) (len=6) "orange" } Test: TestCheckSumDownload Messages: wrong missingondst result in run 7 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", "potato"} actual : []string{"", "banana"} Diff: --- Expected +++ Actual @@ -1,5 +1,4 @@ -([]string) (len=3) { +([]string) (len=2) { (string) "", - (string) (len=6) "banana", - (string) (len=6) "potato" + (string) (len=6) "banana" } Test: TestCheckSumDownload Messages: wrong match result in run 7 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:425 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{} actual : []string{"", "potato"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) "", + (string) (len=6) "potato" } Test: TestCheckSumDownload Messages: wrong differ result in run 7 --- FAIL: TestCheckSumDownload (114.63s) --- PASS: TestCheckSumDownload/subtest1 (1.52s) --- PASS: TestCheckSumDownload/subtest2 (0.49s) --- PASS: TestCheckSumDownload/subtest3 (0.67s) --- PASS: TestCheckSumDownload/subtest4 (0.74s) --- PASS: TestCheckSumDownload/subtest5 (0.68s) --- PASS: TestCheckSumDownload/subtest6 (0.72s) --- PASS: TestCheckSumDownload/subtest7 (1.15s) FAIL 2026/08/18 03:09:59 DEBUG : Linkbox root 'rclone-test-cevemuf6foho': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^TestCheckSumDownload$'" - Finished ERROR in 1m57.005795769s (try 3/5): exit status 1: Failed [TestCheckSumDownload]