"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncSuffix)$'" - Starting (try 2/5)
2025/03/21 04:42:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-tebogum9pufe"
2025/03/21 04:42:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/21 04:42:38 DEBUG : Starting OpenDrive session with ID: dc37d7a575246761b3e162cdb6781de2d89e2e2c40b78dd648bb0d3f2ce8c30f
2025/03/21 04:42:39 DEBUG : Creating backend with remote "/tmp/rclone2230098821"
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "OpenDrive root 'rclone-test-tebogum9pufe'", Local "Local file system at /tmp/rclone2230098821", Modify Window "1s"
2025/03/21 04:42:40 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/03/21 04:42:42 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/03/21 04:42:43 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/03/21 04:42:45 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-tebogum9pufe/dst"
2025/03/21 04:42:45 DEBUG : Starting OpenDrive session with ID: f9ecf722934760508c22d8a832895ba2d16ced723d2e525549cdebec144ad8b7
2025/03/21 04:42:46 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/03/21 04:42:46 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/21 04:42:46 DEBUG : two: Unchanged skipping
2025/03/21 04:42:46 DEBUG : OpenDrive root 'rclone-test-tebogum9pufe/dst': Waiting for checks to finish
2025/03/21 04:42:46 DEBUG : one: same parent dir (ODFfODE4NzUzMl9nN0JWNA) - using file/rename instead of move_copy for one.bak
2025/03/21 04:42:46 INFO  : one: Moved (server-side) to: one.bak
2025/03/21 04:42:46 DEBUG : OpenDrive root 'rclone-test-tebogum9pufe/dst': Waiting for transfers to finish
2025/03/21 04:42:47 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/03/21 04:42:48 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/03/21 04:42:48 INFO  : one: Copied (new)
2025/03/21 04:42:48 DEBUG : Waiting for deletions to finish
2025/03/21 04:42:48 DEBUG : three.txt: same parent dir (ODFfODE4NzUzMl9nN0JWNA) - using file/rename instead of move_copy for three.txt.bak
2025/03/21 04:42:48 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/21 04:42:48 INFO  : three.txt: Moved into backup dir
2025/03/21 04:42:50 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2025/03/21 04:42:51 DEBUG : one.bak: Excluded (Path Filter)
2025/03/21 04:42:51 DEBUG : one.bak: Excluded
2025/03/21 04:42:51 DEBUG : three.txt.bak: Excluded (Path Filter)
2025/03/21 04:42:51 DEBUG : three.txt.bak: Excluded
2025/03/21 04:42:51 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/03/21 04:42:51 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/21 04:42:51 DEBUG : two: Unchanged skipping
2025/03/21 04:42:51 DEBUG : OpenDrive root 'rclone-test-tebogum9pufe/dst': Waiting for checks to finish
2025/03/21 04:42:51 INFO  : one.bak: Deleted
2025/03/21 04:42:51 DEBUG : one: same parent dir (ODFfODE4NzUzMl9nN0JWNA) - using file/rename instead of move_copy for one.bak
2025/03/21 04:42:52 INFO  : one: Moved (server-side) to: one.bak
2025/03/21 04:42:52 DEBUG : OpenDrive root 'rclone-test-tebogum9pufe/dst': Waiting for transfers to finish
2025/03/21 04:42:52 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/03/21 04:42:53 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/03/21 04:42:53 INFO  : one: Copied (new)
2025/03/21 04:42:53 DEBUG : Waiting for deletions to finish
2025/03/21 04:42:54 INFO  : three.txt.bak: Deleted
2025/03/21 04:42:54 DEBUG : three.txt: same parent dir (ODFfODE4NzUzMl9nN0JWNA) - using file/rename instead of move_copy for three.txt.bak
2025/03/21 04:42:54 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/21 04:42:54 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (17.09s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "OpenDrive root 'rclone-test-tebogum9pufe'", Local "Local file system at /tmp/rclone2230098821", Modify Window "1s"
2025/03/21 04:42:57 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/03/21 04:42:59 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/03/21 04:43:00 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/03/21 04:43:01 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-tebogum9pufe/dst"
2025/03/21 04:43:02 DEBUG : Starting OpenDrive session with ID: a53f7913d9fad4d878dd97caeb16bb87a3b4d27f3a5dcef04a63299f928cc286
2025/03/21 04:43:02 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/03/21 04:43:03 DEBUG : one: same parent dir (ODFfODE4NzUzM182dkcybw) - using file/rename instead of move_copy for one.bak
2025/03/21 04:43:03 INFO  : one: Moved (server-side) to: one.bak
2025/03/21 04:43:04 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/03/21 04:43:05 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/03/21 04:43:05 INFO  : one: Copied (new)
2025/03/21 04:43:05 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/21 04:43:05 DEBUG : two: Unchanged skipping
2025/03/21 04:43:05 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/03/21 04:43:05 DEBUG : three.txt: same parent dir (ODFfODE4NzUzM182dkcybw) - using file/rename instead of move_copy for three.txt.bak
2025/03/21 04:43:05 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/21 04:43:06 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2025/03/21 04:43:08 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2025/03/21 04:43:08 INFO  : three.txt: Copied (new)
2025/03/21 04:43:09 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/03/21 04:43:09 INFO  : one.bak: Deleted
2025/03/21 04:43:09 DEBUG : one: same parent dir (ODFfODE4NzUzM182dkcybw) - using file/rename instead of move_copy for one.bak
2025/03/21 04:43:09 INFO  : one: Moved (server-side) to: one.bak
2025/03/21 04:43:10 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/03/21 04:43:11 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/03/21 04:43:11 INFO  : one: Copied (new)
2025/03/21 04:43:11 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/21 04:43:11 DEBUG : two: Unchanged skipping
2025/03/21 04:43:11 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/03/21 04:43:11 INFO  : three.txt.bak: Deleted
2025/03/21 04:43:12 DEBUG : three.txt: same parent dir (ODFfODE4NzUzM182dkcybw) - using file/rename instead of move_copy for three.txt.bak
2025/03/21 04:43:12 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/21 04:43:13 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2025/03/21 04:43:14 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2025/03/21 04:43:14 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (19.71s)
PASS
2025/03/21 04:43:16 DEBUG : OpenDrive root 'rclone-test-tebogum9pufe': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncSuffix)$'" - Finished OK in 38.171977387s (try 2/5)