"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncSuffixKeepExtension)$'" - Starting (try 2/5)
2025/08/27 01:28:48 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jekawuv6rada"
2025/08/27 01:28:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/27 01:28:49 DEBUG : Starting OpenDrive session with ID: fb047f531c7217f0da8d357a95fefd8f23f51abf3db744e39d01b40b93fb7fb1
2025/08/27 01:28:49 DEBUG : Creating backend with remote "/tmp/rclone4136324574"
=== RUN   TestCopyNoEmptyDirectories
    run.go:180: Remote "OpenDrive root 'rclone-test-jekawuv6rada'", Local "Local file system at /tmp/rclone4136324574", Modify Window "1s"
2025/08/27 01:28:49 INFO  : sub dir2: Making directory
2025/08/27 01:28:49 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2025/08/27 01:28:49 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/08/27 01:28:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/08/27 01:28:50 DEBUG : OpenDrive root 'rclone-test-jekawuv6rada': Waiting for checks to finish
2025/08/27 01:28:50 DEBUG : OpenDrive root 'rclone-test-jekawuv6rada': Waiting for transfers to finish
2025/08/27 01:28:51 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0
2025/08/27 01:28:52 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/08/27 01:28:52 INFO  : sub dir/hello world: Copied (new)
    run.go:125: removing dir "sub dir" failed - try 1/3: folder not empty
    run.go:125: removing dir "sub dir" failed - try 2/3: folder not empty
--- PASS: TestCopyNoEmptyDirectories (6.96s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "OpenDrive root 'rclone-test-jekawuv6rada'", Local "Local file system at /tmp/rclone4136324574", Modify Window "1s"
2025/08/27 01:28:57 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0
2025/08/27 01:29:00 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0
2025/08/27 01:29:01 INFO  : d: Making directory
2025/08/27 01:29:01 INFO  : d/e: Making directory
2025/08/27 01:29:03 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/08/27 01:29:03 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/08/27 01:29:03 DEBUG : c/non empty space: Unchanged skipping
2025/08/27 01:29:03 DEBUG : OpenDrive root 'rclone-test-jekawuv6rada': Waiting for checks to finish
2025/08/27 01:29:03 DEBUG : OpenDrive root 'rclone-test-jekawuv6rada': Waiting for transfers to finish
2025/08/27 01:29:04 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0
2025/08/27 01:29:05 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/08/27 01:29:05 INFO  : a/potato2: Copied (new)
2025/08/27 01:29:05 DEBUG : Waiting for deletions to finish
2025/08/27 01:29:06 INFO  : b/potato: Deleted
2025/08/27 01:29:06 INFO  : d/e: Removing directory
2025/08/27 01:29:06 INFO  : d: Removing directory
2025/08/27 01:29:07 INFO  : b: Removing directory
2025/08/27 01:29:07 DEBUG : OpenDrive root 'rclone-test-jekawuv6rada': deleted 3 directories
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (13.11s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:180: Remote "OpenDrive root 'rclone-test-jekawuv6rada'", Local "Local file system at /tmp/rclone4136324574", Modify Window "1s"
2025/08/27 01:29:11 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/08/27 01:29:13 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/08/27 01:29:15 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/08/27 01:29:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jekawuv6rada/dst"
2025/08/27 01:29:17 DEBUG : Starting OpenDrive session with ID: 10653702be0ef6a072e94d4910514f66098c97cc3ed07fed8073ebc33a066384
2025/08/27 01:29:17 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/08/27 01:29:18 DEBUG : one: same parent dir (ODFfOTQyMzU3N18wSDFWag) - using file/rename instead of move_copy for one-2019-01-01
2025/08/27 01:29:18 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/08/27 01:29:19 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/08/27 01:29:20 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/08/27 01:29:20 INFO  : one: Copied (new)
2025/08/27 01:29:20 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/08/27 01:29:20 DEBUG : two: Unchanged skipping
2025/08/27 01:29:20 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/08/27 01:29:20 DEBUG : three.txt: same parent dir (ODFfOTQyMzU3N18wSDFWag) - using file/rename instead of move_copy for three-2019-01-01.txt
2025/08/27 01:29:21 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/08/27 01:29:22 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2025/08/27 01:29:23 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2025/08/27 01:29:23 INFO  : three.txt: Copied (new)
2025/08/27 01:29:24 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/08/27 01:29:25 INFO  : one-2019-01-01: Deleted
2025/08/27 01:29:25 DEBUG : one: same parent dir (ODFfOTQyMzU3N18wSDFWag) - using file/rename instead of move_copy for one-2019-01-01
2025/08/27 01:29:25 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/08/27 01:29:26 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/08/27 01:29:27 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/08/27 01:29:27 INFO  : one: Copied (new)
2025/08/27 01:29:27 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/08/27 01:29:27 DEBUG : two: Unchanged skipping
2025/08/27 01:29:27 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/08/27 01:29:28 INFO  : three-2019-01-01.txt: Deleted
2025/08/27 01:29:28 DEBUG : three.txt: same parent dir (ODFfOTQyMzU3N18wSDFWag) - using file/rename instead of move_copy for three-2019-01-01.txt
2025/08/27 01:29:28 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/08/27 01:29:29 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2025/08/27 01:29:30 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2025/08/27 01:29:30 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (23.31s)
PASS
2025/08/27 01:29:33 DEBUG : OpenDrive root 'rclone-test-jekawuv6rada': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncSuffixKeepExtension)$'" - Finished OK in 44.923104835s (try 2/5)