"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest)$'" - Starting (try 2/5)
2026/10/04 02:50:19 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yutojek8zapi"
2026/10/04 02:50:19 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/10/04 02:50:19 DEBUG : Linkbox root 'rclone-test-yutojek8zapi': Using cached web token
2026/10/04 02:50:20 DEBUG : Creating backend with remote "/tmp/rclone177876264"
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Linkbox root 'rclone-test-yutojek8zapi'", Local "Local file system at /tmp/rclone177876264", Modify Window "876000h0m0s"
2026/10/04 02:50:20 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yutojek8zapi/data"
2026/10/04 02:50:20 DEBUG : Linkbox root 'rclone-test-yutojek8zapi/data': Using cached web token
=== RUN   TestCheckSumDownload/subtest1
2026/10/04 02:50:32 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest2
2026/10/04 02:50:36 DEBUG : test.sum: Update: removing old file
2026/10/04 02:50:38 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/10/04 02:50:38 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/10/04 02:50:38 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/10/04 02:50:38 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/10/04 02:50:38 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/10/04 02:50:38 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/10/04 02:50:39 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/10/04 02:50:39 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/10/04 02:50:41 DEBUG : pacer: Reducing sleep to 1.5s
2026/10/04 02:50:44 DEBUG : pacer: Reducing sleep to 1.125s
2026/10/04 02:50:44 DEBUG : pacer: Reducing sleep to 843.75ms
2026/10/04 02:50:45 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/10/04 02:50:46 DEBUG : pacer: Reducing sleep to 474.609375ms
=== RUN   TestCheckSumDownload/subtest3
2026/10/04 02:50:48 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/10/04 02:50:48 DEBUG : test.sum: Update: removing old file
2026/10/04 02:50:49 DEBUG : pacer: Reducing sleep to 200ms
=== RUN   TestCheckSumDownload/subtest4
2026/10/04 02:50:53 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest5
2026/10/04 02:50:57 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest6
2026/10/04 02:51:01 DEBUG : data/banana: Update: removing old file
2026/10/04 02:51:05 DEBUG : data/potato: Update: removing old file
2026/10/04 02:51:08 DEBUG : test.sum: Update: removing old file
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (58.78s)
    --- PASS: TestCheckSumDownload/subtest1 (1.28s)
    --- PASS: TestCheckSumDownload/subtest2 (0.48s)
    --- PASS: TestCheckSumDownload/subtest3 (1.37s)
    --- PASS: TestCheckSumDownload/subtest4 (0.67s)
    --- PASS: TestCheckSumDownload/subtest5 (0.70s)
    --- PASS: TestCheckSumDownload/subtest6 (0.67s)
    --- PASS: TestCheckSumDownload/subtest7 (0.67s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Linkbox root 'rclone-test-yutojek8zapi'", Local "Local file system at /tmp/rclone177876264", Modify Window "876000h0m0s"
2026/10/04 02:51:18 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yutojek8zapi/dst"
2026/10/04 02:51:18 DEBUG : Linkbox root 'rclone-test-yutojek8zapi/dst': Using cached web token
2026/10/04 02:51:20 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yutojek8zapi/CompareDest"
2026/10/04 02:51:20 DEBUG : Linkbox root 'rclone-test-yutojek8zapi/CompareDest': Using cached web token
2026/10/04 02:51:21 DEBUG : one: Need to transfer - File not found at Destination
2026/10/04 02:51:25 DEBUG : one: size = 3 OK
2026/10/04 02:51:25 INFO  : one: Copied (new)
2026/10/04 02:51:26 DEBUG : one: size = 5 (Local file system at /tmp/rclone177876264)
2026/10/04 02:51:26 DEBUG : one: size = 3 (Linkbox root 'rclone-test-yutojek8zapi/dst')
2026/10/04 02:51:26 DEBUG : one: Sizes differ
2026/10/04 02:51:26 DEBUG : one: Update: removing old file
2026/10/04 02:51:30 DEBUG : one: size = 5 OK
2026/10/04 02:51:30 INFO  : one: Copied (replaced existing)
2026/10/04 02:51:31 DEBUG : dst/one: Update: removing old file
2026/10/04 02:51:38 DEBUG : one: size = 5 (Local file system at /tmp/rclone177876264)
2026/10/04 02:51:38 DEBUG : one: size = 3 (Linkbox root 'rclone-test-yutojek8zapi/dst')
2026/10/04 02:51:38 DEBUG : one: Sizes differ
2026/10/04 02:51:39 DEBUG : one: size = 5 OK
2026/10/04 02:51:39 DEBUG : one: Sizes identical
2026/10/04 02:51:39 DEBUG : one: Destination found in --compare-dest, skipping
2026/10/04 02:51:42 DEBUG : two: Need to transfer - File not found at Destination
2026/10/04 02:51:42 DEBUG : two: size = 3 OK
2026/10/04 02:51:42 DEBUG : two: Sizes identical
2026/10/04 02:51:42 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/04 02:51:43 DEBUG : two: Need to transfer - File not found at Destination
2026/10/04 02:51:43 DEBUG : two: size = 3 OK
2026/10/04 02:51:43 DEBUG : two: Sizes identical
2026/10/04 02:51:43 DEBUG : two: Destination found in --compare-dest, skipping
2026/10/04 02:51:45 DEBUG : two: Need to transfer - File not found at Destination
2026/10/04 02:51:45 DEBUG : two: size = 5 (Local file system at /tmp/rclone177876264)
2026/10/04 02:51:45 DEBUG : two: size = 3 (Linkbox root 'rclone-test-yutojek8zapi/CompareDest')
2026/10/04 02:51:45 DEBUG : two: Sizes differ
2026/10/04 02:51:47 DEBUG : two: size = 5 OK
2026/10/04 02:51:47 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (38.21s)
PASS
2026/10/04 02:51:57 DEBUG : Linkbox root 'rclone-test-yutojek8zapi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest)$'" - Finished OK in 1m39.386914243s (try 2/5)