"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestServerSideMoveOverSelf|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncBackupDirWithSuffix|TestSyncSuffix)$'" - Starting (try 2/5)
2025/05/04 05:26:02 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kapikis4wiza"
2025/05/04 05:26:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/04 05:26:03 DEBUG : Starting OpenDrive session with ID: 495f15f7c66120703b774c17c0ad7d579171b1fcab6318b4b08d77cab149f049
2025/05/04 05:26:03 DEBUG : Creating backend with remote "/tmp/rclone4042737401"
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "OpenDrive root 'rclone-test-kapikis4wiza'", Local "Local file system at /tmp/rclone4042737401", Modify Window "1s"
2025/05/04 05:26:04 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0
2025/05/04 05:26:06 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vuxogib3bige"
2025/05/04 05:26:06 DEBUG : Starting OpenDrive session with ID: d8cd9ec50c88fc90debffb11088b315f00f8e9f0bf6fd313d9ea3b3a7abf29d7
    sync_test.go:671: Server side copy (if possible) OpenDrive root 'rclone-test-kapikis4wiza' -> OpenDrive root 'rclone-test-vuxogib3bige'
2025/05/04 05:26:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/04 05:26:07 DEBUG : OpenDrive root 'rclone-test-vuxogib3bige': Waiting for checks to finish
2025/05/04 05:26:07 DEBUG : OpenDrive root 'rclone-test-vuxogib3bige': Waiting for transfers to finish
2025/05/04 05:26:08 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/05/04 05:26:08 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "OpenDrive root 'rclone-test-kapikis4wiza'", Local "Local file system at /tmp/rclone4042737401", Modify Window "1s"
2025/05/04 05:26:09 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0
2025/05/04 05:26:10 DEBUG : OpenDrive root 'rclone-test-vuxogib3bige': Using server-side directory move
2025/05/04 05:26:10 INFO  : OpenDrive root 'rclone-test-vuxogib3bige': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/05/04 05:26:10 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/05/04 05:26:10 DEBUG : OpenDrive root 'rclone-test-vuxogib3bige': Waiting for checks to finish
2025/05/04 05:26:10 DEBUG : OpenDrive root 'rclone-test-vuxogib3bige': Waiting for transfers to finish
2025/05/04 05:26:10 INFO  : sub dir/hello world: Deleted
2025/05/04 05:26:11 INFO  : sub dir/hello world: Moved (server-side)
2025/05/04 05:26:12 DEBUG : sub dir/hello world: Uploading chunk 0, size=24, remain=0
2025/05/04 05:26:13 DEBUG : testing file moves
2025/05/04 05:26:14 DEBUG : OpenDrive root 'rclone-test-vuxogib3bige': Waiting for checks to finish
2025/05/04 05:26:14 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/05/04 05:26:14 DEBUG : OpenDrive root 'rclone-test-vuxogib3bige': Waiting for transfers to finish
2025/05/04 05:26:14 INFO  : sub dir/hello world: Deleted
2025/05/04 05:26:14 INFO  : sub dir/hello world: Moved (server-side)
    run.go:180: Remote "OpenDrive root 'rclone-test-kapikis4wiza'", Local "Local file system at /tmp/rclone4042737401", Modify Window "1s"
