"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCheckDownload|TestCheckSumDownload)$'" - Starting (try 2/5)
2026/07/19 01:53:30 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-fedideh8jome"
2026/07/19 01:53:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/19 01:53:30 DEBUG : Linkbox root 'rclone-test-fedideh8jome': Using cached web token
2026/07/19 01:53:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:30 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/19 01:53:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:30 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/19 01:53:30 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:30 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/19 01:53:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:31 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/19 01:53:33 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:35 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:37 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:39 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:41 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:43 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:45 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:47 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:49 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:51 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:53:53 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/19 01:53:53 DEBUG : Creating backend with remote "/tmp/rclone2175662170"
2026/07/19 01:53:53 DEBUG : Config file has changed externally - reloading
=== RUN   TestCheckDownload
    run.go:198: Remote "Linkbox root 'rclone-test-fedideh8jome'", Local "Local file system at /tmp/rclone2175662170", Modify Window "876000h0m0s"
2026/07/19 01:53:55 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/19 01:53:57 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/19 01:53:58 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/19 01:53:58 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/19 01:54:00 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/19 01:54:00 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/19 01:54:01 DEBUG : pacer: Reducing sleep to 200.225829ms
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2026/07/19 01:54:04 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10)
2026/07/19 01:54:04 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10)
2026/07/19 01:54:05 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10)
2026/07/19 01:54:05 DEBUG : empty space: Trying to read object after upload: try again in 800ms (4/10)
2026/07/19 01:54:06 DEBUG : empty space: Trying to read object after upload: try again in 1.6s (5/10)
=== RUN   TestCheckDownload/3
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
2026/07/19 01:54:20 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:20 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/19 01:54:20 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:20 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/19 01:54:20 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:20 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/19 01:54:21 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:21 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/19 01:54:23 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:25 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:27 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:29 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:31 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:33 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
=== NAME  TestCheckDownload
    run.go:172: removing file "rutabaga" failed - try 1/3: sleeping 1s: could not Remove: invalid character '<' looking for beginning of value
2026/07/19 01:54:35 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:37 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:39 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:41 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:43 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:45 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:47 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:49 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:51 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:54:54 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/19 01:54:55 DEBUG : pacer: Reducing sleep to 1.125s
--- PASS: TestCheckDownload (61.81s)
    --- PASS: TestCheckDownload/1 (0.37s)
    --- PASS: TestCheckDownload/2 (0.34s)
    --- PASS: TestCheckDownload/3 (0.23s)
    --- PASS: TestCheckDownload/4 (0.44s)
    --- PASS: TestCheckDownload/5 (0.61s)
    --- PASS: TestCheckDownload/6 (0.45s)
    --- PASS: TestCheckDownload/7 (0.60s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Linkbox root 'rclone-test-fedideh8jome'", Local "Local file system at /tmp/rclone2175662170", Modify Window "876000h0m0s"
2026/07/19 01:54:55 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-fedideh8jome/data"
2026/07/19 01:54:55 DEBUG : Linkbox root 'rclone-test-fedideh8jome/data': Using cached web token
2026/07/19 01:54:57 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/19 01:54:59 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/19 01:54:59 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/07/19 01:55:00 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/07/19 01:55:01 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/07/19 01:55:02 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/19 01:55:02 DEBUG : pacer: Reducing sleep to 200ms
=== RUN   TestCheckSumDownload/subtest1
2026/07/19 01:55:07 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest2
2026/07/19 01:55:12 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest3
2026/07/19 01:55:16 DEBUG : test.sum: Update: removing old file
2026/07/19 01:55:17 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:17 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/07/19 01:55:18 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:18 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/07/19 01:55:18 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:18 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/07/19 01:55:19 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:19 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/07/19 01:55:20 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:22 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:25 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:26 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:29 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:31 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
=== NAME  TestCheckSumDownload
    run.go:299: Retry Put of "test.sum" to Linkbox root 'rclone-test-fedideh8jome': 1/10 (Update err in unmarshaling response: invalid character '<' looking for beginning of value)
2026/07/19 01:55:33 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:35 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:37 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:39 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:41 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:43 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:45 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:47 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:49 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:51 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:53 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/07/19 01:55:55 DEBUG : pacer: Reducing sleep to 1.5s
2026/07/19 01:55:58 DEBUG : pacer: Reducing sleep to 1.125s
2026/07/19 01:55:58 DEBUG : pacer: Reducing sleep to 843.75ms
2026/07/19 01:55:59 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/07/19 01:56:00 DEBUG : pacer: Reducing sleep to 474.609375ms
=== RUN   TestCheckSumDownload/subtest4
2026/07/19 01:56:02 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/07/19 01:56:02 DEBUG : test.sum: Update: removing old file
2026/07/19 01:56:04 DEBUG : pacer: Reducing sleep to 200ms
=== RUN   TestCheckSumDownload/subtest5
2026/07/19 01:56:07 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest6
2026/07/19 01:56:11 DEBUG : data/banana: Update: removing old file
2026/07/19 01:56:15 DEBUG : data/potato: Update: removing old file
2026/07/19 01:56:18 DEBUG : test.sum: Update: removing old file
2026/07/19 01:56:21 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
2026/07/19 01:56:22 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10)
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (94.45s)
    --- PASS: TestCheckSumDownload/subtest1 (0.89s)
    --- PASS: TestCheckSumDownload/subtest2 (0.48s)
    --- PASS: TestCheckSumDownload/subtest3 (0.67s)
    --- PASS: TestCheckSumDownload/subtest4 (1.85s)
    --- PASS: TestCheckSumDownload/subtest5 (0.69s)
    --- PASS: TestCheckSumDownload/subtest6 (0.68s)
    --- PASS: TestCheckSumDownload/subtest7 (0.66s)
PASS
2026/07/19 01:56:29 DEBUG : Linkbox root 'rclone-test-fedideh8jome': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCheckDownload|TestCheckSumDownload)$'" - Finished OK in 3m1.107238996s (try 2/5)