"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestServerSideCopy|TestSyncAfterChangingModtimeOnly|TestSyncIgnoreExisting|TestSyncUTFNorm)$'" - Starting (try 2/5)
2025/07/29 04:27:54 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-febepih6foyi"
2025/07/29 04:27:54 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/29 04:27:55 DEBUG : Starting OpenDrive session with ID: abf890f5fea720bf978f366ec058ee23da69f794249cce3918f39d3b6b783c85
2025/07/29 04:27:55 DEBUG : Creating backend with remote "/tmp/rclone2720685457"
=== RUN   TestServerSideCopy
    run.go:180: Remote "OpenDrive root 'rclone-test-febepih6foyi'", Local "Local file system at /tmp/rclone2720685457", Modify Window "1s"
2025/07/29 04:27:57 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0
2025/07/29 04:27:58 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cecaxoj1sere"
2025/07/29 04:27:58 DEBUG : Starting OpenDrive session with ID: 5b83d6486cba7242be5c0d7cc539bfa88228f4a732b9cae851f5bc4a06277fee
    sync_test.go:620: Server side copy (if possible) OpenDrive root 'rclone-test-febepih6foyi' -> OpenDrive root 'rclone-test-cecaxoj1sere'
2025/07/29 04:27:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/07/29 04:27:59 DEBUG : OpenDrive root 'rclone-test-cecaxoj1sere': Waiting for checks to finish
2025/07/29 04:27:59 DEBUG : OpenDrive root 'rclone-test-cecaxoj1sere': Waiting for transfers to finish
2025/07/29 04:28:00 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/07/29 04:28:00 INFO  : sub dir/hello world: Copied (server-side copy)
2025/07/29 04:28:01 DEBUG : OpenDrive root 'rclone-test-cecaxoj1sere': Purge remote
--- PASS: TestServerSideCopy (6.71s)
=== RUN   TestSyncIgnoreExisting
    run.go:180: Remote "OpenDrive root 'rclone-test-febepih6foyi'", Local "Local file system at /tmp/rclone2720685457", Modify Window "1s"
2025/07/29 04:28:02 DEBUG : existing: Need to transfer - File not found at Destination
2025/07/29 04:28:02 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for checks to finish
2025/07/29 04:28:02 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for transfers to finish
2025/07/29 04:28:03 DEBUG : existing: Uploading chunk 0, size=6, remain=0
2025/07/29 04:28:04 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK
2025/07/29 04:28:04 INFO  : existing: Copied (new)
2025/07/29 04:28:04 DEBUG : Waiting for deletions to finish
2025/07/29 04:28:04 DEBUG : existing: Destination exists, skipping
2025/07/29 04:28:04 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for checks to finish
2025/07/29 04:28:04 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for transfers to finish
2025/07/29 04:28:04 DEBUG : Waiting for deletions to finish
2025/07/29 04:28:04 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreExisting (3.09s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:180: Remote "OpenDrive root 'rclone-test-febepih6foyi'", Local "Local file system at /tmp/rclone2720685457", Modify Window "1s"
2025/07/29 04:28:06 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
2025/07/29 04:28:07 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for checks to finish
2025/07/29 04:28:07 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/07/29 04:28:07 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/07/29 04:28:07 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1)
2025/07/29 04:28:07 DEBUG : empty space: Unchanged skipping
2025/07/29 04:28:07 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for transfers to finish
2025/07/29 04:28:07 DEBUG : Waiting for deletions to finish
2025/07/29 04:28:07 INFO  : There was nothing to transfer
2025/07/29 04:28:08 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/07/29 04:28:08 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for checks to finish
2025/07/29 04:28:08 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/07/29 04:28:08 INFO  : empty space: Updated modification time in destination
2025/07/29 04:28:08 DEBUG : empty space: Unchanged skipping
2025/07/29 04:28:08 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for transfers to finish
2025/07/29 04:28:08 DEBUG : Waiting for deletions to finish
2025/07/29 04:28:08 INFO  : There was nothing to transfer
--- PASS: TestSyncAfterChangingModtimeOnly (3.39s)
=== RUN   TestSyncUTFNorm
    run.go:180: Remote "OpenDrive root 'rclone-test-febepih6foyi'", Local "Local file system at /tmp/rclone2720685457", Modify Window "1s"
2025/07/29 04:28:09 DEBUG : Testêé: Uploading chunk 0, size=18, remain=0
2025/07/29 04:28:10 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2025/07/29 04:28:10 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for checks to finish
2025/07/29 04:28:10 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Waiting for transfers to finish
2025/07/29 04:28:11 DEBUG : Testêé: Uploading chunk 0, size=14, remain=0
2025/07/29 04:28:12 DEBUG : Testêé: md5 = ce114e4501d2f4e2dcea3e17b546f339 OK
2025/07/29 04:28:12 INFO  : Testêé: Copied (replaced existing) to: Testêé
2025/07/29 04:28:12 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (3.93s)
PASS
2025/07/29 04:28:12 DEBUG : OpenDrive root 'rclone-test-febepih6foyi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestServerSideCopy|TestSyncAfterChangingModtimeOnly|TestSyncIgnoreExisting|TestSyncUTFNorm)$'" - Finished OK in 18.3922304s (try 2/5)