"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestSyncBackupDirWithSuffixKeepExtension)$'" - Starting (try 3/5)
2025/12/28 03:38:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-bosuqek4rifi"
2025/12/28 03:38:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/12/28 03:38:11 DEBUG : Starting OpenDrive session with ID: e43e6a76b3cda6bce7758cd27dac72bdccd8432a355b56011f2b7528bec78823
2025/12/28 03:38:11 DEBUG : Creating backend with remote "/tmp/rclone42844609"
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:185: Remote "OpenDrive root 'rclone-test-bosuqek4rifi'", Local "Local file system at /tmp/rclone42844609", Modify Window "1s"
2025/12/28 03:38:13 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/12/28 03:38:15 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/12/28 03:38:17 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/12/28 03:38:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-bosuqek4rifi/dst"
2025/12/28 03:38:19 DEBUG : Starting OpenDrive session with ID: bfdb885c5a54e041678368fbf6f8cbe3864e361697ebd245ed650e5795ac2256
2025/12/28 03:38:19 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-bosuqek4rifi/backup"
2025/12/28 03:38:20 DEBUG : Starting OpenDrive session with ID: 83a7b0ed572ac14d308e17cd8fc72ec1ad5ef05b37a7d7de10c9227079575377
2025/12/28 03:38:20 DEBUG : one: size = 4 (Local file system at /tmp/rclone42844609)
2025/12/28 03:38:20 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-bosuqek4rifi/dst')
2025/12/28 03:38:20 DEBUG : one: Sizes differ
2025/12/28 03:38:20 DEBUG : two: size = 3 OK
2025/12/28 03:38:20 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/12/28 03:38:20 DEBUG : two: Unchanged skipping
2025/12/28 03:38:20 DEBUG : OpenDrive root 'rclone-test-bosuqek4rifi/dst': Waiting for checks to finish
2025/12/28 03:38:21 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/12/28 03:38:21 DEBUG : OpenDrive root 'rclone-test-bosuqek4rifi/dst': Waiting for transfers to finish
2025/12/28 03:38:22 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/12/28 03:38:23 DEBUG : one: size = 4 OK
2025/12/28 03:38:23 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/12/28 03:38:23 INFO  : one: Copied (new)
2025/12/28 03:38:23 DEBUG : Waiting for deletions to finish
2025/12/28 03:38:24 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/12/28 03:38:24 INFO  : three.txt: Moved into backup dir
2025/12/28 03:38:25 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2025/12/28 03:38:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone42844609)
2025/12/28 03:38:27 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-bosuqek4rifi/dst')
2025/12/28 03:38:27 DEBUG : one: Sizes differ
2025/12/28 03:38:27 DEBUG : two: size = 3 OK
2025/12/28 03:38:27 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/12/28 03:38:27 DEBUG : OpenDrive root 'rclone-test-bosuqek4rifi/dst': Waiting for checks to finish
2025/12/28 03:38:27 DEBUG : two: Unchanged skipping
2025/12/28 03:38:27 INFO  : one-2019-01-01: Deleted
2025/12/28 03:38:28 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/12/28 03:38:28 DEBUG : OpenDrive root 'rclone-test-bosuqek4rifi/dst': Waiting for transfers to finish
2025/12/28 03:38:28 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/12/28 03:38:29 DEBUG : one: size = 5 OK
2025/12/28 03:38:29 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/12/28 03:38:29 INFO  : one: Copied (new)
2025/12/28 03:38:29 DEBUG : Waiting for deletions to finish
2025/12/28 03:38:30 INFO  : three-2019-01-01.txt: Deleted
2025/12/28 03:38:30 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/12/28 03:38:30 INFO  : three.txt: Moved into backup dir
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (28.78s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:185: Remote "OpenDrive root 'rclone-test-bosuqek4rifi'", Local "Local file system at /tmp/rclone42844609", Modify Window "1s"
2025/12/28 03:38:40 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/12/28 03:38:40 INFO  : sub dir: Making directory
2025/12/28 03:38:41 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
--- PASS: TestNothingToTransferWithEmptyDirs (16.45s)
PASS
2025/12/28 03:38:57 DEBUG : OpenDrive root 'rclone-test-bosuqek4rifi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestSyncBackupDirWithSuffixKeepExtension)$'" - Finished OK in 46.901833796s (try 3/5)