"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestSyncAfterRemovingAFileAndAddingAFile$'" - Starting (try 2/5)
2024/11/26 04:32:29 DEBUG : Creating backend with remote "TestDropbox:rclone-test-lexuguf5sowi"
2024/11/26 04:32:29 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/26 04:32:29 DEBUG : Creating backend with remote "/tmp/rclone3853765053"
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:180: Remote "Dropbox root 'rclone-test-lexuguf5sowi'", Local "Local file system at /tmp/rclone3853765053", Modify Window "1s"
2024/11/26 04:32:31 DEBUG : potato: Uploading chunk 1/1
2024/11/26 04:32:31 DEBUG : potato: Uploading chunk 2/1
2024/11/26 04:32:32 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Adding "potato" to batch
2024/11/26 04:32:32 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Batch idle for 500ms so committing
2024/11/26 04:32:32 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Committing sync batch length 1 starting with: potato
2024/11/26 04:32:33 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Committed sync batch length 1 starting with: potato
2024/11/26 04:32:33 DEBUG : empty space: Uploading chunk 1/1
2024/11/26 04:32:34 DEBUG : empty space: Uploading chunk 2/1
2024/11/26 04:32:34 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Adding "empty space" to batch
2024/11/26 04:32:35 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Batch idle for 500ms so committing
2024/11/26 04:32:35 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Committing sync batch length 1 starting with: empty space
2024/11/26 04:32:36 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Committed sync batch length 1 starting with: empty space
2024/11/26 04:32:36 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/26 04:32:36 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/11/26 04:32:36 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Waiting for checks to finish
2024/11/26 04:32:36 DEBUG : empty space: Unchanged skipping
2024/11/26 04:32:36 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Waiting for transfers to finish
2024/11/26 04:32:37 DEBUG : potato2: Uploading chunk 1/1
2024/11/26 04:32:37 DEBUG : potato2: Uploading chunk 2/1
2024/11/26 04:32:38 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Adding "potato2" to batch
2024/11/26 04:32:38 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Batch idle for 500ms so committing
2024/11/26 04:32:38 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Committing sync batch length 1 starting with: potato2
2024/11/26 04:32:39 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Committed sync batch length 1 starting with: potato2
2024/11/26 04:32:39 DEBUG : potato2: dropbox = a979481df794fed9c3990a6a422e0b1044ac802c15fab13af9c687f8bdbee01a OK
2024/11/26 04:32:39 INFO  : potato2: Copied (new)
2024/11/26 04:32:39 DEBUG : Waiting for deletions to finish
2024/11/26 04:32:40 INFO  : potato: Deleted
    run.go:180: Remote "Dropbox root 'rclone-test-lexuguf5sowi'", Local "Local file system at /tmp/rclone3853765053", Modify Window "1s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (13.41s)
PASS
2024/11/26 04:32:43 DEBUG : Dropbox root 'rclone-test-lexuguf5sowi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^TestSyncAfterRemovingAFileAndAddingAFile$'" - Finished OK in 14.335193463s (try 2/5)