"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestSyncBackupDirWithSuffixKeepExtension|TestSyncOverlapWithFilter)$'" - Starting (try 4/5)
2025/09/26 03:57:08 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vazetaw2vuqo"
2025/09/26 03:57:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/26 03:57:08 DEBUG : Starting OpenDrive session with ID: 09702c1faf4e1ca67ad6811c733c3575a44f863207701e966d67de27a21d2be6
2025/09/26 03:57:09 DEBUG : Creating backend with remote "/tmp/rclone3598661134"
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "OpenDrive root 'rclone-test-vazetaw2vuqo'", Local "Local file system at /tmp/rclone3598661134", Modify Window "1s"
2025/09/26 03:57:09 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vazetaw2vuqo/rclone-sync-test"
2025/09/26 03:57:15 DEBUG : Starting OpenDrive session with ID: eacc1b6cfcd717017776fc096c48e48f20dda336b8a3a6e0555d1353f452e52e
2025/09/26 03:57:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vazetaw2vuqo/rclone-sync-test-include/layer2"
2025/09/26 03:57:17 DEBUG : Starting OpenDrive session with ID: 9e28c13b6569d9c6d9ec535ba1fdd579755af89656bcc69593b5f4517446e67f
2025/09/26 03:57:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vazetaw2vuqo/rclone-sync-test-ignore-file"
2025/09/26 03:57:19 DEBUG : Starting OpenDrive session with ID: ebdc272d69c61fe2c3a56c6f536166db2b696f4a97ab50385b8b8cdb22a6d303
2025/09/26 03:57:21 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0
2025/09/26 03:57:23 DEBUG : rclone-sync-test: Excluded
2025/09/26 03:57:23 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/26 03:57:24 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/09/26 03:57:24 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/rclone-sync-test': Waiting for checks to finish
2025/09/26 03:57:24 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/rclone-sync-test': Waiting for transfers to finish
2025/09/26 03:57:24 DEBUG : Waiting for deletions to finish
2025/09/26 03:57:24 INFO  : There was nothing to transfer
2025/09/26 03:57:24 DEBUG : rclone-sync-test: Excluded
2025/09/26 03:57:24 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/26 03:57:25 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/09/26 03:57:25 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo': Waiting for checks to finish
2025/09/26 03:57:25 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo': Waiting for transfers to finish
2025/09/26 03:57:25 DEBUG : Waiting for deletions to finish
2025/09/26 03:57:25 INFO  : rclone-sync-test-include: Removing directory
2025/09/26 03:57:25 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo': deleted 1 directories
2025/09/26 03:57:25 INFO  : There was nothing to transfer
2025/09/26 03:57:26 DEBUG : rclone-sync-test: Excluded
2025/09/26 03:57:26 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/26 03:57:26 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/rclone-sync-test-include/layer2': Waiting for checks to finish
2025/09/26 03:57:26 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/rclone-sync-test-include/layer2': Waiting for transfers to finish
2025/09/26 03:57:26 DEBUG : Waiting for deletions to finish
2025/09/26 03:57:26 INFO  : There was nothing to transfer
2025/09/26 03:57:26 DEBUG : rclone-sync-test: Excluded
2025/09/26 03:57:27 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/26 03:57:27 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo': Waiting for checks to finish
2025/09/26 03:57:27 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo': Waiting for transfers to finish
2025/09/26 03:57:27 DEBUG : Waiting for deletions to finish
2025/09/26 03:57:27 INFO  : There was nothing to transfer
2025/09/26 03:57:27 DEBUG : Excluded
2025/09/26 03:57:27 DEBUG : rclone-sync-test: Excluded
2025/09/26 03:57:27 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/09/26 03:57:27 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/rclone-sync-test-ignore-file': Waiting for checks to finish
2025/09/26 03:57:27 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/rclone-sync-test-ignore-file': Waiting for transfers to finish
2025/09/26 03:57:27 DEBUG : Waiting for deletions to finish
2025/09/26 03:57:27 INFO  : There was nothing to transfer
    run.go:125: removing dir "rclone-sync-test-ignore-file" failed - try 1/3: folder not empty
