"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestSyncBackupDirWithSuffix|TestSyncSuffixKeepExtension)$'" - Starting (try 4/5)
2024/12/22 03:28:33 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xerabic4wutu"
2024/12/22 03:28:33 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/22 03:28:34 DEBUG : Starting OpenDrive session with ID: 21fde01cf3186f1e9f7565c810969d8e21d8ae8b5d2c3539ed9d83f3259a5abf
2024/12/22 03:28:34 DEBUG : Creating backend with remote "/tmp/rclone1500660452"
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "OpenDrive root 'rclone-test-xerabic4wutu'", Local "Local file system at /tmp/rclone1500660452", Modify Window "1s"
2024/12/22 03:28:36 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2024/12/22 03:28:38 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2024/12/22 03:28:39 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2024/12/22 03:28:41 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xerabic4wutu/dst"
2024/12/22 03:28:41 DEBUG : Starting OpenDrive session with ID: df65cdb24622c1de422b1365ab0d87cdc63ce11f7939d5527caec6b091532bbf
2024/12/22 03:28:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xerabic4wutu/backup"
2024/12/22 03:28:42 DEBUG : Starting OpenDrive session with ID: b3431987d823b5d9d8f3597328e1a9dcfc5dc12d11819a8335f4f46dbc8b04df
2024/12/22 03:28:43 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/22 03:28:43 DEBUG : OpenDrive root 'rclone-test-xerabic4wutu/dst': Waiting for checks to finish
2024/12/22 03:28:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/22 03:28:43 DEBUG : two: Unchanged skipping
2024/12/22 03:28:44 INFO  : one: Moved (server-side) to: one.bak
2024/12/22 03:28:44 DEBUG : OpenDrive root 'rclone-test-xerabic4wutu/dst': Waiting for transfers to finish
2024/12/22 03:28:45 DEBUG : one: Uploading chunk 0, size=4, remain=0
2024/12/22 03:28:46 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2024/12/22 03:28:46 INFO  : one: Copied (new)
2024/12/22 03:28:46 DEBUG : Waiting for deletions to finish
2024/12/22 03:28:47 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/22 03:28:47 INFO  : three.txt: Moved into backup dir
2024/12/22 03:28:48 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2024/12/22 03:28:50 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/22 03:28:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/22 03:28:50 DEBUG : two: Unchanged skipping
2024/12/22 03:28:50 DEBUG : OpenDrive root 'rclone-test-xerabic4wutu/dst': Waiting for checks to finish
2024/12/22 03:28:50 INFO  : one.bak: Deleted
2024/12/22 03:28:50 INFO  : one: Moved (server-side) to: one.bak
2024/12/22 03:28:50 DEBUG : OpenDrive root 'rclone-test-xerabic4wutu/dst': Waiting for transfers to finish
2024/12/22 03:28:51 DEBUG : one: Uploading chunk 0, size=5, remain=0
2024/12/22 03:28:52 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2024/12/22 03:28:52 INFO  : one: Copied (new)
2024/12/22 03:28:52 DEBUG : Waiting for deletions to finish
2024/12/22 03:28:52 INFO  : three.txt.bak: Deleted
2024/12/22 03:28:53 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/22 03:28:53 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (20.96s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:180: Remote "OpenDrive root 'rclone-test-xerabic4wutu'", Local "Local file system at /tmp/rclone1500660452", Modify Window "1s"
2024/12/22 03:28:56 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2024/12/22 03:28:58 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2024/12/22 03:29:00 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2024/12/22 03:29:02 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xerabic4wutu/dst"
2024/12/22 03:29:02 DEBUG : Starting OpenDrive session with ID: 9635ffd9a563467f93fb64d84a4cdcf5498ac2c8acca6e3716c75f9f99fbf64f
2024/12/22 03:29:03 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/22 03:29:03 DEBUG : one: same parent dir (ODFfNzg0MjA5Nl9lbktoQg) - using file/rename instead of move_copy for one-2019-01-01
2024/12/22 03:29:03 INFO  : one: Moved (server-side) to: one-2019-01-01
2024/12/22 03:29:04 DEBUG : one: Uploading chunk 0, size=4, remain=0
2024/12/22 03:29:05 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2024/12/22 03:29:05 INFO  : one: Copied (new)
2024/12/22 03:29:05 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/22 03:29:05 DEBUG : two: Unchanged skipping
2024/12/22 03:29:05 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2024/12/22 03:29:06 DEBUG : three.txt: same parent dir (ODFfNzg0MjA5Nl9lbktoQg) - using file/rename instead of move_copy for three-2019-01-01.txt
2024/12/22 03:29:06 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2024/12/22 03:29:07 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2024/12/22 03:29:08 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2024/12/22 03:29:08 INFO  : three.txt: Copied (new)
2024/12/22 03:29:09 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/22 03:29:09 INFO  : one-2019-01-01: Deleted
2024/12/22 03:29:10 DEBUG : one: same parent dir (ODFfNzg0MjA5Nl9lbktoQg) - using file/rename instead of move_copy for one-2019-01-01
2024/12/22 03:29:10 INFO  : one: Moved (server-side) to: one-2019-01-01
2024/12/22 03:29:10 DEBUG : one: Uploading chunk 0, size=5, remain=0
2024/12/22 03:29:12 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2024/12/22 03:29:12 INFO  : one: Copied (new)
2024/12/22 03:29:12 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/22 03:29:12 DEBUG : two: Unchanged skipping
2024/12/22 03:29:12 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2024/12/22 03:29:13 INFO  : three-2019-01-01.txt: Deleted
2024/12/22 03:29:13 DEBUG : three.txt: same parent dir (ODFfNzg0MjA5Nl9lbktoQg) - using file/rename instead of move_copy for three-2019-01-01.txt
2024/12/22 03:29:13 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2024/12/22 03:29:14 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2024/12/22 03:29:15 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2024/12/22 03:29:15 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (22.16s)
=== RUN   TestFixCase
    run.go:180: Remote "OpenDrive root 'rclone-test-xerabic4wutu'", Local "Local file system at /tmp/rclone1500660452", Modify Window "1s"
2024/12/22 03:29:18 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0
2024/12/22 03:29:20 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0
2024/12/22 03:29:23 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 0, size=8, remain=0
2024/12/22 03:29:25 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 0, size=8, remain=0
2024/12/22 03:29:27 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/22 03:29:27 DEBUG : existing: Unchanged skipping
2024/12/22 03:29:27 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8)
2024/12/22 03:29:27 DEBUG : subdira-rclone-move-yowitob6: same parent dir (ODFfNzg0MjA5MF9sbjFEag) - using folder/rename instead of move_copy
2024/12/22 03:29:27 DEBUG : EXISTING: moving to existing-rclone-move-qomumit5
2024/12/22 03:29:28 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-cecosej3
2024/12/22 03:29:28 DEBUG : subdira: same parent dir (ODFfNzg0MjA5MF9sbjFEag) - using folder/rename instead of move_copy
2024/12/22 03:29:28 DEBUG : EXISTING: same parent dir (ODFfNzg0MjA5MF9sbjFEag) - using file/rename instead of move_copy for existing-rclone-move-qomumit5
2024/12/22 03:29:28 DEBUG : EXISTINGBUTDIFFERENT: same parent dir (ODFfNzg0MjA5MF9sbjFEag) - using file/rename instead of move_copy for existingbutdifferent-rclone-move-cecosej3
2024/12/22 03:29:28 INFO  : SUBDIRA: Fixed case by renaming to: subdira
2024/12/22 03:29:28 INFO  : EXISTING: Moved (server-side) to: existing-rclone-move-qomumit5
2024/12/22 03:29:28 DEBUG : EXISTING: moving to existing
2024/12/22 03:29:28 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-cecosej3
2024/12/22 03:29:28 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent
2024/12/22 03:29:29 DEBUG : existingbutdifferent-rclone-move-cecosej3: same parent dir (ODFfNzg0MjA5MF9sbjFEag) - using file/rename instead of move_copy for existingbutdifferent
2024/12/22 03:29:29 DEBUG : subdira/subdirb-rclone-move-baworof3: same parent dir (ODFfNzg0MjA5OV9oMnFiaw) - using folder/rename instead of move_copy
2024/12/22 03:29:29 DEBUG : existing-rclone-move-qomumit5: same parent dir (ODFfNzg0MjA5MF9sbjFEag) - using file/rename instead of move_copy for existing
2024/12/22 03:29:29 INFO  : existingbutdifferent-rclone-move-cecosej3: Moved (server-side) to: existingbutdifferent
2024/12/22 03:29:29 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent
2024/12/22 03:29:29 INFO  : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent
2024/12/22 03:29:29 INFO  : existing-rclone-move-qomumit5: Moved (server-side) to: existing
2024/12/22 03:29:29 INFO  : EXISTING: Moved (server-side) to: existing
2024/12/22 03:29:29 INFO  : EXISTING: Fixed case by renaming to: existing
2024/12/22 03:29:29 DEBUG : existingbutdifferent: Uploading chunk 0, size=5, remain=0
2024/12/22 03:29:29 DEBUG : subdira/subdirb: same parent dir (ODFfNzg0MjA5OV9oMnFiaw) - using folder/rename instead of move_copy
2024/12/22 03:29:30 INFO  : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb
2024/12/22 03:29:30 DEBUG : existingbutdifferent: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK
2024/12/22 03:29:30 INFO  : existingbutdifferent: Copied (replaced existing)
2024/12/22 03:29:30 DEBUG : subdira/subdirb/subdirc-rclone-move-jocuqan7: same parent dir (ODFfNzg0MjEwMF9TdXg2bg) - using folder/rename instead of move_copy
2024/12/22 03:29:31 DEBUG : subdira/subdirb/subdirc: same parent dir (ODFfNzg0MjEwMF9TdXg2bg) - using folder/rename instead of move_copy
2024/12/22 03:29:31 INFO  : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc
2024/12/22 03:29:32 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8)
2024/12/22 03:29:32 DEBUG : subdira/subdirb/subdirc/subdird-rclone-move-hatequx4: same parent dir (ODFfNzg0MjEwMV8wcWxPaw) - using folder/rename instead of move_copy
2024/12/22 03:29:32 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-qofesid5
2024/12/22 03:29:32 INFO  : OpenDrive root 'rclone-test-xerabic4wutu': Can't DirMove - falling back to file moves: can't copy directory - destination already exists
2024/12/22 03:29:32 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: same parent dir (ODFfNzg0MjEwMV8wcWxPaw) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello-rclone-move-qofesid5
2024/12/22 03:29:33 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-qofesid5
2024/12/22 03:29:33 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello
2024/12/22 03:29:33 DEBUG : subdira/subdirb/subdirc/hello-rclone-move-qofesid5: same parent dir (ODFfNzg0MjEwMV8wcWxPaw) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello
2024/12/22 03:29:33 INFO  : subdira/subdirb/subdirc/hello-rclone-move-qofesid5: Moved (server-side) to: subdira/subdirb/subdirc/hello
2024/12/22 03:29:33 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello
2024/12/22 03:29:33 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello
2024/12/22 03:29:33 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Deleted
2024/12/22 03:29:33 ERROR : subdira/subdirb/subdirc/subdird-rclone-move-hatequx4/filewithoutcasedifferences: Couldn't move: failed to get fileinfo: File is deleted (Error 404)
2024/12/22 03:29:33 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)
2024/12/22 03:29:33 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 0, size=5, remain=0
2024/12/22 03:29:33 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Need to transfer - File not found at Destination
2024/12/22 03:29:33 DEBUG : OpenDrive root 'rclone-test-xerabic4wutu': Waiting for checks to finish
2024/12/22 03:29:33 DEBUG : OpenDrive root 'rclone-test-xerabic4wutu': Waiting for transfers to finish
2024/12/22 03:29:34 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 0, size=5, remain=0
2024/12/22 03:29:34 DEBUG : subdira/subdirb/subdirc/hello: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK
2024/12/22 03:29:34 INFO  : subdira/subdirb/subdirc/hello: Copied (replaced existing)
2024/12/22 03:29:35 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK
2024/12/22 03:29:35 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (new)
2024/12/22 03:29:35 ERROR : OpenDrive root 'rclone-test-xerabic4wutu': not deleting files as there were IO errors
2024/12/22 03:29:35 ERROR : OpenDrive root 'rclone-test-xerabic4wutu': 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
--- FAIL: TestFixCase (21.59s)
FAIL
2024/12/22 03:29:39 DEBUG : OpenDrive root 'rclone-test-xerabic4wutu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestSyncBackupDirWithSuffix|TestSyncSuffixKeepExtension)$'" - Finished ERROR in 1m6.184516982s (try 4/5): exit status 1: Failed [TestFixCase]