"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncAfterChangingModtimeOnly|TestSyncBackupDirWithSuffix|TestSyncReplaceDirModTimeWithEmptyDirs)$'" - Starting (try 2/5)
2025/09/05 02:43:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cokoquh8saka"
2025/09/05 02:43:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/05 02:43:17 DEBUG : Starting OpenDrive session with ID: 55e8a080f7476b1dc3739a5bfabf061bde1c4813a6fb1e529ef0973c290c3e65
2025/09/05 02:43:17 DEBUG : Creating backend with remote "/tmp/rclone3853968394"
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:180: Remote "OpenDrive root 'rclone-test-cokoquh8saka'", Local "Local file system at /tmp/rclone3853968394", Modify Window "1s"
2025/09/05 02:43:19 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
2025/09/05 02:43:20 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC
2025/09/05 02:43:20 DEBUG : OpenDrive root 'rclone-test-cokoquh8saka': Waiting for checks to finish
2025/09/05 02:43:20 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/09/05 02:43:20 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1)
2025/09/05 02:43:20 DEBUG : empty space: Unchanged skipping
2025/09/05 02:43:20 DEBUG : OpenDrive root 'rclone-test-cokoquh8saka': Waiting for transfers to finish
2025/09/05 02:43:20 DEBUG : Waiting for deletions to finish
2025/09/05 02:43:20 INFO  : There was nothing to transfer
2025/09/05 02:43:20 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC
2025/09/05 02:43:20 DEBUG : OpenDrive root 'rclone-test-cokoquh8saka': Waiting for checks to finish
2025/09/05 02:43:20 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/09/05 02:43:21 INFO  : empty space: Updated modification time in destination
2025/09/05 02:43:21 DEBUG : empty space: Unchanged skipping
2025/09/05 02:43:21 DEBUG : OpenDrive root 'rclone-test-cokoquh8saka': Waiting for transfers to finish
2025/09/05 02:43:21 DEBUG : Waiting for deletions to finish
2025/09/05 02:43:21 INFO  : There was nothing to transfer
--- PASS: TestSyncAfterChangingModtimeOnly (4.15s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "OpenDrive root 'rclone-test-cokoquh8saka'", Local "Local file system at /tmp/rclone3853968394", Modify Window "1s"
2025/09/05 02:43:23 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/09/05 02:43:25 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/09/05 02:43:27 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/09/05 02:43:28 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cokoquh8saka/dst"
2025/09/05 02:43:29 DEBUG : Starting OpenDrive session with ID: f57219528aeea51479470b1e20486740bca9d85f6a7540de4eb9f2a3fb12531b
2025/09/05 02:43:29 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cokoquh8saka/backup"
2025/09/05 02:43:30 DEBUG : Starting OpenDrive session with ID: b9b434d11caf054662af0a3b90a3fe0f47fe2ba1b3f43c3f40b409d39ab76219
2025/09/05 02:43:31 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/09/05 02:43:31 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/09/05 02:43:31 DEBUG : two: Unchanged skipping
2025/09/05 02:43:31 DEBUG : OpenDrive root 'rclone-test-cokoquh8saka/dst': Waiting for checks to finish
2025/09/05 02:43:32 INFO  : one: Moved (server-side) to: one.bak
2025/09/05 02:43:32 DEBUG : OpenDrive root 'rclone-test-cokoquh8saka/dst': Waiting for transfers to finish
2025/09/05 02:43:33 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/09/05 02:43:34 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/09/05 02:43:34 INFO  : one: Copied (new)
2025/09/05 02:43:34 DEBUG : Waiting for deletions to finish
2025/09/05 02:43:34 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/09/05 02:43:34 INFO  : three.txt: Moved into backup dir
2025/09/05 02:43:36 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2025/09/05 02:43:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/09/05 02:43:38 DEBUG : two: Unchanged skipping
2025/09/05 02:43:38 DEBUG : OpenDrive root 'rclone-test-cokoquh8saka/dst': Waiting for checks to finish
2025/09/05 02:43:38 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/09/05 02:43:38 INFO  : one.bak: Deleted
2025/09/05 02:43:38 INFO  : one: Moved (server-side) to: one.bak
2025/09/05 02:43:38 DEBUG : OpenDrive root 'rclone-test-cokoquh8saka/dst': Waiting for transfers to finish
2025/09/05 02:43:39 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/09/05 02:43:40 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/09/05 02:43:40 INFO  : one: Copied (new)
2025/09/05 02:43:40 DEBUG : Waiting for deletions to finish
2025/09/05 02:43:41 INFO  : three.txt.bak: Deleted
2025/09/05 02:43:41 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/09/05 02:43:41 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (22.32s)
=== RUN   TestSyncReplaceDirModTimeWithEmptyDirs
    run.go:180: Remote "OpenDrive root 'rclone-test-cokoquh8saka'", Local "Local file system at /tmp/rclone3853968394", Modify Window "1s"
2025/09/05 02:43:44 DEBUG : empty_dir: Making directory with metadata
2025/09/05 02:43:44 INFO  : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/09/05 02:43:44 DEBUG : empty_on_remote: Making directory with metadata
2025/09/05 02:43:44 INFO  : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/09/05 02:43:44 INFO  : empty_on_remote: Making directory
2025/09/05 02:43:44 INFO  : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
2025/09/05 02:43:49 INFO  : test_dir1: Set directory modification time (using DirSetModTime)
2025/09/05 02:43:49 INFO  : test_dir2: Set directory modification time (using DirSetModTime)
2025/09/05 02:43:49 INFO  : test_dir2/sub_dir: Set directory modification time (using DirSetModTime)
2025/09/05 02:43:49 INFO  : empty_on_remote: Set directory modification time (using DirSetModTime)
2025/09/05 02:43:49 INFO  : empty_dir: Set directory modification time (using DirSetModTime)
--- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (9.16s)
PASS
2025/09/05 02:43:53 DEBUG : OpenDrive root 'rclone-test-cokoquh8saka': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncAfterChangingModtimeOnly|TestSyncBackupDirWithSuffix|TestSyncReplaceDirModTimeWithEmptyDirs)$'" - Finished OK in 37.130076917s (try 2/5)