"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCheckSizeOnly|TestCopyFileCompareDest)$'" - Starting (try 2/5)
2026/06/25 01:51:31 DEBUG : Creating backend with remote "TestDropbox:rclone-test-zeraqoj4ravi"
2026/06/25 01:51:31 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/06/25 01:51:31 DEBUG : Creating backend with remote "/tmp/rclone2616790651"
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "Dropbox root 'rclone-test-zeraqoj4ravi'", Local "Local file system at /tmp/rclone2616790651", Modify Window "1s"
2026/06/25 01:51:32 DEBUG : rutabaga: Uploading chunk 1/1
2026/06/25 01:51:33 DEBUG : rutabaga: Uploading chunk 2/1
2026/06/25 01:51:33 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Adding "rutabaga" to batch
2026/06/25 01:51:34 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Batch idle for 500ms so committing
2026/06/25 01:51:34 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committing sync batch length 1 starting with: rutabaga
2026/06/25 01:51:34 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committed sync batch length 1 starting with: rutabaga
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2026/06/25 01:51:36 DEBUG : empty space: Uploading chunk 1/1
2026/06/25 01:51:36 DEBUG : empty space: Uploading chunk 2/1
2026/06/25 01:51:36 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Adding "empty space" to batch
2026/06/25 01:51:37 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Batch idle for 500ms so committing
2026/06/25 01:51:37 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committing sync batch length 1 starting with: empty space
2026/06/25 01:51:38 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committed sync batch length 1 starting with: empty space
=== RUN   TestCheckSizeOnly/3
2026/06/25 01:51:39 DEBUG : potato2: Uploading chunk 1/1
2026/06/25 01:51:39 DEBUG : potato2: Uploading chunk 2/1
2026/06/25 01:51:39 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Adding "potato2" to batch
2026/06/25 01:51:40 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Batch idle for 500ms so committing
2026/06/25 01:51:40 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committing sync batch length 1 starting with: potato2
2026/06/25 01:51:41 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committed sync batch length 1 starting with: potato2
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2026/06/25 01:51:42 DEBUG : remotepotato: Uploading chunk 1/1
2026/06/25 01:51:42 DEBUG : remotepotato: Uploading chunk 2/1
2026/06/25 01:51:43 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Adding "remotepotato" to batch
2026/06/25 01:51:43 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Batch idle for 500ms so committing
2026/06/25 01:51:43 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committing sync batch length 1 starting with: remotepotato
2026/06/25 01:51:44 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committed sync batch length 1 starting with: remotepotato
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (16.38s)
    --- PASS: TestCheckSizeOnly/1 (0.28s)
    --- PASS: TestCheckSizeOnly/2 (0.28s)
    --- PASS: TestCheckSizeOnly/3 (0.30s)
    --- PASS: TestCheckSizeOnly/4 (0.28s)
    --- PASS: TestCheckSizeOnly/5 (0.29s)
    --- PASS: TestCheckSizeOnly/6 (0.30s)
    --- PASS: TestCheckSizeOnly/7 (0.28s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Dropbox root 'rclone-test-zeraqoj4ravi'", Local "Local file system at /tmp/rclone2616790651", Modify Window "1s"
2026/06/25 01:51:48 DEBUG : Creating backend with remote "TestDropbox:rclone-test-zeraqoj4ravi/dst"
2026/06/25 01:51:48 DEBUG : Creating backend with remote "TestDropbox:rclone-test-zeraqoj4ravi/CompareDest"
2026/06/25 01:51:48 DEBUG : one: Need to transfer - File not found at Destination
2026/06/25 01:51:49 DEBUG : one: Uploading chunk 1/1
2026/06/25 01:51:49 DEBUG : one: Uploading chunk 2/1
2026/06/25 01:51:50 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Adding "one" to batch
2026/06/25 01:51:50 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Batch idle for 500ms so committing
2026/06/25 01:51:50 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Committing sync batch length 1 starting with: one
2026/06/25 01:51:51 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Committed sync batch length 1 starting with: one
2026/06/25 01:51:51 DEBUG : one: size = 3 OK
2026/06/25 01:51:51 DEBUG : one: dropbox = fe8d7a873dc48961a6af334c996b2cb3ce37149d5ce9c9253952a54c6a92c1ad OK
2026/06/25 01:51:51 INFO  : one: Copied (new)
2026/06/25 01:51:53 DEBUG : one: size = 5 (Local file system at /tmp/rclone2616790651)
2026/06/25 01:51:53 DEBUG : one: size = 3 (Dropbox root 'rclone-test-zeraqoj4ravi/dst')
2026/06/25 01:51:53 DEBUG : one: Sizes differ
2026/06/25 01:51:53 DEBUG : one: Uploading chunk 1/1
2026/06/25 01:51:54 DEBUG : one: Uploading chunk 2/1
2026/06/25 01:51:54 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Adding "one" to batch
2026/06/25 01:51:54 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Batch idle for 500ms so committing
2026/06/25 01:51:54 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Committing sync batch length 1 starting with: one
2026/06/25 01:51:55 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Committed sync batch length 1 starting with: one
2026/06/25 01:51:55 DEBUG : one: size = 5 OK
2026/06/25 01:51:55 DEBUG : one: dropbox = 7da8d117f9fb226817d81adc15c72720c9879c8b7fb821d27f217d198bd79d0d OK
2026/06/25 01:51:55 INFO  : one: Copied (replaced existing)
2026/06/25 01:51:56 DEBUG : dst/one: Uploading chunk 1/1
2026/06/25 01:51:57 DEBUG : dst/one: Uploading chunk 2/1
2026/06/25 01:51:57 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Adding "dst/one" to batch
2026/06/25 01:51:58 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Batch idle for 500ms so committing
2026/06/25 01:51:58 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committing sync batch length 1 starting with: dst/one
2026/06/25 01:51:58 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committed sync batch length 1 starting with: dst/one
2026/06/25 01:51:59 DEBUG : CompareDest/one: Uploading chunk 1/1
2026/06/25 01:51:59 DEBUG : CompareDest/one: Uploading chunk 2/1
2026/06/25 01:52:00 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Adding "CompareDest/one" to batch
2026/06/25 01:52:00 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Batch idle for 500ms so committing
2026/06/25 01:52:00 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committing sync batch length 1 starting with: CompareDest/one
2026/06/25 01:52:01 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committed sync batch length 1 starting with: CompareDest/one
2026/06/25 01:52:02 DEBUG : one: size = 5 (Local file system at /tmp/rclone2616790651)
2026/06/25 01:52:02 DEBUG : one: size = 3 (Dropbox root 'rclone-test-zeraqoj4ravi/dst')
2026/06/25 01:52:02 DEBUG : one: Sizes differ
2026/06/25 01:52:02 DEBUG : one: size = 5 OK
2026/06/25 01:52:02 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/06/25 01:52:02 DEBUG : one: Destination found in --compare-dest, skipping
2026/06/25 01:52:03 DEBUG : CompareDest/two: Uploading chunk 1/1
2026/06/25 01:52:03 DEBUG : CompareDest/two: Uploading chunk 2/1
2026/06/25 01:52:04 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Adding "CompareDest/two" to batch
2026/06/25 01:52:04 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Batch idle for 500ms so committing
2026/06/25 01:52:04 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committing sync batch length 1 starting with: CompareDest/two
2026/06/25 01:52:05 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Committed sync batch length 1 starting with: CompareDest/two
2026/06/25 01:52:06 DEBUG : two: Need to transfer - File not found at Destination
2026/06/25 01:52:06 DEBUG : two: size = 3 OK
2026/06/25 01:52:06 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/06/25 01:52:06 DEBUG : two: Destination found in --compare-dest, skipping
2026/06/25 01:52:07 DEBUG : two: Need to transfer - File not found at Destination
2026/06/25 01:52:07 DEBUG : two: size = 3 OK
2026/06/25 01:52:07 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/06/25 01:52:07 DEBUG : two: Destination found in --compare-dest, skipping
2026/06/25 01:52:09 DEBUG : two: Need to transfer - File not found at Destination
2026/06/25 01:52:09 DEBUG : two: size = 5 (Local file system at /tmp/rclone2616790651)
2026/06/25 01:52:09 DEBUG : two: size = 3 (Dropbox root 'rclone-test-zeraqoj4ravi/CompareDest')
2026/06/25 01:52:09 DEBUG : two: Sizes differ
2026/06/25 01:52:09 DEBUG : two: Uploading chunk 1/1
2026/06/25 01:52:10 DEBUG : two: Uploading chunk 2/1
2026/06/25 01:52:10 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Adding "two" to batch
2026/06/25 01:52:11 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Batch idle for 500ms so committing
2026/06/25 01:52:11 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Committing sync batch length 1 starting with: two
2026/06/25 01:52:11 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi/dst': Committed sync batch length 1 starting with: two
2026/06/25 01:52:11 DEBUG : two: size = 5 OK
2026/06/25 01:52:11 DEBUG : two: dropbox = 25e70b9a05696acbcc5bd6fa74ba6fa580c9a58960a02390ae1d2f71d21c0378 OK
2026/06/25 01:52:11 INFO  : two: Copied (new)
2026/06/25 01:52:17 INFO  : Dropbox root 'rclone-test-zeraqoj4ravi/CompareDest': Committing uploads - please wait...
--- PASS: TestCopyFileCompareDest (29.55s)
PASS
2026/06/25 01:52:17 DEBUG : Dropbox root 'rclone-test-zeraqoj4ravi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestCheckSizeOnly|TestCopyFileCompareDest)$'" - Finished OK in 46.862830613s (try 2/5)