"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSuffix)$'" - Starting (try 2/5)
2026/02/10 04:48:31 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-zoriyax6nafu"
2026/02/10 04:48:31 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/10 04:48:32 DEBUG : Creating backend with remote "/tmp/rclone1058318622"
=== RUN   TestSyncSuffix
    run.go:185: Remote "files root 'rclone-test-zoriyax6nafu'", Local "Local file system at /tmp/rclone1058318622", Modify Window "1s"
2026/02/10 04:48:36 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-zoriyax6nafu/dst"
2026/02/10 04:48:36 DEBUG : one: size = 4 (Local file system at /tmp/rclone1058318622)
2026/02/10 04:48:36 DEBUG : one: size = 3 (files root 'rclone-test-zoriyax6nafu/dst')
2026/02/10 04:48:36 DEBUG : one: Sizes differ
2026/02/10 04:48:37 INFO  : one: Moved (server-side) to: one.bak
2026/02/10 04:48:38 DEBUG : one: size = 4 OK
2026/02/10 04:48:38 DEBUG : one: Dst hash empty - aborting Src hash check
2026/02/10 04:48:38 INFO  : one: Copied (new)
2026/02/10 04:48:38 DEBUG : two: size = 3 OK
2026/02/10 04:48:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/02/10 04:48:38 DEBUG : two: Unchanged skipping
2026/02/10 04:48:38 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone1058318622)
2026/02/10 04:48:38 DEBUG : three.txt: size = 5 (files root 'rclone-test-zoriyax6nafu/dst')
2026/02/10 04:48:38 DEBUG : three.txt: Sizes differ
2026/02/10 04:48:38 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2026/02/10 04:48:39 DEBUG : three.txt: size = 6 OK
2026/02/10 04:48:39 DEBUG : three.txt: Dst hash empty - aborting Src hash check
2026/02/10 04:48:39 INFO  : three.txt: Copied (new)
2026/02/10 04:48:40 DEBUG : one: size = 5 (Local file system at /tmp/rclone1058318622)
2026/02/10 04:48:40 DEBUG : one: size = 4 (files root 'rclone-test-zoriyax6nafu/dst')
2026/02/10 04:48:40 DEBUG : one: Sizes differ
2026/02/10 04:48:40 INFO  : one.bak: Deleted
2026/02/10 04:48:41 INFO  : one: Moved (server-side) to: one.bak
2026/02/10 04:48:42 DEBUG : one: size = 5 OK
2026/02/10 04:48:42 DEBUG : one: Dst hash empty - aborting Src hash check
2026/02/10 04:48:42 INFO  : one: Copied (new)
2026/02/10 04:48:42 DEBUG : two: size = 3 OK
2026/02/10 04:48:42 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/02/10 04:48:42 DEBUG : two: Unchanged skipping
2026/02/10 04:48:42 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone1058318622)
2026/02/10 04:48:42 DEBUG : three.txt: size = 6 (files root 'rclone-test-zoriyax6nafu/dst')
2026/02/10 04:48:42 DEBUG : three.txt: Sizes differ
2026/02/10 04:48:42 INFO  : three.txt.bak: Deleted
2026/02/10 04:48:43 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2026/02/10 04:48:43 DEBUG : three.txt: size = 19 OK
2026/02/10 04:48:43 DEBUG : three.txt: Dst hash empty - aborting Src hash check
2026/02/10 04:48:43 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (13.21s)
=== RUN   TestSyncReplaceDirModTimeWithEmptyDirs
    run.go:185: Remote "files root 'rclone-test-zoriyax6nafu'", Local "Local file system at /tmp/rclone1058318622", Modify Window "1s"
2026/02/10 04:48:45 DEBUG : empty_dir: Making directory with metadata
2026/02/10 04:48:45 INFO  : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/02/10 04:48:45 DEBUG : empty_on_remote: Making directory with metadata
2026/02/10 04:48:45 INFO  : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2026/02/10 04:48:45 INFO  : empty_on_remote: Making directory
2026/02/10 04:48:45 INFO  : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
2026/02/10 04:48:48 INFO  : test_dir1: Set directory modification time (using DirSetModTime)
2026/02/10 04:48:48 INFO  : test_dir2: Set directory modification time (using DirSetModTime)
2026/02/10 04:48:48 INFO  : test_dir2/sub_dir: Set directory modification time (using DirSetModTime)
2026/02/10 04:48:48 INFO  : empty_on_remote: Set directory modification time (using DirSetModTime)
2026/02/10 04:48:48 INFO  : empty_dir: Set directory modification time (using DirSetModTime)
2026/02/10 04:48:51 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-zoriyax6nafu/test_dir2 not empty`)
2026/02/10 04:48:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/02/10 04:48:51 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-zoriyax6nafu/test_dir2 not empty`)
2026/02/10 04:48:51 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/02/10 04:48:51 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-zoriyax6nafu/test_dir2 not empty`)
2026/02/10 04:48:51 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/02/10 04:48:51 DEBUG : pacer: Reducing sleep to 60ms
2026/02/10 04:48:52 DEBUG : pacer: Reducing sleep to 45ms
2026/02/10 04:48:52 DEBUG : pacer: Reducing sleep to 33.75ms
2026/02/10 04:48:52 DEBUG : pacer: Reducing sleep to 25.3125ms
2026/02/10 04:48:52 DEBUG : pacer: Reducing sleep to 18.984375ms
--- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (7.11s)
PASS
2026/02/10 04:48:52 DEBUG : files root 'rclone-test-zoriyax6nafu': Purge remote
2026/02/10 04:48:52 DEBUG : pacer: Reducing sleep to 14.238281ms
"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSuffix)$'" - Finished OK in 21.233142175s (try 2/5)