"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirWithSuffix)$'" - Starting (try 3/5) 2024/10/30 01:30:24 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-robizac3koho" 2024/10/30 01:30:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/10/30 01:30:25 DEBUG : Starting OpenDrive session with ID: 524beb1a07678acfcd9a43c63a5c154ad7d172aa3d1010bea9fbeea2fb15e19c 2024/10/30 01:30:25 DEBUG : Creating backend with remote "/tmp/rclone2431236064" === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "OpenDrive root 'rclone-test-robizac3koho'", Local "Local file system at /tmp/rclone2431236064", Modify Window "1s" 2024/10/30 01:30:27 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2024/10/30 01:30:29 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2024/10/30 01:30:30 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2024/10/30 01:30:32 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-robizac3koho/dst" 2024/10/30 01:30:33 DEBUG : Starting OpenDrive session with ID: 57816ed795890f8b2db1a6c1c7319003d8d2b90a6222ad67c8f9210701b37b8b 2024/10/30 01:30:33 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-robizac3koho/backup" 2024/10/30 01:30:34 DEBUG : Starting OpenDrive session with ID: 04c449cf7b7eee20a4db204cb3c2053398656b5fcd178cc741e2d06c25e1bf3c 2024/10/30 01:30:35 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/10/30 01:30:35 DEBUG : OpenDrive root 'rclone-test-robizac3koho/dst': Waiting for checks to finish 2024/10/30 01:30:35 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/10/30 01:30:35 DEBUG : two: Unchanged skipping 2024/10/30 01:30:36 INFO : one: Moved (server-side) to: one.bak 2024/10/30 01:30:36 DEBUG : OpenDrive root 'rclone-test-robizac3koho/dst': Waiting for transfers to finish 2024/10/30 01:30:37 DEBUG : one: Uploading chunk 0, size=4, remain=0 2024/10/30 01:30:38 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2024/10/30 01:30:38 INFO : one: Copied (new) 2024/10/30 01:30:38 DEBUG : Waiting for deletions to finish 2024/10/30 01:30:38 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/10/30 01:30:38 INFO : three.txt: Moved into backup dir 2024/10/30 01:30:40 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2024/10/30 01:30:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/10/30 01:30:42 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/10/30 01:30:42 DEBUG : two: Unchanged skipping 2024/10/30 01:30:42 DEBUG : OpenDrive root 'rclone-test-robizac3koho/dst': Waiting for checks to finish 2024/10/30 01:30:42 INFO : one.bak: Deleted 2024/10/30 01:30:42 INFO : one: Moved (server-side) to: one.bak 2024/10/30 01:30:42 DEBUG : OpenDrive root 'rclone-test-robizac3koho/dst': Waiting for transfers to finish 2024/10/30 01:30:43 DEBUG : one: Uploading chunk 0, size=5, remain=0 2024/10/30 01:30:44 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2024/10/30 01:30:44 INFO : one: Copied (new) 2024/10/30 01:30:44 DEBUG : Waiting for deletions to finish 2024/10/30 01:30:44 INFO : three.txt.bak: Deleted 2024/10/30 01:30:45 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/10/30 01:30:45 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (21.99s) === RUN TestFixCase run.go:180: Remote "OpenDrive root 'rclone-test-robizac3koho'", Local "Local file system at /tmp/rclone2431236064", Modify Window "1s" 2024/10/30 01:30:48 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0 2024/10/30 01:30:50 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0 2024/10/30 01:30:53 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 0, size=8, remain=0 2024/10/30 01:30:55 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 0, size=8, remain=0 2024/10/30 01:30:57 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/10/30 01:30:57 DEBUG : existing: Unchanged skipping 2024/10/30 01:30:57 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8) 2024/10/30 01:30:57 DEBUG : subdira-rclone-move-tanatix8: same parent dir (ODFfNjY5NjkxNV9jVHBQTA) - using folder/rename instead of move_copy 2024/10/30 01:30:57 DEBUG : EXISTING: moving to existing-rclone-move-guyuhoz2 2024/10/30 01:30:57 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-winukiw8 2024/10/30 01:30:58 DEBUG : subdira: same parent dir (ODFfNjY5NjkxNV9jVHBQTA) - using folder/rename instead of move_copy 2024/10/30 01:30:58 DEBUG : EXISTINGBUTDIFFERENT: same parent dir (ODFfNjY5NjkxNV9jVHBQTA) - using file/rename instead of move_copy for existingbutdifferent-rclone-move-winukiw8 2024/10/30 01:30:58 DEBUG : EXISTING: same parent dir (ODFfNjY5NjkxNV9jVHBQTA) - using file/rename instead of move_copy for existing-rclone-move-guyuhoz2 2024/10/30 01:30:58 INFO : SUBDIRA: Fixed case by renaming to: subdira 2024/10/30 01:30:58 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-winukiw8 2024/10/30 01:30:58 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2024/10/30 01:30:58 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-guyuhoz2 2024/10/30 01:30:58 DEBUG : EXISTING: moving to existing 2024/10/30 01:30:59 DEBUG : existing-rclone-move-guyuhoz2: same parent dir (ODFfNjY5NjkxNV9jVHBQTA) - using file/rename instead of move_copy for existing 2024/10/30 01:30:59 DEBUG : existingbutdifferent-rclone-move-winukiw8: same parent dir (ODFfNjY5NjkxNV9jVHBQTA) - using file/rename instead of move_copy for existingbutdifferent 2024/10/30 01:30:59 DEBUG : subdira/subdirb-rclone-move-kahuman1: same parent dir (ODFfNjY5NjkxOF9WOUlncA) - using folder/rename instead of move_copy 2024/10/30 01:30:59 INFO : existing-rclone-move-guyuhoz2: Moved (server-side) to: existing 2024/10/30 01:30:59 INFO : EXISTING: Moved (server-side) to: existing 2024/10/30 01:30:59 INFO : EXISTING: Fixed case by renaming to: existing 2024/10/30 01:30:59 INFO : existingbutdifferent-rclone-move-winukiw8: Moved (server-side) to: existingbutdifferent 2024/10/30 01:30:59 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2024/10/30 01:30:59 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2024/10/30 01:30:59 DEBUG : existingbutdifferent: Uploading chunk 0, size=5, remain=0 2024/10/30 01:30:59 DEBUG : subdira/subdirb: same parent dir (ODFfNjY5NjkxOF9WOUlncA) - using folder/rename instead of move_copy 2024/10/30 01:31:00 INFO : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb 2024/10/30 01:31:00 DEBUG : existingbutdifferent: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2024/10/30 01:31:00 INFO : existingbutdifferent: Copied (replaced existing) 2024/10/30 01:31:00 DEBUG : subdira/subdirb/subdirc-rclone-move-yoyifof7: same parent dir (ODFfNjY5NjkxOV8zZnFVQg) - using folder/rename instead of move_copy 2024/10/30 01:31:01 DEBUG : subdira/subdirb/subdirc: same parent dir (ODFfNjY5NjkxOV8zZnFVQg) - using folder/rename instead of move_copy 2024/10/30 01:31:01 INFO : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc 2024/10/30 01:31:01 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8) 2024/10/30 01:31:02 DEBUG : subdira/subdirb/subdirc/subdird-rclone-move-pijomip1: same parent dir (ODFfNjY5NjkyMF9Qd25Bdg) - using folder/rename instead of move_copy 2024/10/30 01:31:02 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-kujagep6 2024/10/30 01:31:03 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: same parent dir (ODFfNjY5NjkyMF9Qd25Bdg) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello-rclone-move-kujagep6 2024/10/30 01:31:03 DEBUG : subdira/subdirb/subdirc/subdird: same parent dir (ODFfNjY5NjkyMF9Qd25Bdg) - using folder/rename instead of move_copy 2024/10/30 01:31:03 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-kujagep6 2024/10/30 01:31:03 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello 2024/10/30 01:31:03 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird 2024/10/30 01:31:03 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Need to transfer - File not found at Destination 2024/10/30 01:31:03 DEBUG : subdira/subdirb/subdirc/hello-rclone-move-kujagep6: same parent dir (ODFfNjY5NjkyMF9Qd25Bdg) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello 2024/10/30 01:31:03 DEBUG : OpenDrive root 'rclone-test-robizac3koho': Waiting for checks to finish 2024/10/30 01:31:03 INFO : subdira/subdirb/subdirc/hello-rclone-move-kujagep6: Moved (server-side) to: subdira/subdirb/subdirc/hello 2024/10/30 01:31:03 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello 2024/10/30 01:31:03 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello 2024/10/30 01:31:03 DEBUG : OpenDrive root 'rclone-test-robizac3koho': Waiting for transfers to finish 2024/10/30 01:31:03 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 0, size=5, remain=0 2024/10/30 01:31:03 ERROR : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Failed to copy: failed to make directory: File or folder with such name already exists (Error 409) 2024/10/30 01:31:04 DEBUG : subdira/subdirb/subdirc/hello: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2024/10/30 01:31:04 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) 2024/10/30 01:31:04 ERROR : OpenDrive root 'rclone-test-robizac3koho': not deleting files as there were IO errors 2024/10/30 01:31:04 ERROR : OpenDrive root 'rclone-test-robizac3koho': not deleting directories as there were IO errors sync_test.go:2655: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2655 Error: Received unexpected error: failed to make directory: File or folder with such name already exists (Error 409) Test: TestFixCase --- FAIL: TestFixCase (20.20s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-robizac3koho'", Local "Local file system at /tmp/rclone2431236064", Modify Window "1s" 2024/10/30 01:31:08 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/10/30 01:31:08 DEBUG : sub dir: Making directory 2024/10/30 01:31:08 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "OpenDrive root 'rclone-test-robizac3koho'", Local "Local file system at /tmp/rclone2431236064", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-robizac3koho'", Local "Local file system at /tmp/rclone2431236064", Modify Window "1s" --- PASS: TestNothingToTransferWithEmptyDirs (17.11s) FAIL 2024/10/30 01:31:25 DEBUG : OpenDrive root 'rclone-test-robizac3koho': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirWithSuffix)$'" - Finished ERROR in 1m0.672510171s (try 3/5): exit status 1: Failed [TestFixCase]