"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDir$'" - Starting (try 2/5)
2025/11/05 05:09:48 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wipumab2mola"
2025/11/05 05:09:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/05 05:09:48 DEBUG : Starting OpenDrive session with ID: 0e9817eef0c30fb90b915710426f8c3db35f2e93fd689b93fe68c7d19ea4dfab
2025/11/05 05:09:48 DEBUG : Creating backend with remote "/tmp/rclone246877309"
=== RUN   TestSyncBackupDir
    run.go:185: Remote "OpenDrive root 'rclone-test-wipumab2mola'", Local "Local file system at /tmp/rclone246877309", Modify Window "1s"
2025/11/05 05:09:50 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/11/05 05:09:52 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/11/05 05:09:54 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/11/05 05:09:55 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wipumab2mola/dst"
2025/11/05 05:09:56 DEBUG : Starting OpenDrive session with ID: 7f791a57ae6da9860b33a2f9e38a84d5eadaf77677d4e236e90607a3ff699e78
2025/11/05 05:09:56 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wipumab2mola/backup"
2025/11/05 05:09:56 DEBUG : Starting OpenDrive session with ID: 5f23b0d36e8883ca83606e49818ada9529ed3b18caa0172f36f6950d9f4223dc
2025/11/05 05:09:57 DEBUG : one: size = 4 (Local file system at /tmp/rclone246877309)
2025/11/05 05:09:57 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-wipumab2mola/dst')
2025/11/05 05:09:57 DEBUG : one: Sizes differ
2025/11/05 05:09:57 DEBUG : OpenDrive root 'rclone-test-wipumab2mola/dst': Waiting for checks to finish
2025/11/05 05:09:57 DEBUG : two: size = 3 OK
2025/11/05 05:09:57 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/11/05 05:09:57 DEBUG : two: Unchanged skipping
2025/11/05 05:09:58 INFO  : one: Moved (server-side)
2025/11/05 05:09:58 DEBUG : OpenDrive root 'rclone-test-wipumab2mola/dst': Waiting for transfers to finish
2025/11/05 05:09:59 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/11/05 05:10:00 DEBUG : one: size = 4 OK
2025/11/05 05:10:00 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/11/05 05:10:00 INFO  : one: Copied (new)
2025/11/05 05:10:00 DEBUG : Waiting for deletions to finish
2025/11/05 05:10:01 INFO  : three.txt: Moved (server-side)
2025/11/05 05:10:01 INFO  : three.txt: Moved into backup dir
2025/11/05 05:10:02 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2025/11/05 05:10:04 DEBUG : one: size = 5 (Local file system at /tmp/rclone246877309)
2025/11/05 05:10:04 DEBUG : OpenDrive root 'rclone-test-wipumab2mola/dst': Waiting for checks to finish
2025/11/05 05:10:04 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-wipumab2mola/dst')
2025/11/05 05:10:04 DEBUG : one: Sizes differ
2025/11/05 05:10:04 DEBUG : two: size = 3 OK
2025/11/05 05:10:04 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/11/05 05:10:04 DEBUG : two: Unchanged skipping
2025/11/05 05:10:04 INFO  : one: Deleted
2025/11/05 05:10:04 INFO  : one: Moved (server-side)
2025/11/05 05:10:04 DEBUG : OpenDrive root 'rclone-test-wipumab2mola/dst': Waiting for transfers to finish
2025/11/05 05:10:05 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/11/05 05:10:06 DEBUG : one: size = 5 OK
2025/11/05 05:10:06 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/11/05 05:10:06 INFO  : one: Copied (new)
2025/11/05 05:10:06 DEBUG : Waiting for deletions to finish
2025/11/05 05:10:07 INFO  : three.txt: Deleted
2025/11/05 05:10:07 INFO  : three.txt: Moved (server-side)
2025/11/05 05:10:07 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (20.77s)
PASS
2025/11/05 05:10:09 DEBUG : OpenDrive root 'rclone-test-wipumab2mola': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDir$'" - Finished OK in 22.075758081s (try 2/5)