"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncCompareDest)$'" - Starting (try 2/5)
2025/08/26 03:02:22 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-vazeheq8pafe"
2025/08/26 03:02:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/26 03:02:23 DEBUG : Creating backend with remote "/tmp/rclone3034342108"
=== RUN   TestSyncCompareDest
    run.go:180: Remote "files root 'rclone-test-vazeheq8pafe'", Local "Local file system at /tmp/rclone3034342108", Modify Window "1s"
2025/08/26 03:02:23 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-vazeheq8pafe/dst"
2025/08/26 03:02:24 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-vazeheq8pafe/CompareDest"
2025/08/26 03:02:25 DEBUG : one: Need to transfer - File not found at Destination
2025/08/26 03:02:25 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for checks to finish
2025/08/26 03:02:25 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for transfers to finish
2025/08/26 03:02:26 DEBUG : one: Dst hash empty - aborting Src hash check
2025/08/26 03:02:26 INFO  : one: Copied (new)
2025/08/26 03:02:26 DEBUG : Waiting for deletions to finish
2025/08/26 03:02:27 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for checks to finish
2025/08/26 03:02:27 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/26 03:02:27 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for transfers to finish
2025/08/26 03:02:28 DEBUG : one: Dst hash empty - aborting Src hash check
2025/08/26 03:02:28 INFO  : one: Copied (replaced existing)
2025/08/26 03:02:28 DEBUG : Waiting for deletions to finish
2025/08/26 03:02:32 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for checks to finish
2025/08/26 03:02:32 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/26 03:02:32 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/08/26 03:02:32 DEBUG : one: Destination found in --compare-dest, skipping
2025/08/26 03:02:32 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for transfers to finish
2025/08/26 03:02:32 DEBUG : Waiting for deletions to finish
2025/08/26 03:02:32 INFO  : There was nothing to transfer
2025/08/26 03:02:33 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/26 03:02:34 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/08/26 03:02:34 DEBUG : two: Destination found in --compare-dest, skipping
2025/08/26 03:02:34 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for checks to finish
2025/08/26 03:02:34 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/08/26 03:02:34 DEBUG : one: Destination found in --compare-dest, skipping
2025/08/26 03:02:34 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for transfers to finish
2025/08/26 03:02:34 DEBUG : Waiting for deletions to finish
2025/08/26 03:02:34 INFO  : There was nothing to transfer
2025/08/26 03:02:34 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/26 03:02:35 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/08/26 03:02:35 DEBUG : two: Destination found in --compare-dest, skipping
2025/08/26 03:02:35 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for checks to finish
2025/08/26 03:02:35 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/08/26 03:02:35 DEBUG : one: Destination found in --compare-dest, skipping
2025/08/26 03:02:35 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for transfers to finish
2025/08/26 03:02:35 DEBUG : Waiting for deletions to finish
2025/08/26 03:02:35 INFO  : There was nothing to transfer
2025/08/26 03:02:35 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/26 03:02:35 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC
2025/08/26 03:02:35 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2025/08/26 03:02:35 DEBUG : two: Destination found in --compare-dest, skipping
2025/08/26 03:02:35 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for checks to finish
2025/08/26 03:02:35 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/08/26 03:02:35 DEBUG : one: Destination found in --compare-dest, skipping
2025/08/26 03:02:35 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for transfers to finish
2025/08/26 03:02:35 DEBUG : Waiting for deletions to finish
2025/08/26 03:02:35 INFO  : There was nothing to transfer
2025/08/26 03:02:36 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/08/26 03:02:36 DEBUG : two: Sizes differ (src 5 vs dst 3)
2025/08/26 03:02:36 DEBUG : two: Need to transfer - File not found at Destination
2025/08/26 03:02:36 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for checks to finish
2025/08/26 03:02:36 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/08/26 03:02:36 DEBUG : one: Destination found in --compare-dest, skipping
2025/08/26 03:02:36 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for transfers to finish
2025/08/26 03:02:37 DEBUG : two: Dst hash empty - aborting Src hash check
2025/08/26 03:02:37 INFO  : two: Copied (new)
2025/08/26 03:02:37 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (15.93s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "files root 'rclone-test-vazeheq8pafe'", Local "Local file system at /tmp/rclone3034342108", Modify Window "1s"
2025/08/26 03:02:42 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-vazeheq8pafe/dst"
2025/08/26 03:02:43 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/08/26 03:02:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/08/26 03:02:43 DEBUG : two: Unchanged skipping
2025/08/26 03:02:43 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for checks to finish
2025/08/26 03:02:43 INFO  : one: Moved (server-side) to: one.bak
2025/08/26 03:02:43 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for transfers to finish
2025/08/26 03:02:44 DEBUG : one: Dst hash empty - aborting Src hash check
2025/08/26 03:02:44 INFO  : one: Copied (new)
2025/08/26 03:02:44 DEBUG : Waiting for deletions to finish
2025/08/26 03:02:45 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/08/26 03:02:45 INFO  : three.txt: Moved into backup dir
2025/08/26 03:02:48 DEBUG : one.bak: Excluded (Path Filter)
2025/08/26 03:02:48 DEBUG : one.bak: Excluded
2025/08/26 03:02:48 DEBUG : three.txt.bak: Excluded (Path Filter)
2025/08/26 03:02:48 DEBUG : three.txt.bak: Excluded
2025/08/26 03:02:48 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/08/26 03:02:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/08/26 03:02:48 DEBUG : two: Unchanged skipping
2025/08/26 03:02:48 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for checks to finish
2025/08/26 03:02:48 INFO  : one.bak: Deleted
2025/08/26 03:02:48 INFO  : one: Moved (server-side) to: one.bak
2025/08/26 03:02:48 DEBUG : files root 'rclone-test-vazeheq8pafe/dst': Waiting for transfers to finish
2025/08/26 03:02:49 DEBUG : one: Dst hash empty - aborting Src hash check
2025/08/26 03:02:49 INFO  : one: Copied (new)
2025/08/26 03:02:49 DEBUG : Waiting for deletions to finish
2025/08/26 03:02:49 INFO  : three.txt.bak: Deleted
2025/08/26 03:02:50 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/08/26 03:02:50 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (13.36s)
PASS
2025/08/26 03:02:52 DEBUG : files root 'rclone-test-vazeheq8pafe': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncCompareDest)$'" - Finished OK in 30.295894257s (try 2/5)