"./operations.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^TestCheckSumDownload$'" - Starting (try 3/5)
2026/09/21 05:04:17 DEBUG : Creating backend with remote "TestPremiumizeMe:rclone-test-yivogip8juco"
2026/09/21 05:04:17 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/21 05:04:17 DEBUG : Creating backend with remote "/tmp/rclone2976778667"
=== RUN   TestCheckSumDownload
    run.go:198: Remote "premiumize.me root 'rclone-test-yivogip8juco'", Local "Local file system at /tmp/rclone2976778667", Modify Window "876000h0m0s"
2026/09/21 05:04:17 DEBUG : Creating backend with remote "TestPremiumizeMe:rclone-test-yivogip8juco/data"
=== RUN   TestCheckSumDownload/subtest1
2026/09/21 05:04:20 DEBUG : test.sum: Moving old file out the way to "test.sum.kuqafaw2"
2026/09/21 05:04:21 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest2
2026/09/21 05:04:22 DEBUG : test.sum: Moving old file out the way to "test.sum.lofanid4"
2026/09/21 05:04:22 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest3
2026/09/21 05:04:23 DEBUG : test.sum: Moving old file out the way to "test.sum.hesuyig6"
2026/09/21 05:04:23 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest4
2026/09/21 05:04:24 DEBUG : test.sum: Moving old file out the way to "test.sum.tarowoq7"
2026/09/21 05:04:25 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest5
2026/09/21 05:04:25 DEBUG : test.sum: Moving old file out the way to "test.sum.hacujuv0"
2026/09/21 05:04:26 DEBUG : test.sum: Removing old file
=== RUN   TestCheckSumDownload/subtest6
2026/09/21 05:04:27 DEBUG : data/banana: Moving old file out the way to "banana.girehik4"
2026/09/21 05:04:27 DEBUG : data/banana: Removing old file
2026/09/21 05:04:27 DEBUG : data/potato: Moving old file out the way to "potato.rohocab1"
2026/09/21 05:04:28 DEBUG : data/potato: Removing old file
2026/09/21 05:04:28 DEBUG : test.sum: Moving old file out the way to "test.sum.mupofen0"
2026/09/21 05:04:29 DEBUG : test.sum: Removing old file
2026/09/21 05:04:29 DEBUG : pacer: low level retry 1/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/21 05:04:29 DEBUG : pacer: low level retry 2/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:29 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/21 05:04:29 DEBUG : pacer: low level retry 3/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:29 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/21 05:04:29 DEBUG : pacer: low level retry 4/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:29 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/21 05:04:29 DEBUG : pacer: low level retry 5/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:29 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/21 05:04:29 DEBUG : pacer: low level retry 6/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:29 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/21 05:04:29 DEBUG : pacer: low level retry 7/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:29 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/21 05:04:30 DEBUG : pacer: low level retry 8/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:30 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/21 05:04:31 DEBUG : pacer: low level retry 9/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:33 DEBUG : pacer: low level retry 10/10 (error error: Rate limit exceeded. Please slow down.)
=== NAME  TestCheckSumDownload
    run.go:299: Retry Put of "test.sum" to premiumize.me root 'rclone-test-yivogip8juco': 1/10 (upload remove old file: remove http: error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:35 DEBUG : pacer: low level retry 1/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:37 DEBUG : pacer: low level retry 2/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:39 DEBUG : pacer: low level retry 3/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:41 DEBUG : pacer: low level retry 4/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:43 DEBUG : pacer: low level retry 5/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:45 DEBUG : pacer: low level retry 6/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:47 DEBUG : pacer: low level retry 7/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:49 DEBUG : pacer: low level retry 8/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:51 DEBUG : pacer: low level retry 9/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:53 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-yivogip8juco': 2/10 (couldn't list files: error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:55 DEBUG : pacer: low level retry 1/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:57 DEBUG : pacer: low level retry 2/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:04:59 DEBUG : pacer: low level retry 3/10 (error error: Rate limit exceeded. Please slow down.)
2026/09/21 05:05:01 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/21 05:05:03 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/21 05:05:03 DEBUG : test.sum: Moving old file out the way to "test.sum.decaxuz1"
2026/09/21 05:05:05 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/21 05:05:06 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/21 05:05:06 DEBUG : test.sum: Removing old file
2026/09/21 05:05:07 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/21 05:05:08 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/21 05:05:08 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/09/21 05:05:08 DEBUG : pacer: Reducing sleep to 200.225829ms
    fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:302: Flushing the directory cache
2026/09/21 05:05:09 DEBUG : pacer: Reducing sleep to 150.169371ms
2026/09/21 05:05:10 DEBUG : pacer: Reducing sleep to 112.627028ms
2026/09/21 05:05:10 DEBUG : pacer: Reducing sleep to 84.470271ms
    fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:302: Flushing the directory cache
2026/09/21 05:05:12 DEBUG : pacer: Reducing sleep to 63.352703ms
2026/09/21 05:05:12 DEBUG : pacer: Reducing sleep to 47.514527ms
2026/09/21 05:05:12 DEBUG : pacer: Reducing sleep to 35.635895ms
    fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:302: Flushing the directory cache
    fstest.go:306: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351
        	            				/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:1264
        	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.sum (82), test.sum.mupofen0 (123)
    fstest.go:192: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351
        	            				/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.sum.mupofen0"
=== RUN   TestCheckSumDownload/subtest7
2026/09/21 05:05:17 DEBUG : pacer: Reducing sleep to 11.275419ms
2026/09/21 05:05:17 DEBUG : pacer: Reducing sleep to 10ms
--- FAIL: TestCheckSumDownload (59.85s)
    --- PASS: TestCheckSumDownload/subtest1 (0.61s)
    --- PASS: TestCheckSumDownload/subtest2 (0.26s)
    --- PASS: TestCheckSumDownload/subtest3 (0.40s)
    --- PASS: TestCheckSumDownload/subtest4 (0.35s)
    --- PASS: TestCheckSumDownload/subtest5 (0.26s)
    --- PASS: TestCheckSumDownload/subtest6 (0.36s)
    --- PASS: TestCheckSumDownload/subtest7 (0.34s)
FAIL
2026/09/21 05:05:17 DEBUG : premiumize.me root 'rclone-test-yivogip8juco': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^TestCheckSumDownload$'" - Finished ERROR in 1m0.182357513s (try 3/5): exit status 1: Failed [TestCheckSumDownload]