"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDirWithSuffix$'" - Starting (try 2/5)
2025/08/30 02:43:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-riwimuh9foyi"
2025/08/30 02:43:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/30 02:43:16 DEBUG : Starting OpenDrive session with ID: 7bfa1365934ca074256b8f1c4a3945a2109acfbb59ff3de5a863c091cc0db8e9
2025/08/30 02:43:17 DEBUG : Creating backend with remote "/tmp/rclone1129370362"
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "OpenDrive root 'rclone-test-riwimuh9foyi'", Local "Local file system at /tmp/rclone1129370362", Modify Window "1s"
2025/08/30 02:43:19 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/08/30 02:43:20 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/08/30 02:43:22 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/08/30 02:43:24 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-riwimuh9foyi/dst"
2025/08/30 02:43:24 DEBUG : Starting OpenDrive session with ID: 2e870938b99e575b6a904190666876aebbf623ac021285d66adb5da1c1196e24
2025/08/30 02:43:25 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-riwimuh9foyi/backup"
2025/08/30 02:43:25 DEBUG : Starting OpenDrive session with ID: c9b53679c7545191bf0ae67a716e60a1c2799db0a06fa731fa637706f92e7565
2025/08/30 02:43:26 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/08/30 02:43:26 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/08/30 02:43:26 DEBUG : two: Unchanged skipping
2025/08/30 02:43:26 DEBUG : OpenDrive root 'rclone-test-riwimuh9foyi/dst': Waiting for checks to finish
2025/08/30 02:43:27 INFO  : one: Moved (server-side) to: one.bak
2025/08/30 02:43:27 DEBUG : OpenDrive root 'rclone-test-riwimuh9foyi/dst': Waiting for transfers to finish
2025/08/30 02:43:28 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/08/30 02:43:29 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/08/30 02:43:29 INFO  : one: Copied (new)
2025/08/30 02:43:29 DEBUG : Waiting for deletions to finish
2025/08/30 02:43:30 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/08/30 02:43:30 INFO  : three.txt: Moved into backup dir
2025/08/30 02:43:31 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2025/08/30 02:43:33 DEBUG : OpenDrive root 'rclone-test-riwimuh9foyi/dst': Waiting for checks to finish
2025/08/30 02:43:33 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/08/30 02:43:33 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/08/30 02:43:33 DEBUG : two: Unchanged skipping
2025/08/30 02:43:33 INFO  : one.bak: Deleted
2025/08/30 02:43:34 INFO  : one: Moved (server-side) to: one.bak
2025/08/30 02:43:34 DEBUG : OpenDrive root 'rclone-test-riwimuh9foyi/dst': Waiting for transfers to finish
2025/08/30 02:43:35 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/08/30 02:43:36 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/08/30 02:43:36 INFO  : one: Copied (new)
2025/08/30 02:43:36 DEBUG : Waiting for deletions to finish
2025/08/30 02:43:36 INFO  : three.txt.bak: Deleted
2025/08/30 02:43:37 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/08/30 02:43:37 INFO  : three.txt: Moved into backup dir
    run.go:125: removing dir "dst" failed - try 1/3: folder not empty
--- PASS: TestSyncBackupDirWithSuffix (23.69s)
PASS
2025/08/30 02:43:41 DEBUG : OpenDrive root 'rclone-test-riwimuh9foyi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDirWithSuffix$'" - Finished OK in 25.28915253s (try 2/5)