"./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncCopyDest)$'" - Starting (try 2/5) 2024/11/03 04:40:25 DEBUG : Creating backend with remote "TestDropbox:rclone-test-kaxixoq5noxe" 2024/11/03 04:40:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/11/03 04:40:25 DEBUG : Creating backend with remote "/tmp/rclone1095065246" === RUN TestServerSideCopyOverSelf run.go:180: Remote "Dropbox root 'rclone-test-kaxixoq5noxe'", Local "Local file system at /tmp/rclone1095065246", Modify Window "1s" 2024/11/03 04:40:25 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/) 2024/11/03 04:40:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/11/03 04:40:26 DEBUG : pacer: Reducing sleep to 15ms 2024/11/03 04:40:26 DEBUG : pacer: Reducing sleep to 11.25ms 2024/11/03 04:40:26 DEBUG : sub dir/hello world: Uploading chunk 1/1 2024/11/03 04:40:27 DEBUG : pacer: Reducing sleep to 10ms 2024/11/03 04:40:27 DEBUG : sub dir/hello world: Uploading chunk 2/1 2024/11/03 04:40:27 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Adding "sub dir/hello world" to batch 2024/11/03 04:40:28 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Batch idle for 500ms so committing 2024/11/03 04:40:28 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committing sync batch length 1 starting with: sub dir/hello world 2024/11/03 04:40:28 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committed sync batch length 1 starting with: sub dir/hello world 2024/11/03 04:40:29 DEBUG : Creating backend with remote "TestDropbox:rclone-test-toxixin0vayo" sync_test.go:626: Server side copy (if possible) Dropbox root 'rclone-test-kaxixoq5noxe' -> Dropbox root 'rclone-test-toxixin0vayo' 2024/11/03 04:40:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/11/03 04:40:29 DEBUG : Dropbox root 'rclone-test-toxixin0vayo': Waiting for checks to finish 2024/11/03 04:40:29 DEBUG : Dropbox root 'rclone-test-toxixin0vayo': Waiting for transfers to finish 2024/11/03 04:40:31 DEBUG : sub dir/hello world: dropbox = bc62d4b80d9e36da29c16c5d4d9f11731f36052c72401a76c23c0fb5a9b74423 OK 2024/11/03 04:40:31 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Dropbox root 'rclone-test-kaxixoq5noxe'", Local "Local file system at /tmp/rclone1095065246", Modify Window "1s" 2024/11/03 04:40:31 DEBUG : sub dir/hello world: Uploading chunk 1/1 2024/11/03 04:40:32 DEBUG : sub dir/hello world: Uploading chunk 2/1 2024/11/03 04:40:32 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Adding "sub dir/hello world" to batch 2024/11/03 04:40:33 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Batch idle for 500ms so committing 2024/11/03 04:40:33 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committing sync batch length 1 starting with: sub dir/hello world 2024/11/03 04:40:33 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committed sync batch length 1 starting with: sub dir/hello world 2024/11/03 04:40:35 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2024/11/03 04:40:35 DEBUG : Dropbox root 'rclone-test-toxixin0vayo': Waiting for checks to finish 2024/11/03 04:40:35 DEBUG : Dropbox root 'rclone-test-toxixin0vayo': Waiting for transfers to finish 2024/11/03 04:40:35 ERROR : sub dir/hello world: Failed to copy: copy failed: to/conflict/file/... sync_test.go:639: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:639 Error: Received unexpected error: copy failed: to/conflict/file/... Test: TestServerSideCopyOverSelf 2024/11/03 04:40:35 DEBUG : Dropbox root 'rclone-test-toxixin0vayo': Purge remote --- FAIL: TestServerSideCopyOverSelf (14.99s) === RUN TestSyncCopyDest run.go:180: Remote "Dropbox root 'rclone-test-kaxixoq5noxe'", Local "Local file system at /tmp/rclone1095065246", Modify Window "1s" 2024/11/03 04:40:40 DEBUG : Creating backend with remote "TestDropbox:rclone-test-kaxixoq5noxe/dst" 2024/11/03 04:40:40 DEBUG : Creating backend with remote "TestDropbox:rclone-test-kaxixoq5noxe/CopyDest" 2024/11/03 04:40:41 DEBUG : one: Need to transfer - File not found at Destination 2024/11/03 04:40:41 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for checks to finish 2024/11/03 04:40:41 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for transfers to finish 2024/11/03 04:40:41 DEBUG : one: Uploading chunk 1/1 2024/11/03 04:40:42 DEBUG : one: Uploading chunk 2/1 2024/11/03 04:40:42 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Adding "one" to batch 2024/11/03 04:40:42 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Batch idle for 500ms so committing 2024/11/03 04:40:42 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Committing sync batch length 1 starting with: one 2024/11/03 04:40:43 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Committed sync batch length 1 starting with: one 2024/11/03 04:40:43 DEBUG : one: dropbox = fe8d7a873dc48961a6af334c996b2cb3ce37149d5ce9c9253952a54c6a92c1ad OK 2024/11/03 04:40:43 INFO : one: Copied (new) 2024/11/03 04:40:43 DEBUG : Waiting for deletions to finish 2024/11/03 04:40:44 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/11/03 04:40:44 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for checks to finish 2024/11/03 04:40:45 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for transfers to finish 2024/11/03 04:40:45 DEBUG : one: Uploading chunk 1/1 2024/11/03 04:40:45 DEBUG : one: Uploading chunk 2/1 2024/11/03 04:40:46 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Adding "one" to batch 2024/11/03 04:40:46 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Batch idle for 500ms so committing 2024/11/03 04:40:46 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Committing sync batch length 1 starting with: one 2024/11/03 04:40:47 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Committed sync batch length 1 starting with: one 2024/11/03 04:40:47 DEBUG : one: dropbox = 7da8d117f9fb226817d81adc15c72720c9879c8b7fb821d27f217d198bd79d0d OK 2024/11/03 04:40:47 INFO : one: Copied (replaced existing) 2024/11/03 04:40:47 DEBUG : Waiting for deletions to finish 2024/11/03 04:40:48 DEBUG : dst/one: Uploading chunk 1/1 2024/11/03 04:40:48 DEBUG : dst/one: Uploading chunk 2/1 2024/11/03 04:40:48 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Adding "dst/one" to batch 2024/11/03 04:40:49 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Batch idle for 500ms so committing 2024/11/03 04:40:49 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committing sync batch length 1 starting with: dst/one 2024/11/03 04:40:50 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committed sync batch length 1 starting with: dst/one 2024/11/03 04:40:50 DEBUG : CopyDest/one: Uploading chunk 1/1 2024/11/03 04:40:50 DEBUG : CopyDest/one: Uploading chunk 2/1 2024/11/03 04:40:51 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Adding "CopyDest/one" to batch 2024/11/03 04:40:51 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Batch idle for 500ms so committing 2024/11/03 04:40:51 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committing sync batch length 1 starting with: CopyDest/one 2024/11/03 04:40:52 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committed sync batch length 1 starting with: CopyDest/one 2024/11/03 04:40:53 DEBUG : Creating backend with remote "TestDropbox:rclone-test-kaxixoq5noxe/BackupDir" 2024/11/03 04:40:54 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/11/03 04:40:54 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for checks to finish 2024/11/03 04:40:55 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/11/03 04:40:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/11/03 04:40:56 INFO : one: Moved (server-side) 2024/11/03 04:40:57 DEBUG : pacer: low level retry 1/10 (error too_many_write_operations/..) 2024/11/03 04:40:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/11/03 04:40:57 DEBUG : pacer: low level retry 2/10 (error too_many_write_operations/.) 2024/11/03 04:40:57 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/11/03 04:40:58 DEBUG : pacer: Reducing sleep to 30ms 2024/11/03 04:40:58 DEBUG : one: dropbox = 7da8d117f9fb226817d81adc15c72720c9879c8b7fb821d27f217d198bd79d0d OK 2024/11/03 04:40:58 INFO : one: Copied (server-side copy) 2024/11/03 04:40:58 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/11/03 04:40:58 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for transfers to finish 2024/11/03 04:40:58 DEBUG : Waiting for deletions to finish 2024/11/03 04:40:59 DEBUG : CopyDest/two: Uploading chunk 1/1 2024/11/03 04:40:59 DEBUG : CopyDest/two: Uploading chunk 2/1 2024/11/03 04:41:00 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Adding "CopyDest/two" to batch 2024/11/03 04:41:00 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Batch idle for 500ms so committing 2024/11/03 04:41:00 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committing sync batch length 1 starting with: CopyDest/two 2024/11/03 04:41:01 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committed sync batch length 1 starting with: CopyDest/two 2024/11/03 04:41:02 DEBUG : pacer: Reducing sleep to 22.5ms 2024/11/03 04:41:02 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/11/03 04:41:03 DEBUG : pacer: Reducing sleep to 16.875ms 2024/11/03 04:41:03 DEBUG : two: dropbox = 313db63e0283ab2a9bc72e9a0ac749da1d3acf708fcd1a7848767244dd2f17e8 OK 2024/11/03 04:41:03 INFO : two: Copied (server-side copy) 2024/11/03 04:41:03 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/11/03 04:41:03 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/11/03 04:41:03 DEBUG : one: Unchanged skipping 2024/11/03 04:41:03 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for checks to finish 2024/11/03 04:41:03 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for transfers to finish 2024/11/03 04:41:03 DEBUG : Waiting for deletions to finish 2024/11/03 04:41:04 DEBUG : pacer: Reducing sleep to 12.65625ms 2024/11/03 04:41:04 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/11/03 04:41:04 DEBUG : one: Unchanged skipping 2024/11/03 04:41:04 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/11/03 04:41:04 DEBUG : two: Unchanged skipping 2024/11/03 04:41:04 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for checks to finish 2024/11/03 04:41:04 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for transfers to finish 2024/11/03 04:41:04 DEBUG : Waiting for deletions to finish 2024/11/03 04:41:04 INFO : There was nothing to transfer 2024/11/03 04:41:05 DEBUG : CopyDest/three: Uploading chunk 1/1 2024/11/03 04:41:05 DEBUG : CopyDest/three: Uploading chunk 2/1 2024/11/03 04:41:05 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Adding "CopyDest/three" to batch 2024/11/03 04:41:06 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Batch idle for 500ms so committing 2024/11/03 04:41:06 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committing sync batch length 1 starting with: CopyDest/three 2024/11/03 04:41:06 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Committed sync batch length 1 starting with: CopyDest/three 2024/11/03 04:41:08 DEBUG : pacer: Reducing sleep to 10ms 2024/11/03 04:41:08 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/11/03 04:41:08 DEBUG : three: Destination not found in --copy-dest 2024/11/03 04:41:08 DEBUG : three: Need to transfer - File not found at Destination 2024/11/03 04:41:08 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/11/03 04:41:08 DEBUG : one: Unchanged skipping 2024/11/03 04:41:08 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/11/03 04:41:08 DEBUG : two: Unchanged skipping 2024/11/03 04:41:08 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for checks to finish 2024/11/03 04:41:08 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Waiting for transfers to finish 2024/11/03 04:41:08 DEBUG : three: Uploading chunk 1/1 2024/11/03 04:41:09 DEBUG : three: Uploading chunk 2/1 2024/11/03 04:41:09 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Adding "three" to batch 2024/11/03 04:41:10 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Batch idle for 500ms so committing 2024/11/03 04:41:10 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Committing sync batch length 1 starting with: three 2024/11/03 04:41:10 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe/dst': Committed sync batch length 1 starting with: three 2024/11/03 04:41:10 DEBUG : three: dropbox = 78af7ad260025156e50fd9648fd9a36509cd3541c9a8d02597e9b3c598937cbd OK 2024/11/03 04:41:10 INFO : three: Copied (new) 2024/11/03 04:41:10 DEBUG : Waiting for deletions to finish 2024/11/03 04:41:19 INFO : Dropbox root 'rclone-test-kaxixoq5noxe/CopyDest': Committing uploads - please wait... 2024/11/03 04:41:19 INFO : Dropbox root 'rclone-test-kaxixoq5noxe/BackupDir': Committing uploads - please wait... --- PASS: TestSyncCopyDest (39.13s) FAIL 2024/11/03 04:41:19 DEBUG : Dropbox root 'rclone-test-kaxixoq5noxe': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestDropbox: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncCopyDest)$'" - Finished ERROR in 54.966112089s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf]