"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirWithSuffix)$'" - Starting (try 5/5) 2025/01/22 05:11:27 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-regosuk4zime" 2025/01/22 05:11:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 05:11:28 DEBUG : Starting OpenDrive session with ID: a6179b811d8ce57d996f15b3c9b5ef458d90dbe107f43e176ac672d1945be28c 2025/01/22 05:11:28 DEBUG : Creating backend with remote "/tmp/rclone1799307520" === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "OpenDrive root 'rclone-test-regosuk4zime'", Local "Local file system at /tmp/rclone1799307520", Modify Window "1s" 2025/01/22 05:11:29 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/01/22 05:11:31 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/01/22 05:11:33 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/01/22 05:11:34 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-regosuk4zime/dst" 2025/01/22 05:11:35 DEBUG : Starting OpenDrive session with ID: de9be05d73027dfeb213a6d0e129c7485ef37fb442ab911a1c4ca1554471c71f 2025/01/22 05:11:35 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-regosuk4zime/backup" 2025/01/22 05:11:35 DEBUG : Starting OpenDrive session with ID: 99b79c7a7ca9b261327100711de9738d0fd06612dbe71e1e60955e8fca2cd577 2025/01/22 05:11:36 DEBUG : OpenDrive root 'rclone-test-regosuk4zime/dst': Waiting for checks to finish 2025/01/22 05:11:36 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:11:36 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/22 05:11:36 DEBUG : two: Unchanged skipping 2025/01/22 05:11:37 INFO : one: Moved (server-side) to: one.bak 2025/01/22 05:11:37 DEBUG : OpenDrive root 'rclone-test-regosuk4zime/dst': Waiting for transfers to finish 2025/01/22 05:11:38 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/01/22 05:11:39 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/01/22 05:11:39 INFO : one: Copied (new) 2025/01/22 05:11:39 DEBUG : Waiting for deletions to finish 2025/01/22 05:11:39 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/22 05:11:39 INFO : three.txt: Moved into backup dir 2025/01/22 05:11:41 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/01/22 05:11:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/22 05:11:42 DEBUG : OpenDrive root 'rclone-test-regosuk4zime/dst': Waiting for checks to finish 2025/01/22 05:11:42 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:11:42 DEBUG : two: Unchanged skipping 2025/01/22 05:11:43 INFO : one.bak: Deleted 2025/01/22 05:11:43 INFO : one: Moved (server-side) to: one.bak 2025/01/22 05:11:43 DEBUG : OpenDrive root 'rclone-test-regosuk4zime/dst': Waiting for transfers to finish 2025/01/22 05:11:44 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/01/22 05:11:45 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/01/22 05:11:45 INFO : one: Copied (new) 2025/01/22 05:11:45 DEBUG : Waiting for deletions to finish 2025/01/22 05:11:45 INFO : three.txt.bak: Deleted 2025/01/22 05:11:45 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/22 05:11:45 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (19.87s) === RUN TestFixCase run.go:180: Remote "OpenDrive root 'rclone-test-regosuk4zime'", Local "Local file system at /tmp/rclone1799307520", Modify Window "1s" 2025/01/22 05:11:49 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0 2025/01/22 05:11:51 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0 2025/01/22 05:11:53 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 0, size=8, remain=0 2025/01/22 05:11:55 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 0, size=8, remain=0 2025/01/22 05:11:57 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/01/22 05:11:57 DEBUG : existing: Unchanged skipping 2025/01/22 05:11:57 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8) 2025/01/22 05:11:57 DEBUG : subdira-rclone-move-gadomiy4: same parent dir (ODFfNzk4OTMyNV9UNmJEWQ) - using folder/rename instead of move_copy 2025/01/22 05:11:57 DEBUG : EXISTING: moving to existing-rclone-move-meboquc0 2025/01/22 05:11:58 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-bifufur2 2025/01/22 05:11:58 DEBUG : subdira: same parent dir (ODFfNzk4OTMyNV9UNmJEWQ) - using folder/rename instead of move_copy 2025/01/22 05:11:58 DEBUG : EXISTINGBUTDIFFERENT: same parent dir (ODFfNzk4OTMyNV9UNmJEWQ) - using file/rename instead of move_copy for existingbutdifferent-rclone-move-bifufur2 2025/01/22 05:11:58 INFO : SUBDIRA: Fixed case by renaming to: subdira 2025/01/22 05:11:58 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-bifufur2 2025/01/22 05:11:58 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2025/01/22 05:11:58 DEBUG : EXISTING: same parent dir (ODFfNzk4OTMyNV9UNmJEWQ) - using file/rename instead of move_copy for existing-rclone-move-meboquc0 2025/01/22 05:11:58 DEBUG : existingbutdifferent-rclone-move-bifufur2: same parent dir (ODFfNzk4OTMyNV9UNmJEWQ) - using file/rename instead of move_copy for existingbutdifferent 2025/01/22 05:11:58 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-meboquc0 2025/01/22 05:11:58 DEBUG : EXISTING: moving to existing 2025/01/22 05:11:59 INFO : existingbutdifferent-rclone-move-bifufur2: Moved (server-side) to: existingbutdifferent 2025/01/22 05:11:59 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2025/01/22 05:11:59 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2025/01/22 05:11:59 DEBUG : existingbutdifferent: Uploading chunk 0, size=5, remain=0 2025/01/22 05:11:59 DEBUG : subdira/subdirb-rclone-move-xajuzuc9: same parent dir (ODFfNzk4OTMyOF9PTEw5Ug) - using folder/rename instead of move_copy 2025/01/22 05:11:59 DEBUG : existing-rclone-move-meboquc0: same parent dir (ODFfNzk4OTMyNV9UNmJEWQ) - using file/rename instead of move_copy for existing 2025/01/22 05:11:59 INFO : existing-rclone-move-meboquc0: Moved (server-side) to: existing 2025/01/22 05:11:59 INFO : EXISTING: Moved (server-side) to: existing 2025/01/22 05:11:59 INFO : EXISTING: Fixed case by renaming to: existing 2025/01/22 05:11:59 DEBUG : subdira/subdirb: same parent dir (ODFfNzk4OTMyOF9PTEw5Ug) - using folder/rename instead of move_copy 2025/01/22 05:11:59 INFO : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb 2025/01/22 05:12:00 DEBUG : existingbutdifferent: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2025/01/22 05:12:00 INFO : existingbutdifferent: Copied (replaced existing) 2025/01/22 05:12:00 DEBUG : subdira/subdirb/subdirc-rclone-move-juvobiv6: same parent dir (ODFfNzk4OTMyOV90QlF6Vw) - using folder/rename instead of move_copy 2025/01/22 05:12:01 DEBUG : subdira/subdirb/subdirc: same parent dir (ODFfNzk4OTMyOV90QlF6Vw) - using folder/rename instead of move_copy 2025/01/22 05:12:01 INFO : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc 2025/01/22 05:12:01 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8) 2025/01/22 05:12:02 DEBUG : subdira/subdirb/subdirc/subdird-rclone-move-dekedax9: same parent dir (ODFfNzk4OTMzMF9GeXNrTA) - using folder/rename instead of move_copy 2025/01/22 05:12:02 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-cegequs6 2025/01/22 05:12:02 INFO : OpenDrive root 'rclone-test-regosuk4zime': Can't DirMove - falling back to file moves: can't copy directory - destination already exists 2025/01/22 05:12:02 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: same parent dir (ODFfNzk4OTMzMF9GeXNrTA) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello-rclone-move-cegequs6 2025/01/22 05:12:02 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-cegequs6 2025/01/22 05:12:02 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello 2025/01/22 05:12:02 DEBUG : subdira/subdirb/subdirc/hello-rclone-move-cegequs6: same parent dir (ODFfNzk4OTMzMF9GeXNrTA) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello 2025/01/22 05:12:03 INFO : subdira/subdirb/subdirc/hello-rclone-move-cegequs6: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/01/22 05:12:03 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/01/22 05:12:03 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello 2025/01/22 05:12:03 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Deleted 2025/01/22 05:12:03 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 0, size=5, remain=0 2025/01/22 05:12:03 ERROR : subdira/subdirb/subdirc/subdird-rclone-move-dekedax9/filewithoutcasedifferences: Couldn't move: failed to get fileinfo: File is deleted (Error 404) 2025/01/22 05:12:03 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird: Error while attempting to rename to subdira/subdirb/subdirc/subdird: RenameDir renames: failed to get fileinfo: File is deleted (Error 404) 2025/01/22 05:12:03 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Need to transfer - File not found at Destination 2025/01/22 05:12:03 DEBUG : OpenDrive root 'rclone-test-regosuk4zime': Waiting for checks to finish 2025/01/22 05:12:03 DEBUG : OpenDrive root 'rclone-test-regosuk4zime': Waiting for transfers to finish 2025/01/22 05:12:04 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 0, size=5, remain=0 2025/01/22 05:12:04 DEBUG : subdira/subdirb/subdirc/hello: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2025/01/22 05:12:04 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) 2025/01/22 05:12:05 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2025/01/22 05:12:05 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (new) 2025/01/22 05:12:05 ERROR : OpenDrive root 'rclone-test-regosuk4zime': not deleting files as there were IO errors 2025/01/22 05:12:05 ERROR : OpenDrive root 'rclone-test-regosuk4zime': 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: RenameDir renames: failed to get fileinfo: File is deleted (Error 404) Test: TestFixCase run.go:125: removing dir "subdira/subdirb/subdirc/subdird-rclone-move-dekedax9" failed - try 1/3: folder not empty --- FAIL: TestFixCase (21.22s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-regosuk4zime'", Local "Local file system at /tmp/rclone1799307520", Modify Window "1s" 2025/01/22 05:12:09 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/22 05:12:09 INFO : sub dir: Making directory 2025/01/22 05:12:09 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC sync_test.go:2793: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:18 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2791 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2871 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestNothingToTransferWithEmptyDirs fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- FAIL: TestNothingToTransferWithEmptyDirs (16.99s) FAIL "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirWithSuffix)$'" - Finished ERROR in 59.376403752s (try 5/5): exit status 1: Failed [TestFixCase TestNothingToTransferWithEmptyDirs]