"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestCheckSumDownload$'" - Starting (try 2/5)
2024/10/04 02:07:52 DEBUG : Creating backend with remote "TestDropbox:rclone-test-wulaxel9ruwu"
2024/10/04 02:07:52 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/10/04 02:07:52 DEBUG : Creating backend with remote "/tmp/rclone581892118"
=== RUN   TestCheckSumDownload
    run.go:180: Remote "Dropbox root 'rclone-test-wulaxel9ruwu'", Local "Local file system at /tmp/rclone581892118", Modify Window "1s"
2024/10/04 02:07:52 DEBUG : Creating backend with remote "TestDropbox:rclone-test-wulaxel9ruwu/data"
2024/10/04 02:07:53 DEBUG : data/banana: Uploading chunk 1/1
2024/10/04 02:07:53 DEBUG : data/banana: Uploading chunk 2/1
2024/10/04 02:07:54 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "data/banana" to batch
2024/10/04 02:07:54 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:07:54 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: data/banana
2024/10/04 02:07:56 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: data/banana
2024/10/04 02:07:56 DEBUG : test.sum: Uploading chunk 1/1
2024/10/04 02:07:57 DEBUG : test.sum: Uploading chunk 2/1
2024/10/04 02:07:57 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "test.sum" to batch
2024/10/04 02:07:58 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:07:58 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: test.sum
2024/10/04 02:07:58 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: test.sum
=== RUN   TestCheckSumDownload/subtest1
2024/10/04 02:08:01 DEBUG : data/potato: Uploading chunk 1/1
2024/10/04 02:08:01 DEBUG : data/potato: Uploading chunk 2/1
2024/10/04 02:08:01 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "data/potato" to batch
2024/10/04 02:08:02 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:08:02 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: data/potato
2024/10/04 02:08:04 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: data/potato
2024/10/04 02:08:04 DEBUG : test.sum: Uploading chunk 1/1
2024/10/04 02:08:04 DEBUG : test.sum: Uploading chunk 2/1
2024/10/04 02:08:05 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "test.sum" to batch
2024/10/04 02:08:05 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:08:05 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: test.sum
2024/10/04 02:08:06 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: test.sum
=== RUN   TestCheckSumDownload/subtest2
2024/10/04 02:08:08 DEBUG : test.sum: Uploading chunk 1/1
2024/10/04 02:08:08 DEBUG : test.sum: Uploading chunk 2/1
2024/10/04 02:08:09 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "test.sum" to batch
2024/10/04 02:08:09 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:08:09 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: test.sum
2024/10/04 02:08:10 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: test.sum
=== RUN   TestCheckSumDownload/subtest3
2024/10/04 02:08:13 DEBUG : test.sum: Uploading chunk 1/1
2024/10/04 02:08:14 DEBUG : test.sum: Uploading chunk 2/1
2024/10/04 02:08:14 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "test.sum" to batch
2024/10/04 02:08:15 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:08:15 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: test.sum
2024/10/04 02:08:16 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: test.sum
=== RUN   TestCheckSumDownload/subtest4
2024/10/04 02:08:19 DEBUG : test.sum: Uploading chunk 1/1
2024/10/04 02:08:20 DEBUG : test.sum: Uploading chunk 2/1
2024/10/04 02:08:20 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "test.sum" to batch
2024/10/04 02:08:21 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:08:21 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: test.sum
2024/10/04 02:08:22 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: test.sum
=== RUN   TestCheckSumDownload/subtest5
2024/10/04 02:08:24 DEBUG : test.sum: Uploading chunk 1/1
2024/10/04 02:08:24 DEBUG : test.sum: Uploading chunk 2/1
2024/10/04 02:08:25 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "test.sum" to batch
2024/10/04 02:08:25 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:08:25 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: test.sum
2024/10/04 02:08:27 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: test.sum
=== RUN   TestCheckSumDownload/subtest6
2024/10/04 02:08:29 DEBUG : data/banana: Uploading chunk 1/1
2024/10/04 02:08:30 DEBUG : data/banana: Uploading chunk 2/1
2024/10/04 02:08:30 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "data/banana" to batch
2024/10/04 02:08:31 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:08:31 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: data/banana
2024/10/04 02:08:31 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: data/banana
2024/10/04 02:08:32 DEBUG : data/potato: Uploading chunk 1/1
2024/10/04 02:08:32 DEBUG : data/potato: Uploading chunk 2/1
2024/10/04 02:08:33 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "data/potato" to batch
2024/10/04 02:08:33 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:08:33 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: data/potato
2024/10/04 02:08:34 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: data/potato
2024/10/04 02:08:34 DEBUG : test.sum: Uploading chunk 1/1
2024/10/04 02:08:34 DEBUG : test.sum: Uploading chunk 2/1
2024/10/04 02:08:35 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Adding "test.sum" to batch
2024/10/04 02:08:35 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Batch idle for 500ms so committing
2024/10/04 02:08:35 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committing sync batch length 1 starting with: test.sum
2024/10/04 02:08:36 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Committed sync batch length 1 starting with: test.sum
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (50.25s)
    --- PASS: TestCheckSumDownload/subtest1 (1.57s)
    --- PASS: TestCheckSumDownload/subtest2 (1.28s)
    --- PASS: TestCheckSumDownload/subtest3 (1.70s)
    --- PASS: TestCheckSumDownload/subtest4 (2.04s)
    --- PASS: TestCheckSumDownload/subtest5 (1.58s)
    --- PASS: TestCheckSumDownload/subtest6 (1.75s)
    --- PASS: TestCheckSumDownload/subtest7 (1.54s)
PASS
2024/10/04 02:08:42 DEBUG : Dropbox root 'rclone-test-wulaxel9ruwu': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestCheckSumDownload$'" - Finished OK in 51.415912898s (try 2/5)