2025/05/04 05:26:15 DEBUG : OpenDrive root 'rclone-test-vuxogib3bige': Purge remote
--- PASS: TestServerSideMoveOverSelf (13.86s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:180: Remote "OpenDrive root 'rclone-test-kapikis4wiza'", Local "Local file system at /tmp/rclone4042737401", Modify Window "1s"
2025/05/04 05:26:18 DEBUG : potato: Uploading chunk 0, size=21, remain=0
2025/05/04 05:26:19 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
2025/05/04 05:26:21 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/05/04 05:26:21 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/04 05:26:21 DEBUG : OpenDrive root 'rclone-test-kapikis4wiza': Waiting for checks to finish
2025/05/04 05:26:21 DEBUG : empty space: Unchanged skipping
2025/05/04 05:26:21 DEBUG : OpenDrive root 'rclone-test-kapikis4wiza': Waiting for transfers to finish
2025/05/04 05:26:21 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
2025/05/04 05:26:22 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/05/04 05:26:22 INFO  : potato2: Copied (new)
2025/05/04 05:26:22 DEBUG : Waiting for deletions to finish
2025/05/04 05:26:22 INFO  : potato: Deleted
    run.go:180: Remote "OpenDrive root 'rclone-test-kapikis4wiza'", Local "Local file system at /tmp/rclone4042737401", Modify Window "1s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (6.61s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "OpenDrive root 'rclone-test-kapikis4wiza'", Local "Local file system at /tmp/rclone4042737401", Modify Window "1s"
2025/05/04 05:26:25 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/05/04 05:26:26 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/05/04 05:26:28 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/05/04 05:26:29 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kapikis4wiza/dst"
2025/05/04 05:26:30 DEBUG : Starting OpenDrive session with ID: 0302e74e646be3b48c9169f161d2a43afaa3171a5c2673260ad842c95f8e4ff5
2025/05/04 05:26:30 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kapikis4wiza/backup"
2025/05/04 05:26:30 DEBUG : Starting OpenDrive session with ID: 143316617caa77884e48b1ef682e7998233f199a49989158ff3d3b93d8621f00
2025/05/04 05:26:31 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/04 05:26:31 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/05/04 05:26:31 DEBUG : two: Unchanged skipping
2025/05/04 05:26:31 DEBUG : OpenDrive root 'rclone-test-kapikis4wiza/dst': Waiting for checks to finish
2025/05/04 05:26:32 INFO  : one: Moved (server-side) to: one.bak
2025/05/04 05:26:32 DEBUG : OpenDrive root 'rclone-test-kapikis4wiza/dst': Waiting for transfers to finish
2025/05/04 05:26:33 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/05/04 05:26:34 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/04 05:26:34 INFO  : one: Copied (new)
2025/05/04 05:26:34 DEBUG : Waiting for deletions to finish
2025/05/04 05:26:34 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/04 05:26:34 INFO  : three.txt: Moved into backup dir
2025/05/04 05:26:36 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2025/05/04 05:26:37 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/04 05:26:37 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/05/04 05:26:37 DEBUG : two: Unchanged skipping
2025/05/04 05:26:37 DEBUG : OpenDrive root 'rclone-test-kapikis4wiza/dst': Waiting for checks to finish
2025/05/04 05:26:37 INFO  : one.bak: Deleted
2025/05/04 05:26:38 INFO  : one: Moved (server-side) to: one.bak
2025/05/04 05:26:38 DEBUG : OpenDrive root 'rclone-test-kapikis4wiza/dst': Waiting for transfers to finish
2025/05/04 05:26:38 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/05/04 05:26:39 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/04 05:26:39 INFO  : one: Copied (new)
2025/05/04 05:26:39 DEBUG : Waiting for deletions to finish
2025/05/04 05:26:40 INFO  : three.txt.bak: Deleted
2025/05/04 05:26:40 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/04 05:26:40 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (18.57s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "OpenDrive root 'rclone-test-kapikis4wiza'", Local "Local file system at /tmp/rclone4042737401", Modify Window "1s"
2025/05/04 05:26:43 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/05/04 05:26:45 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/05/04 05:26:47 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/05/04 05:26:48 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kapikis4wiza/dst"
2025/05/04 05:26:48 DEBUG : Starting OpenDrive session with ID: c991895ee7254255801c6fd055239b699036531fafa7532b84c9adb0bac0541f
2025/05/04 05:26:49 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/04 05:26:49 DEBUG : one: same parent dir (ODFfODU0MTkwNV93cnpQTw) - using file/rename instead of move_copy for one.bak
2025/05/04 05:26:49 INFO  : one: Moved (server-side) to: one.bak
2025/05/04 05:26:50 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/05/04 05:26:51 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/04 05:26:51 INFO  : one: Copied (new)
2025/05/04 05:26:51 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/05/04 05:26:51 DEBUG : two: Unchanged skipping
2025/05/04 05:26:51 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/05/04 05:26:52 DEBUG : three.txt: same parent dir (ODFfODU0MTkwNV93cnpQTw) - using file/rename instead of move_copy for three.txt.bak
2025/05/04 05:26:52 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/04 05:26:53 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2025/05/04 05:26:54 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2025/05/04 05:26:54 INFO  : three.txt: Copied (new)
2025/05/04 05:26:54 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/04 05:26:55 INFO  : one.bak: Deleted
2025/05/04 05:26:55 DEBUG : one: same parent dir (ODFfODU0MTkwNV93cnpQTw) - using file/rename instead of move_copy for one.bak
2025/05/04 05:26:55 INFO  : one: Moved (server-side) to: one.bak
2025/05/04 05:26:56 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/05/04 05:26:57 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/04 05:26:57 INFO  : one: Copied (new)
2025/05/04 05:26:57 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/05/04 05:26:57 DEBUG : two: Unchanged skipping
2025/05/04 05:26:57 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/05/04 05:26:57 INFO  : three.txt.bak: Deleted
2025/05/04 05:26:58 DEBUG : three.txt: same parent dir (ODFfODU0MTkwNV93cnpQTw) - using file/rename instead of move_copy for three.txt.bak
2025/05/04 05:26:58 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/04 05:26:58 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2025/05/04 05:26:59 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2025/05/04 05:26:59 INFO  : three.txt: Copied (new)
    run.go:125: removing dir "dst" failed - try 1/3: folder not empty
--- PASS: TestSyncSuffix (20.38s)
PASS
2025/05/04 05:27:02 DEBUG : OpenDrive root 'rclone-test-kapikis4wiza': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestServerSideMoveOverSelf|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncBackupDirWithSuffix|TestSyncSuffix)$'" - Finished OK in 1m0.669751727s (try 2/5)