"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDir)$'" - Starting (try 2/5)
2025/04/28 02:15:53 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hovawuc9pita"
2025/04/28 02:15:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/28 02:15:53 DEBUG : Starting OpenDrive session with ID: fb3c07424082eb7c51548440918e0f669a36b37e74145891429924ab6191903c
2025/04/28 02:15:54 DEBUG : Creating backend with remote "/tmp/rclone1335556635"
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "OpenDrive root 'rclone-test-hovawuc9pita'", Local "Local file system at /tmp/rclone1335556635", Modify Window "1s"
2025/04/28 02:15:55 DEBUG : b/potato: Uploading chunk 0, size=21, remain=0
2025/04/28 02:15:57 DEBUG : c/non empty space: Uploading chunk 0, size=5, remain=0
2025/04/28 02:15:58 INFO  : d: Making directory
2025/04/28 02:15:58 INFO  : d/e: Making directory
2025/04/28 02:16:00 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/04/28 02:16:00 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/04/28 02:16:00 DEBUG : c/non empty space: Unchanged skipping
2025/04/28 02:16:00 DEBUG : OpenDrive root 'rclone-test-hovawuc9pita': Waiting for checks to finish
2025/04/28 02:16:00 DEBUG : OpenDrive root 'rclone-test-hovawuc9pita': Waiting for transfers to finish
2025/04/28 02:16:01 DEBUG : a/potato2: Uploading chunk 0, size=60, remain=0
2025/04/28 02:16:02 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/04/28 02:16:02 INFO  : a/potato2: Copied (new)
2025/04/28 02:16:02 DEBUG : Waiting for deletions to finish
2025/04/28 02:16:02 INFO  : b/potato: Deleted
2025/04/28 02:16:02 INFO  : d/e: Removing directory
2025/04/28 02:16:02 INFO  : d: Removing directory
2025/04/28 02:16:03 INFO  : b: Removing directory
2025/04/28 02:16:03 DEBUG : OpenDrive root 'rclone-test-hovawuc9pita': deleted 3 directories
    run.go:180: Remote "OpenDrive root 'rclone-test-hovawuc9pita'", Local "Local file system at /tmp/rclone1335556635", Modify Window "1s"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (11.57s)
=== RUN   TestSyncBackupDir
    run.go:180: Remote "OpenDrive root 'rclone-test-hovawuc9pita'", Local "Local file system at /tmp/rclone1335556635", Modify Window "1s"
2025/04/28 02:16:06 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/04/28 02:16:08 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/04/28 02:16:10 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/04/28 02:16:11 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hovawuc9pita/dst"
2025/04/28 02:16:12 DEBUG : Starting OpenDrive session with ID: e379e73e421f07d70302183b6d0972262b892253e9b73b1335f354f9a4763803
2025/04/28 02:16:12 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hovawuc9pita/backup"
2025/04/28 02:16:12 DEBUG : Starting OpenDrive session with ID: 6b89134c6609371bc85d9db323916d141e12e82d0ebe9ce5364ab05a06697cb5
2025/04/28 02:16:13 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/04/28 02:16:13 DEBUG : OpenDrive root 'rclone-test-hovawuc9pita/dst': Waiting for checks to finish
2025/04/28 02:16:13 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/04/28 02:16:13 DEBUG : two: Unchanged skipping
2025/04/28 02:16:14 INFO  : one: Moved (server-side)
2025/04/28 02:16:14 DEBUG : OpenDrive root 'rclone-test-hovawuc9pita/dst': Waiting for transfers to finish
2025/04/28 02:16:15 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/04/28 02:16:16 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/04/28 02:16:16 INFO  : one: Copied (new)
2025/04/28 02:16:16 DEBUG : Waiting for deletions to finish
2025/04/28 02:16:16 INFO  : three.txt: Moved (server-side)
2025/04/28 02:16:16 INFO  : three.txt: Moved into backup dir
2025/04/28 02:16:18 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2025/04/28 02:16:19 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/04/28 02:16:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/04/28 02:16:19 DEBUG : two: Unchanged skipping
2025/04/28 02:16:19 DEBUG : OpenDrive root 'rclone-test-hovawuc9pita/dst': Waiting for checks to finish
2025/04/28 02:16:20 INFO  : one: Deleted
2025/04/28 02:16:20 INFO  : one: Moved (server-side)
2025/04/28 02:16:20 DEBUG : OpenDrive root 'rclone-test-hovawuc9pita/dst': Waiting for transfers to finish
2025/04/28 02:16:21 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/04/28 02:16:22 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/04/28 02:16:22 INFO  : one: Copied (new)
2025/04/28 02:16:22 DEBUG : Waiting for deletions to finish
2025/04/28 02:16:22 INFO  : three.txt: Deleted
2025/04/28 02:16:22 INFO  : three.txt: Moved (server-side)
2025/04/28 02:16:22 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (19.33s)
PASS
2025/04/28 02:16:25 DEBUG : OpenDrive root 'rclone-test-hovawuc9pita': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncBackupDir)$'" - Finished OK in 32.207371843s (try 2/5)