"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDir$'" - Starting (try 3/5)
2024/11/10 04:36:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vuwotug7kesu"
2024/11/10 04:36:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/10 04:36:17 DEBUG : Starting OpenDrive session with ID: 985f22713502a0cff4b079d7fbe9640a206450b4a83e4b6fce0beba2b9a3753c
2024/11/10 04:36:17 DEBUG : Creating backend with remote "/tmp/rclone2090628672"
2024/11/10 04:36:17 DEBUG : Config file has changed externally - reloading
=== RUN   TestSyncBackupDir
    run.go:180: Remote "OpenDrive root 'rclone-test-vuwotug7kesu'", Local "Local file system at /tmp/rclone2090628672", Modify Window "1s"
2024/11/10 04:36:19 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2024/11/10 04:36:20 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2024/11/10 04:36:22 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2024/11/10 04:36:23 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vuwotug7kesu/dst"
2024/11/10 04:36:24 DEBUG : Starting OpenDrive session with ID: b13700e034512bd6770549876a4ef65f626b0d430b48547ab196810376f13dbc
2024/11/10 04:36:24 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vuwotug7kesu/backup"
2024/11/10 04:36:25 DEBUG : Starting OpenDrive session with ID: 3ed91f77999da0a7ac3392b1cf4b39ad111a1c0f2e527957e0060f6f863bee3b
2024/11/10 04:36:26 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/11/10 04:36:26 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/11/10 04:36:26 DEBUG : OpenDrive root 'rclone-test-vuwotug7kesu/dst': Waiting for checks to finish
2024/11/10 04:36:26 DEBUG : two: Unchanged skipping
2024/11/10 04:36:27 INFO  : one: Moved (server-side)
2024/11/10 04:36:27 DEBUG : OpenDrive root 'rclone-test-vuwotug7kesu/dst': Waiting for transfers to finish
2024/11/10 04:36:27 DEBUG : one: Uploading chunk 0, size=4, remain=0
2024/11/10 04:36:28 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2024/11/10 04:36:28 INFO  : one: Copied (new)
2024/11/10 04:36:28 DEBUG : Waiting for deletions to finish
2024/11/10 04:36:29 INFO  : three.txt: Moved (server-side)
2024/11/10 04:36:29 INFO  : three.txt: Moved into backup dir
2024/11/10 04:36:30 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2024/11/10 04:36:31 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/11/10 04:36:31 DEBUG : OpenDrive root 'rclone-test-vuwotug7kesu/dst': Waiting for checks to finish
2024/11/10 04:36:31 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/11/10 04:36:31 DEBUG : two: Unchanged skipping
2024/11/10 04:36:32 INFO  : one: Deleted
2024/11/10 04:36:32 INFO  : one: Moved (server-side)
2024/11/10 04:36:32 DEBUG : OpenDrive root 'rclone-test-vuwotug7kesu/dst': Waiting for transfers to finish
2024/11/10 04:36:33 DEBUG : one: Uploading chunk 0, size=5, remain=0
2024/11/10 04:36:34 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2024/11/10 04:36:34 INFO  : one: Copied (new)
2024/11/10 04:36:34 DEBUG : Waiting for deletions to finish
2024/11/10 04:36:34 INFO  : three.txt: Deleted
2024/11/10 04:36:35 INFO  : three.txt: Moved (server-side)
2024/11/10 04:36:35 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (19.51s)
PASS
2024/11/10 04:36:37 DEBUG : OpenDrive root 'rclone-test-vuwotug7kesu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDir$'" - Finished OK in 20.757874971s (try 3/5)