"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestServerSideCopyOverSelf|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncIgnoreExisting|TestSyncSuffix)$'" - Starting (try 2/5)
2024/11/03 02:26:44 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xapalus8gapo"
2024/11/03 02:26:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/03 02:26:44 DEBUG : Starting OpenDrive session with ID: b8a23deb4eff937f3825cf3ce77f83cc237e0b0552464ab79103323ed8a7cf6a
2024/11/03 02:26:45 DEBUG : Creating backend with remote "/tmp/rclone2063062221"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
2024/11/03 02:26:46 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0
2024/11/03 02:26:47 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-qicihag0felo"
2024/11/03 02:26:48 DEBUG : Starting OpenDrive session with ID: 8b2fa258178e58500418675bde6f0a405bb9ff1d1316d5a2e75655359b9d247d
    sync_test.go:626: Server side copy (if possible) OpenDrive root 'rclone-test-xapalus8gapo' -> OpenDrive root 'rclone-test-qicihag0felo'
2024/11/03 02:26:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/11/03 02:26:49 DEBUG : OpenDrive root 'rclone-test-qicihag0felo': Waiting for checks to finish
2024/11/03 02:26:49 DEBUG : OpenDrive root 'rclone-test-qicihag0felo': Waiting for transfers to finish
2024/11/03 02:26:50 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2024/11/03 02:26:50 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
2024/11/03 02:26:50 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0
2024/11/03 02:26:52 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/11/03 02:26:52 DEBUG : OpenDrive root 'rclone-test-qicihag0felo': Waiting for checks to finish
2024/11/03 02:26:52 DEBUG : OpenDrive root 'rclone-test-qicihag0felo': Waiting for transfers to finish
2024/11/03 02:26:53 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2024/11/03 02:26:53 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
2024/11/03 02:26:53 DEBUG : OpenDrive root 'rclone-test-qicihag0felo': Purge remote
--- PASS: TestServerSideCopyOverSelf (10.37s)
=== RUN   TestSyncIgnoreExisting
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
2024/11/03 02:26:55 DEBUG : existing: Need to transfer - File not found at Destination
2024/11/03 02:26:55 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': Waiting for checks to finish
2024/11/03 02:26:55 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': Waiting for transfers to finish
2024/11/03 02:26:56 DEBUG : existing: Uploading chunk 0, size=6, remain=0
2024/11/03 02:26:57 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK
2024/11/03 02:26:57 INFO  : existing: Copied (new)
2024/11/03 02:26:57 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
2024/11/03 02:26:57 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': Waiting for checks to finish
2024/11/03 02:26:57 DEBUG : existing: Destination exists, skipping
2024/11/03 02:26:57 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': Waiting for transfers to finish
2024/11/03 02:26:57 DEBUG : Waiting for deletions to finish
2024/11/03 02:26:57 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
--- PASS: TestSyncIgnoreExisting (3.69s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
2024/11/03 02:27:00 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0
2024/11/03 02:27:02 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0
2024/11/03 02:27:03 DEBUG : d: Making directory
2024/11/03 02:27:03 DEBUG : d/e: Making directory
2024/11/03 02:27:04 DEBUG : a/potato2: Need to transfer - File not found at Destination
2024/11/03 02:27:04 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/11/03 02:27:04 DEBUG : c/non empty space: Unchanged skipping
2024/11/03 02:27:05 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': Waiting for checks to finish
2024/11/03 02:27:05 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': Waiting for transfers to finish
2024/11/03 02:27:05 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0
2024/11/03 02:27:06 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2024/11/03 02:27:06 INFO  : a/potato2: Copied (new)
2024/11/03 02:27:06 DEBUG : Waiting for deletions to finish
2024/11/03 02:27:06 INFO  : b/potato: Deleted
2024/11/03 02:27:06 INFO  : d/e: Removing directory
2024/11/03 02:27:07 INFO  : d: Removing directory
2024/11/03 02:27:07 INFO  : b: Removing directory
2024/11/03 02:27:07 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': deleted 3 directories
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (11.04s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
2024/11/03 02:27:11 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2024/11/03 02:27:13 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2024/11/03 02:27:14 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2024/11/03 02:27:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xapalus8gapo/dst"
2024/11/03 02:27:16 DEBUG : Starting OpenDrive session with ID: 29ae81ae11dd3f206a4d82e652487c1b12cfb25c936689a5877cf6148bcf8c0c
2024/11/03 02:27:17 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/11/03 02:27:17 DEBUG : one: same parent dir (ODFfNjcyNTg5Ml90bFZCdA) - using file/rename instead of move_copy for one.bak
2024/11/03 02:27:17 INFO  : one: Moved (server-side) to: one.bak
2024/11/03 02:27:18 DEBUG : one: Uploading chunk 0, size=4, remain=0
2024/11/03 02:27:19 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2024/11/03 02:27:19 INFO  : one: Copied (new)
2024/11/03 02:27:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/11/03 02:27:19 DEBUG : two: Unchanged skipping
2024/11/03 02:27:19 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2024/11/03 02:27:20 DEBUG : three.txt: same parent dir (ODFfNjcyNTg5Ml90bFZCdA) - using file/rename instead of move_copy for three.txt.bak
2024/11/03 02:27:20 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/03 02:27:21 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2024/11/03 02:27:22 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2024/11/03 02:27:22 INFO  : three.txt: Copied (new)
2024/11/03 02:27:23 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/11/03 02:27:23 INFO  : one.bak: Deleted
2024/11/03 02:27:23 DEBUG : one: same parent dir (ODFfNjcyNTg5Ml90bFZCdA) - using file/rename instead of move_copy for one.bak
2024/11/03 02:27:24 INFO  : one: Moved (server-side) to: one.bak
2024/11/03 02:27:24 DEBUG : one: Uploading chunk 0, size=5, remain=0
2024/11/03 02:27:25 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2024/11/03 02:27:25 INFO  : one: Copied (new)
2024/11/03 02:27:25 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/11/03 02:27:25 DEBUG : two: Unchanged skipping
2024/11/03 02:27:26 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2024/11/03 02:27:26 INFO  : three.txt.bak: Deleted
2024/11/03 02:27:26 DEBUG : three.txt: same parent dir (ODFfNjcyNTg5Ml90bFZCdA) - using file/rename instead of move_copy for three.txt.bak
2024/11/03 02:27:26 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/11/03 02:27:27 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2024/11/03 02:27:28 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2024/11/03 02:27:28 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (20.57s)
=== RUN   TestFixCase
    run.go:180: Remote "OpenDrive root 'rclone-test-xapalus8gapo'", Local "Local file system at /tmp/rclone2063062221", Modify Window "1s"
2024/11/03 02:27:31 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0
2024/11/03 02:27:33 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0
2024/11/03 02:27:35 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 0, size=8, remain=0
2024/11/03 02:27:37 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 0, size=8, remain=0
2024/11/03 02:27:39 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8)
2024/11/03 02:27:39 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/11/03 02:27:39 DEBUG : existing: Unchanged skipping
2024/11/03 02:27:40 DEBUG : subdira-rclone-move-wixileg7: same parent dir (ODFfNjcyNTg3Ml9NVUNvdA) - using folder/rename instead of move_copy
2024/11/03 02:27:40 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-zelapoj3
2024/11/03 02:27:40 DEBUG : EXISTING: moving to existing-rclone-move-finoner3
2024/11/03 02:27:40 DEBUG : subdira: same parent dir (ODFfNjcyNTg3Ml9NVUNvdA) - using folder/rename instead of move_copy
2024/11/03 02:27:40 DEBUG : EXISTING: same parent dir (ODFfNjcyNTg3Ml9NVUNvdA) - using file/rename instead of move_copy for existing-rclone-move-finoner3
2024/11/03 02:27:40 DEBUG : EXISTINGBUTDIFFERENT: same parent dir (ODFfNjcyNTg3Ml9NVUNvdA) - using file/rename instead of move_copy for existingbutdifferent-rclone-move-zelapoj3
2024/11/03 02:27:40 INFO  : SUBDIRA: Fixed case by renaming to: subdira
2024/11/03 02:27:40 INFO  : EXISTING: Moved (server-side) to: existing-rclone-move-finoner3
2024/11/03 02:27:40 DEBUG : EXISTING: moving to existing
2024/11/03 02:27:41 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-zelapoj3
2024/11/03 02:27:41 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent
2024/11/03 02:27:41 DEBUG : existingbutdifferent-rclone-move-zelapoj3: same parent dir (ODFfNjcyNTg3Ml9NVUNvdA) - using file/rename instead of move_copy for existingbutdifferent
2024/11/03 02:27:41 DEBUG : subdira/subdirb-rclone-move-xopacef9: same parent dir (ODFfNjcyNTg5OF9mc3phWQ) - using folder/rename instead of move_copy
2024/11/03 02:27:41 DEBUG : existing-rclone-move-finoner3: same parent dir (ODFfNjcyNTg3Ml9NVUNvdA) - using file/rename instead of move_copy for existing
2024/11/03 02:27:41 INFO  : existingbutdifferent-rclone-move-zelapoj3: Moved (server-side) to: existingbutdifferent
2024/11/03 02:27:41 INFO  : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent
2024/11/03 02:27:41 INFO  : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent
2024/11/03 02:27:41 INFO  : existing-rclone-move-finoner3: Moved (server-side) to: existing
2024/11/03 02:27:41 INFO  : EXISTING: Moved (server-side) to: existing
2024/11/03 02:27:41 INFO  : EXISTING: Fixed case by renaming to: existing
2024/11/03 02:27:41 DEBUG : existingbutdifferent: Uploading chunk 0, size=5, remain=0
2024/11/03 02:27:42 DEBUG : subdira/subdirb: same parent dir (ODFfNjcyNTg5OF9mc3phWQ) - using folder/rename instead of move_copy
2024/11/03 02:27:42 INFO  : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb
2024/11/03 02:27:42 DEBUG : existingbutdifferent: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK
2024/11/03 02:27:42 INFO  : existingbutdifferent: Copied (replaced existing)
2024/11/03 02:27:43 DEBUG : subdira/subdirb/subdirc-rclone-move-worufeq9: same parent dir (ODFfNjcyNTg5OV9KUHFmOA) - using folder/rename instead of move_copy
2024/11/03 02:27:43 DEBUG : subdira/subdirb/subdirc: same parent dir (ODFfNjcyNTg5OV9KUHFmOA) - using folder/rename instead of move_copy
2024/11/03 02:27:43 INFO  : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc
2024/11/03 02:27:44 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8)
2024/11/03 02:27:44 DEBUG : subdira/subdirb/subdirc/subdird-rclone-move-dulekiv8: same parent dir (ODFfNjcyNTkwMF9rY2NMYQ) - using folder/rename instead of move_copy
2024/11/03 02:27:44 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-saviwat3
2024/11/03 02:27:45 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: same parent dir (ODFfNjcyNTkwMF9rY2NMYQ) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello-rclone-move-saviwat3
2024/11/03 02:27:45 DEBUG : subdira/subdirb/subdirc/subdird: same parent dir (ODFfNjcyNTkwMF9rY2NMYQ) - using folder/rename instead of move_copy
2024/11/03 02:27:45 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-saviwat3
2024/11/03 02:27:45 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello
2024/11/03 02:27:45 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird
2024/11/03 02:27:45 DEBUG : subdira/subdirb/subdirc/hello-rclone-move-saviwat3: same parent dir (ODFfNjcyNTkwMF9rY2NMYQ) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello
2024/11/03 02:27:45 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ (src 5 vs dst 8)
2024/11/03 02:27:45 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': Waiting for checks to finish
2024/11/03 02:27:45 INFO  : subdira/subdirb/subdirc/hello-rclone-move-saviwat3: Moved (server-side) to: subdira/subdirb/subdirc/hello
2024/11/03 02:27:45 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello
2024/11/03 02:27:45 INFO  : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello
2024/11/03 02:27:45 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 0, size=5, remain=0
2024/11/03 02:27:45 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-bidiheg4
2024/11/03 02:27:46 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: same parent dir (ODFfNjcyNTkwMV9zMGJQTw) - using file/rename instead of move_copy for subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-bidiheg4
2024/11/03 02:27:46 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-bidiheg4
2024/11/03 02:27:46 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2024/11/03 02:27:46 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-bidiheg4: same parent dir (ODFfNjcyNTkwMV9zMGJQTw) - using file/rename instead of move_copy for subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2024/11/03 02:27:46 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-bidiheg4: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2024/11/03 02:27:46 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2024/11/03 02:27:46 INFO  : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences
2024/11/03 02:27:46 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': Waiting for transfers to finish
2024/11/03 02:27:46 DEBUG : subdira/subdirb/subdirc/hello: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK
2024/11/03 02:27:46 INFO  : subdira/subdirb/subdirc/hello: Copied (replaced existing)
2024/11/03 02:27:46 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 0, size=5, remain=0
2024/11/03 02:27:47 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK
2024/11/03 02:27:47 INFO  : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing)
2024/11/03 02:27:47 DEBUG : Waiting for deletions to finish
--- PASS: TestFixCase (21.11s)
PASS
2024/11/03 02:27:51 DEBUG : OpenDrive root 'rclone-test-xapalus8gapo': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestServerSideCopyOverSelf|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncIgnoreExisting|TestSyncSuffix)$'" - Finished OK in 1m8.066827641s (try 2/5)