--- PASS: TestSyncOverlapWithFilter (21.40s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:180: Remote "OpenDrive root 'rclone-test-vazetaw2vuqo'", Local "Local file system at /tmp/rclone3598661134", Modify Window "1s"
2025/09/26 03:57:31 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/09/26 03:57:33 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/09/26 03:57:35 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/09/26 03:57:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vazetaw2vuqo/dst"
2025/09/26 03:57:37 DEBUG : Starting OpenDrive session with ID: 91b21039dcf72030759c8e15421ac65f5c5459a71c79cc70b8ca2b46804b8ed9
2025/09/26 03:57:37 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vazetaw2vuqo/backup"
2025/09/26 03:57:38 DEBUG : Starting OpenDrive session with ID: 66c7f84c9d944c6c58c6ce95f81112257e059887b4a77bf89ab055af50b97d0b
2025/09/26 03:57:39 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/dst': Waiting for checks to finish
2025/09/26 03:57:39 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/09/26 03:57:39 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/09/26 03:57:39 DEBUG : two: Unchanged skipping
2025/09/26 03:57:40 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/09/26 03:57:40 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/dst': Waiting for transfers to finish
2025/09/26 03:57:41 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/09/26 03:57:42 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/09/26 03:57:42 INFO  : one: Copied (new)
2025/09/26 03:57:42 DEBUG : Waiting for deletions to finish
2025/09/26 03:57:42 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/09/26 03:57:42 INFO  : three.txt: Moved into backup dir
2025/09/26 03:57:44 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2025/09/26 03:57:45 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/09/26 03:57:45 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/dst': Waiting for checks to finish
2025/09/26 03:57:45 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/09/26 03:57:45 DEBUG : two: Unchanged skipping
2025/09/26 03:57:46 INFO  : one-2019-01-01: Deleted
2025/09/26 03:57:46 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/09/26 03:57:46 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo/dst': Waiting for transfers to finish
2025/09/26 03:57:47 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/09/26 03:57:48 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/09/26 03:57:48 INFO  : one: Copied (new)
2025/09/26 03:57:48 DEBUG : Waiting for deletions to finish
2025/09/26 03:57:49 INFO  : three-2019-01-01.txt: Deleted
2025/09/26 03:57:49 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/09/26 03:57:49 INFO  : three.txt: Moved into backup dir
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (28.70s)
=== RUN   TestFixCase
    run.go:180: Remote "OpenDrive root 'rclone-test-vazetaw2vuqo'", Local "Local file system at /tmp/rclone3598661134", Modify Window "1s"
2025/09/26 03:58:00 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0
2025/09/26 03:58:01 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0
2025/09/26 03:58:04 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 0, size=8, remain=0
2025/09/26 03:58:06 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 0, size=8, remain=0
2025/09/26 03:58:08 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/09/26 03:58:08 DEBUG : existing: Unchanged skipping
2025/09/26 03:58:08 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8)
2025/09/26 03:58:09 DEBUG : subdira-rclone-move-fayaqal6: same parent dir (ODFfOTQ2MjAxM19TZjlFMA) - using folder/rename instead of move_copy
2025/09/26 03:58:09 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-tuluvuv0
2025/09/26 03:58:09 DEBUG : EXISTING: moving to existing-rclone-move-cimewog7
2025/09/26 03:58:09 INFO  : OpenDrive root 'rclone-test-vazetaw2vuqo': Can't DirMove - falling back to file moves: can't copy directory - destination already exists
2025/09/26 03:58:09 DEBUG : EXISTINGBUTDIFFERENT: same parent dir (ODFfOTQ2MjAxM19TZjlFMA) - using file/rename instead of move_copy for existingbutdifferent-rclone-move-tuluvuv0
2025/09/26 03:58:09 DEBUG : EXISTING: same parent dir (ODFfOTQ2MjAxM19TZjlFMA) - using file/rename instead of move_copy for existing-rclone-move-cimewog7
2025/09/26 03:58:09 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-tuluvuv0
2025/09/26 03:58:09 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent
2025/09/26 03:58:10 INFO  : EXISTING: Moved (server-side) to: existing-rclone-move-cimewog7
2025/09/26 03:58:10 DEBUG : EXISTING: moving to existing
2025/09/26 03:58:10 DEBUG : existingbutdifferent-rclone-move-tuluvuv0: same parent dir (ODFfOTQ2MjAxM19TZjlFMA) - using file/rename instead of move_copy for existingbutdifferent
2025/09/26 03:58:10 DEBUG : existing-rclone-move-cimewog7: same parent dir (ODFfOTQ2MjAxM19TZjlFMA) - using file/rename instead of move_copy for existing
2025/09/26 03:58:10 INFO  : existingbutdifferent-rclone-move-tuluvuv0: Moved (server-side) to: existingbutdifferent
2025/09/26 03:58:10 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent
2025/09/26 03:58:10 INFO  : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent
2025/09/26 03:58:10 INFO  : existing-rclone-move-cimewog7: Moved (server-side) to: existing
2025/09/26 03:58:10 INFO  : EXISTING: Moved (server-side) to: existing
2025/09/26 03:58:10 INFO  : EXISTING: Fixed case by renaming to: existing
2025/09/26 03:58:10 DEBUG : existingbutdifferent: Uploading chunk 0, size=5, remain=0
2025/09/26 03:58:11 INFO  : subdira/subdirb/SUBDIRC/HELLO: Deleted
2025/09/26 03:58:11 INFO  : subdira/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Deleted
2025/09/26 03:58:11 ERROR : subdira-rclone-move-fayaqal6/subdirb/SUBDIRC/HELLO: Couldn't move: failed to get fileinfo: File is deleted (Error 404)
2025/09/26 03:58:11 ERROR : subdira-rclone-move-fayaqal6/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Couldn't move: failed to get fileinfo: Get "https://dev.opendrive.com/api/v1/file/info.json/ODFfMTA0NjQyMjg3X1pZYmdj?session_id=09702c1faf4e1ca67ad6811c733c3575a44f863207701e966d67de27a21d2be6": failed to get fileinfo: File is deleted (Error 404)
2025/09/26 03:58:11 ERROR : SUBDIRA: Error while attempting to rename to subdira: RenameDir renames: failed to get fileinfo: File is deleted (Error 404)
2025/09/26 03:58:11 DEBUG : subdira/subdirb/subdirc/hello: Need to transfer - File not found at Destination
2025/09/26 03:58:11 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Need to transfer - File not found at Destination
2025/09/26 03:58:11 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo': Waiting for checks to finish
2025/09/26 03:58:11 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo': Waiting for transfers to finish
2025/09/26 03:58:11 DEBUG : existingbutdifferent: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK
2025/09/26 03:58:11 INFO  : existingbutdifferent: Copied (replaced existing)
2025/09/26 03:58:12 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 0, size=5, remain=0
2025/09/26 03:58:13 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 0, size=5, remain=0
2025/09/26 03:58:13 DEBUG : subdira/subdirb/subdirc/hello: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK
2025/09/26 03:58:13 INFO  : subdira/subdirb/subdirc/hello: Copied (new)
2025/09/26 03:58:14 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK
2025/09/26 03:58:14 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (new)
2025/09/26 03:58:14 ERROR : OpenDrive root 'rclone-test-vazetaw2vuqo': not deleting files as there were IO errors
2025/09/26 03:58:14 ERROR : OpenDrive root 'rclone-test-vazetaw2vuqo': not deleting directories as there were IO errors
    sync_test.go:2685: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2685
        	Error:      	Received unexpected error:
        	            	RenameDir renames: failed to get fileinfo: File is deleted (Error 404)
        	Test:       	TestFixCase
    run.go:125: removing dir "subdira-rclone-move-fayaqal6/subdirb/SUBDIRC/subdird" failed - try 1/3: folder not empty
--- FAIL: TestFixCase (19.25s)
FAIL
2025/09/26 03:58:18 DEBUG : OpenDrive root 'rclone-test-vazetaw2vuqo': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestSyncBackupDirWithSuffixKeepExtension|TestSyncOverlapWithFilter)$'" - Finished ERROR in 1m10.671523015s (try 4/5): exit status 1: Failed [TestFixCase]