"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncCopyDest)$'" - Starting (try 2/5)
2025/03/28 06:40:25 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mofemuh5keto"
2025/03/28 06:40:25 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/28 06:40:26 DEBUG : Starting OpenDrive session with ID: 1cead8aacac5c7cf0cf5325574d3b938cdc6d00949567334abb11d997a91f6fe
2025/03/28 06:40:26 DEBUG : Creating backend with remote "/tmp/rclone1534072458"
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "OpenDrive root 'rclone-test-mofemuh5keto'", Local "Local file system at /tmp/rclone1534072458", Modify Window "1s"
2025/03/28 06:40:27 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0
2025/03/28 06:40:29 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0
2025/03/28 06:40:30 INFO  : d: Making directory
2025/03/28 06:40:31 INFO  : d/e: Making directory
2025/03/28 06:40:32 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/03/28 06:40:32 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/28 06:40:32 DEBUG : c/non empty space: Unchanged skipping
2025/03/28 06:40:32 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto': Waiting for checks to finish
2025/03/28 06:40:32 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto': Waiting for transfers to finish
2025/03/28 06:40:33 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0
2025/03/28 06:40:34 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/03/28 06:40:34 INFO  : a/potato2: Copied (new)
2025/03/28 06:40:34 DEBUG : Waiting for deletions to finish
2025/03/28 06:40:34 INFO  : b/potato: Deleted
2025/03/28 06:40:34 INFO  : d/e: Removing directory
2025/03/28 06:40:34 INFO  : d: Removing directory
2025/03/28 06:40:35 INFO  : b: Removing directory
2025/03/28 06:40:35 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto': deleted 3 directories
    run.go:180: Remote "OpenDrive root 'rclone-test-mofemuh5keto'", Local "Local file system at /tmp/rclone1534072458", Modify Window "1s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (11.17s)
=== RUN   TestSyncCopyDest
    run.go:180: Remote "OpenDrive root 'rclone-test-mofemuh5keto'", Local "Local file system at /tmp/rclone1534072458", Modify Window "1s"
2025/03/28 06:40:37 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mofemuh5keto/dst"
2025/03/28 06:40:38 DEBUG : Starting OpenDrive session with ID: 8f4f5ccf74a679ae7e64a837b5bcac1829ea757a2122c252a989deabdde7eabb
2025/03/28 06:40:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mofemuh5keto/CopyDest"
2025/03/28 06:40:39 DEBUG : Starting OpenDrive session with ID: f9091a86db0995065a71bd6e14b170154591fd9735dcd060117d316cbd17a775
2025/03/28 06:40:40 DEBUG : one: Need to transfer - File not found at Destination
2025/03/28 06:40:40 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for checks to finish
2025/03/28 06:40:40 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for transfers to finish
2025/03/28 06:40:41 DEBUG : one: Uploading chunk 0, size=3, remain=0
2025/03/28 06:40:42 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2025/03/28 06:40:42 INFO  : one: Copied (new)
2025/03/28 06:40:42 DEBUG : Waiting for deletions to finish
2025/03/28 06:40:43 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/28 06:40:43 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for checks to finish
2025/03/28 06:40:43 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for transfers to finish
2025/03/28 06:40:43 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/03/28 06:40:44 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/03/28 06:40:44 INFO  : one: Copied (replaced existing)
2025/03/28 06:40:44 DEBUG : Waiting for deletions to finish
2025/03/28 06:40:45 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/03/28 06:40:47 DEBUG : CopyDest/one: Uploading chunk 0, size=5, remain=0
2025/03/28 06:40:48 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mofemuh5keto/BackupDir"
2025/03/28 06:40:48 DEBUG : Starting OpenDrive session with ID: e884213aae91d31fa3b8c9500df32304a83eb73c0c1858f870c9644b0fdd2ef5
2025/03/28 06:40:49 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/28 06:40:49 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for checks to finish
2025/03/28 06:40:49 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/28 06:40:49 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/03/28 06:40:50 INFO  : one: Moved (server-side)
2025/03/28 06:40:51 DEBUG : one: Dst hash empty - aborting Src hash check
2025/03/28 06:40:51 INFO  : one: Copied (server-side copy)
2025/03/28 06:40:51 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/03/28 06:40:51 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for transfers to finish
2025/03/28 06:40:51 DEBUG : Waiting for deletions to finish
2025/03/28 06:40:52 DEBUG : CopyDest/two: Uploading chunk 0, size=3, remain=0
2025/03/28 06:40:54 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/28 06:40:54 DEBUG : two: Dst hash empty - aborting Src hash check
2025/03/28 06:40:54 INFO  : two: Copied (server-side copy)
2025/03/28 06:40:54 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/03/28 06:40:54 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/28 06:40:54 DEBUG : one: Unchanged skipping
2025/03/28 06:40:54 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for checks to finish
2025/03/28 06:40:54 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for transfers to finish
2025/03/28 06:40:54 DEBUG : Waiting for deletions to finish
2025/03/28 06:40:54 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/28 06:40:54 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/28 06:40:54 DEBUG : two: Unchanged skipping
2025/03/28 06:40:54 DEBUG : one: Unchanged skipping
2025/03/28 06:40:54 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for checks to finish
2025/03/28 06:40:54 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for transfers to finish
2025/03/28 06:40:54 DEBUG : Waiting for deletions to finish
2025/03/28 06:40:54 INFO  : There was nothing to transfer
2025/03/28 06:40:56 DEBUG : CopyDest/three: Uploading chunk 0, size=5, remain=0
2025/03/28 06:40:57 DEBUG : three: Sizes differ (src 7 vs dst 5)
2025/03/28 06:40:57 DEBUG : three: Destination not found in --copy-dest
2025/03/28 06:40:57 DEBUG : three: Need to transfer - File not found at Destination
2025/03/28 06:40:57 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/28 06:40:57 DEBUG : one: Unchanged skipping
2025/03/28 06:40:57 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/03/28 06:40:57 DEBUG : two: Unchanged skipping
2025/03/28 06:40:57 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for checks to finish
2025/03/28 06:40:57 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto/dst': Waiting for transfers to finish
2025/03/28 06:40:58 DEBUG : three: Uploading chunk 0, size=7, remain=0
2025/03/28 06:40:59 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK
2025/03/28 06:40:59 INFO  : three: Copied (new)
2025/03/28 06:40:59 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (24.63s)
PASS
2025/03/28 06:41:02 DEBUG : OpenDrive root 'rclone-test-mofemuh5keto': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncCopyDest)$'" - Finished OK in 37.038945221s (try 2/5)