"./operations.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest|TestCopyURL)$'" - Starting (try 2/5)
2026/09/01 01:04:43 DEBUG : Creating backend with remote "TestPremiumizeMe:rclone-test-jalaxer0cube"
2026/09/01 01:04:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/01 01:04:43 DEBUG : Creating backend with remote "/tmp/rclone2997659913"
=== RUN   TestCheckSumDownload
    run.go:198: Remote "premiumize.me root 'rclone-test-jalaxer0cube'", Local "Local file system at /tmp/rclone2997659913", Modify Window "876000h0m0s"
2026/09/01 01:04:43 DEBUG : Creating backend with remote "TestPremiumizeMe:rclone-test-jalaxer0cube/data"
2026/09/01 01:04:44 DEBUG : pacer: low level retry 1/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/01 01:04:44 DEBUG : pacer: low level retry 2/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:44 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/01 01:04:44 DEBUG : pacer: low level retry 3/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:44 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/01 01:04:44 DEBUG : pacer: low level retry 4/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:44 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/01 01:04:44 DEBUG : pacer: low level retry 5/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:44 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/01 01:04:44 DEBUG : pacer: low level retry 6/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:44 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/01 01:04:45 DEBUG : pacer: low level retry 7/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:45 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/01 01:04:45 DEBUG : pacer: low level retry 8/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:45 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/01 01:04:46 DEBUG : pacer: low level retry 9/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:48 DEBUG : pacer: low level retry 10/10 (error error: Rate limit exceeded. Please slow down.)
    run.go:299: Retry Put of "test.sum" to premiumize.me root 'rclone-test-jalaxer0cube': 1/10 (couldn't list files: error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:50 DEBUG : pacer: low level retry 1/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:52 DEBUG : pacer: low level retry 2/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:54 DEBUG : pacer: low level retry 3/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:56 DEBUG : pacer: low level retry 4/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:04:59 DEBUG : pacer: low level retry 5/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/01 01:05:01 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/01 01:05:03 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/01 01:05:04 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/01 01:05:05 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/01 01:05:06 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/01 01:05:07 DEBUG : pacer: Reducing sleep to 355.957031ms
=== RUN   TestCheckSumDownload/subtest1
2026/09/01 01:05:08 DEBUG : pacer: Reducing sleep to 150.169371ms
2026/09/01 01:05:08 DEBUG : pacer: Reducing sleep to 112.627028ms
2026/09/01 01:05:09 DEBUG : pacer: Reducing sleep to 84.470271ms
2026/09/01 01:05:09 DEBUG : pacer: Reducing sleep to 63.352703ms
2026/09/01 01:05:09 DEBUG : pacer: Reducing sleep to 47.514527ms
2026/09/01 01:05:09 DEBUG : pacer: Reducing sleep to 35.635895ms
2026/09/01 01:05:09 DEBUG : test.sum: Moving old file out the way to "test.sum.qowahaf4"
2026/09/01 01:05:09 DEBUG : pacer: Reducing sleep to 26.726921ms
2026/09/01 01:05:09 DEBUG : pacer: Reducing sleep to 20.04519ms
2026/09/01 01:05:09 DEBUG : test.sum: Removing old file
2026/09/01 01:05:09 DEBUG : pacer: Reducing sleep to 15.033892ms
2026/09/01 01:05:10 DEBUG : pacer: Reducing sleep to 11.275419ms
2026/09/01 01:05:10 DEBUG : pacer: Reducing sleep to 10ms
=== RUN   TestCheckSumDownload/subtest2
2026/09/01 01:05:10 DEBUG : test.sum: Moving old file out the way to "test.sum.juqilum7"
2026/09/01 01:05:10 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest3
2026/09/01 01:05:11 DEBUG : test.sum: Moving old file out the way to "test.sum.woxiquw5"
2026/09/01 01:05:12 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest4
2026/09/01 01:05:12 DEBUG : test.sum: Moving old file out the way to "test.sum.nitapoj8"
2026/09/01 01:05:12 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest5
2026/09/01 01:05:13 DEBUG : test.sum: Moving old file out the way to "test.sum.jizepeq6"
2026/09/01 01:05:14 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest6
2026/09/01 01:05:14 DEBUG : data/banana: Moving old file out the way to "banana.midojaq1"
2026/09/01 01:05:14 DEBUG : data/banana: Removing old file
2026/09/01 01:05:15 DEBUG : data/potato: Moving old file out the way to "potato.janodet1"
2026/09/01 01:05:15 DEBUG : data/potato: Removing old file
2026/09/01 01:05:15 DEBUG : test.sum: Moving old file out the way to "test.sum.didohav8"
2026/09/01 01:05:16 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (33.73s)
    --- PASS: TestCheckSumDownload/subtest1 (1.21s)
    --- PASS: TestCheckSumDownload/subtest2 (0.24s)
    --- PASS: TestCheckSumDownload/subtest3 (0.38s)
    --- PASS: TestCheckSumDownload/subtest4 (0.24s)
    --- PASS: TestCheckSumDownload/subtest5 (0.24s)
    --- PASS: TestCheckSumDownload/subtest6 (0.24s)
    --- PASS: TestCheckSumDownload/subtest7 (0.22s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "premiumize.me root 'rclone-test-jalaxer0cube'", Local "Local file system at /tmp/rclone2997659913", Modify Window "876000h0m0s"
2026/09/01 01:05:17 DEBUG : Creating backend with remote "TestPremiumizeMe:rclone-test-jalaxer0cube/dst"
2026/09/01 01:05:17 DEBUG : Creating backend with remote "TestPremiumizeMe:rclone-test-jalaxer0cube/CompareDest"
2026/09/01 01:05:17 DEBUG : one: Need to transfer - File not found at Destination
2026/09/01 01:05:18 DEBUG : one: size = 3 OK
2026/09/01 01:05:18 INFO  : one: Copied (new)
2026/09/01 01:05:18 DEBUG : one: size = 5 (Local file system at /tmp/rclone2997659913)
2026/09/01 01:05:18 DEBUG : one: size = 3 (premiumize.me root 'rclone-test-jalaxer0cube/dst')
2026/09/01 01:05:18 DEBUG : one: Sizes differ
2026/09/01 01:05:18 DEBUG : one: Moving old file out the way to "one.gasuzef5"
2026/09/01 01:05:19 DEBUG : one: Removing old file
2026/09/01 01:05:19 DEBUG : one: size = 5 OK
2026/09/01 01:05:19 INFO  : one: Copied (replaced existing)
2026/09/01 01:05:19 DEBUG : dst/one: Moving old file out the way to "one.niverav5"
2026/09/01 01:05:20 DEBUG : dst/one: Removing old file
2026/09/01 01:05:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone2997659913)
2026/09/01 01:05:20 DEBUG : one: size = 3 (premiumize.me root 'rclone-test-jalaxer0cube/dst')
2026/09/01 01:05:20 DEBUG : one: Sizes differ
2026/09/01 01:05:21 DEBUG : one: size = 5 OK
2026/09/01 01:05:21 DEBUG : one: Sizes identical
2026/09/01 01:05:21 DEBUG : one: Destination found in --compare-dest, skipping
2026/09/01 01:05:21 DEBUG : two: Need to transfer - File not found at Destination
2026/09/01 01:05:22 DEBUG : two: size = 3 OK
2026/09/01 01:05:22 DEBUG : two: Sizes identical
2026/09/01 01:05:22 DEBUG : two: Destination found in --compare-dest, skipping
2026/09/01 01:05:22 DEBUG : two: Need to transfer - File not found at Destination
2026/09/01 01:05:22 DEBUG : two: size = 3 OK
2026/09/01 01:05:22 DEBUG : two: Sizes identical
2026/09/01 01:05:22 DEBUG : two: Destination found in --compare-dest, skipping
2026/09/01 01:05:22 DEBUG : two: Need to transfer - File not found at Destination
2026/09/01 01:05:22 DEBUG : two: size = 5 (Local file system at /tmp/rclone2997659913)
2026/09/01 01:05:22 DEBUG : two: size = 3 (premiumize.me root 'rclone-test-jalaxer0cube/CompareDest')
2026/09/01 01:05:22 DEBUG : two: Sizes differ
2026/09/01 01:05:23 DEBUG : two: size = 5 OK
2026/09/01 01:05:23 INFO  : two: Copied (new)
--- PASS: TestCopyFileCompareDest (6.81s)
=== RUN   TestCopyURL
    run.go:198: Remote "premiumize.me root 'rclone-test-jalaxer0cube'", Local "Local file system at /tmp/rclone2997659913", Modify Window "876000h0m0s"
2026/09/01 01:05:24 DEBUG : file1: size = 14 OK
2026/09/01 01:05:24 DEBUG : filename.txt: File name found in url
2026/09/01 01:05:25 DEBUG : filename.txt: size = 14 OK
2026/09/01 01:05:25 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/09/01 01:05:25 DEBUG : headerfilename.txt: size = 14 OK
2026/09/01 01:05:26 DEBUG : file2: size = 14 OK
--- PASS: TestCopyURL (2.67s)
PASS
2026/09/01 01:05:26 DEBUG : premiumize.me root 'rclone-test-jalaxer0cube': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^(TestCheckSumDownload|TestCopyFileCompareDest|TestCopyURL)$'" - Finished OK in 43.597706593s (try 2/5)