"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestCopyFileCopyDest$'" - Starting (try 2/5)
2024/10/30 02:49:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-favivag1pata"
2024/10/30 02:49:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/10/30 02:49:11 DEBUG : Starting OpenDrive session with ID: 376dad768b6657398c405791d64c7481eb0534389334e71a97947c24dc059779
2024/10/30 02:49:11 DEBUG : Creating backend with remote "/tmp/rclone531699718"
=== RUN   TestCopyFileCopyDest
    run.go:180: Remote "OpenDrive root 'rclone-test-favivag1pata'", Local "Local file system at /tmp/rclone531699718", Modify Window "1s"
2024/10/30 02:49:11 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-favivag1pata/dst"
2024/10/30 02:49:12 DEBUG : Starting OpenDrive session with ID: 7aa2f41d21a41befa527f5be2cae7f0fa125ecab071a956906d0c7955db16fa3
2024/10/30 02:49:13 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-favivag1pata/CopyDest"
2024/10/30 02:49:13 DEBUG : Starting OpenDrive session with ID: a5c3514d5fab26a96d34dba8a333b9921ae4ea8d42458ecc18d51bd4704501a8
2024/10/30 02:49:13 DEBUG : one: Need to transfer - File not found at Destination
2024/10/30 02:49:15 DEBUG : one: Uploading chunk 0, size=3, remain=0
2024/10/30 02:49:16 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2024/10/30 02:49:16 INFO  : one: Copied (new)
2024/10/30 02:49:17 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/10/30 02:49:18 DEBUG : one: Uploading chunk 0, size=5, remain=0
2024/10/30 02:49:19 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2024/10/30 02:49:19 INFO  : one: Copied (replaced existing)
2024/10/30 02:49:20 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2024/10/30 02:49:22 DEBUG : CopyDest/one: Uploading chunk 0, size=5, remain=0
2024/10/30 02:49:23 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-favivag1pata/BackupDir"
2024/10/30 02:49:24 DEBUG : Starting OpenDrive session with ID: e17330b5f0c97306398ab8662c18099901a1926e1cc6c5b4f4fd1fca8b47e4d6
2024/10/30 02:49:25 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/10/30 02:49:25 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/10/30 02:49:25 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/10/30 02:49:26 INFO  : one: Moved (server-side)
2024/10/30 02:49:26 DEBUG : one: Dst hash empty - aborting Src hash check
2024/10/30 02:49:26 INFO  : one: Copied (server-side copy)
2024/10/30 02:49:26 DEBUG : one: Destination found in --copy-dest, using server-side copy
2024/10/30 02:49:27 DEBUG : CopyDest/two: Uploading chunk 0, size=3, remain=0
2024/10/30 02:49:29 DEBUG : two: Need to transfer - File not found at Destination
2024/10/30 02:49:29 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/10/30 02:49:29 DEBUG : two: Dst hash empty - aborting Src hash check
2024/10/30 02:49:29 INFO  : two: Copied (server-side copy)
2024/10/30 02:49:29 DEBUG : two: Destination found in --copy-dest, using server-side copy
2024/10/30 02:49:30 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/10/30 02:49:30 DEBUG : two: Unchanged skipping
2024/10/30 02:49:31 DEBUG : CopyDest/three: Uploading chunk 0, size=5, remain=0
2024/10/30 02:49:33 DEBUG : three: Need to transfer - File not found at Destination
2024/10/30 02:49:33 DEBUG : three: Sizes differ (src 7 vs dst 5)
2024/10/30 02:49:33 DEBUG : three: Destination not found in --copy-dest
2024/10/30 02:49:34 DEBUG : three: Uploading chunk 0, size=7, remain=0
2024/10/30 02:49:35 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK
2024/10/30 02:49:35 INFO  : three: Copied (new)
--- PASS: TestCopyFileCopyDest (26.46s)
PASS
2024/10/30 02:49:38 DEBUG : OpenDrive root 'rclone-test-favivag1pata': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestCopyFileCopyDest$'" - Finished OK in 27.758835622s (try 2/5)