"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestSyncBackupDirWithSuffix|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse|TestSyncNoUpdateDirModtime|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSetDelayedModTimes)$'" - Starting (try 2/5) 2025/08/25 02:19:08 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-favimuz9qohi" 2025/08/25 02:19:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/25 02:19:09 DEBUG : Creating backend with remote "/tmp/rclone525977954" === RUN TestSyncNoTraverse run.go:180: Remote "files root 'rclone-test-favimuz9qohi'", Local "Local file system at /tmp/rclone525977954", Modify Window "1s" 2025/08/25 02:19:09 ERROR : Ignoring --no-traverse with sync 2025/08/25 02:19:09 DEBUG : Added delayed dir = "sub dir", newDst= 2025/08/25 02:19:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/25 02:19:09 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for checks to finish 2025/08/25 02:19:09 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for transfers to finish 2025/08/25 02:19:11 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/08/25 02:19:11 INFO : sub dir/hello world: Copied (new) 2025/08/25 02:19:11 DEBUG : Waiting for deletions to finish 2025/08/25 02:19:11 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncNoTraverse (3.09s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "files root 'rclone-test-favimuz9qohi'", Local "Local file system at /tmp/rclone525977954", Modify Window "1s" 2025/08/25 02:19:12 DEBUG : sub dir no update dir modtime: Making directory with metadata 2025/08/25 02:19:12 INFO : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/08/25 02:19:13 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for checks to finish 2025/08/25 02:19:13 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for transfers to finish 2025/08/25 02:19:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoUpdateDirModtime (1.61s) === RUN TestSyncEmptyDirectories run.go:180: Remote "files root 'rclone-test-favimuz9qohi'", Local "Local file system at /tmp/rclone525977954", Modify Window "1s" 2025/08/25 02:19:14 DEBUG : sub dir2: Making directory with metadata 2025/08/25 02:19:14 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/08/25 02:19:14 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:14 INFO : sub dir2: Making directory 2025/08/25 02:19:14 INFO : sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/08/25 02:19:14 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/08/25 02:19:14 INFO : sub dir: Making directory 2025/08/25 02:19:15 INFO : sub dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2025/08/25 02:19:15 DEBUG : Added delayed dir = "sub dir", newDst= 2025/08/25 02:19:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/25 02:19:15 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for checks to finish 2025/08/25 02:19:15 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for transfers to finish 2025/08/25 02:19:15 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/08/25 02:19:15 INFO : sub dir/hello world: Copied (new) 2025/08/25 02:19:15 DEBUG : Waiting for deletions to finish 2025/08/25 02:19:16 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:16 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncEmptyDirectories (3.46s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "files root 'rclone-test-favimuz9qohi'", Local "Local file system at /tmp/rclone525977954", Modify Window "1s" 2025/08/25 02:19:17 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:17 INFO : a1: Making directory 2025/08/25 02:19:18 INFO : a1: Made directory with modification time 2001-02-03 04:19:06.499999999 +0000 UTC 2025/08/25 02:19:18 DEBUG : Added delayed dir = "a1", newDst= 2025/08/25 02:19:18 INFO : a1/b1: Making directory 2025/08/25 02:19:18 INFO : a1/b1: Made directory with modification time 2001-02-03 04:18:06.499999999 +0000 UTC 2025/08/25 02:19:18 DEBUG : Added delayed dir = "a1/b1", newDst= 2025/08/25 02:19:18 INFO : a1/b2: Making directory 2025/08/25 02:19:18 INFO : a1/b2: Made directory with modification time 2001-02-03 04:09:06.499999999 +0000 UTC 2025/08/25 02:19:18 DEBUG : Added delayed dir = "a1/b2", newDst= 2025/08/25 02:19:18 INFO : a1/b1/c1: Making directory 2025/08/25 02:19:18 INFO : a1/b2/c1: Making directory 2025/08/25 02:19:19 INFO : a1/b1/c1: Made directory with modification time 2001-02-03 04:17:06.499999999 +0000 UTC 2025/08/25 02:19:19 DEBUG : Added delayed dir = "a1/b1/c1", newDst= 2025/08/25 02:19:19 INFO : a1/b1/c1/d1: Making directory 2025/08/25 02:19:19 INFO : a1/b2/c1: Made directory with modification time 2001-02-03 04:08:06.499999999 +0000 UTC 2025/08/25 02:19:19 DEBUG : Added delayed dir = "a1/b2/c1", newDst= 2025/08/25 02:19:19 INFO : a1/b2/c1/d1: Making directory 2025/08/25 02:19:19 INFO : a1/b1/c1/d1: Made directory with modification time 2001-02-03 04:16:06.499999999 +0000 UTC 2025/08/25 02:19:19 DEBUG : Added delayed dir = "a1/b1/c1/d1", newDst= 2025/08/25 02:19:19 INFO : a1/b1/c1/d2: Making directory 2025/08/25 02:19:19 INFO : a1/b2/c1/d1: Made directory with modification time 2001-02-03 04:07:06.499999999 +0000 UTC 2025/08/25 02:19:19 DEBUG : Added delayed dir = "a1/b2/c1/d1", newDst= 2025/08/25 02:19:19 INFO : a1/b2/c1/d1/e1: Making directory 2025/08/25 02:19:19 INFO : a1/b1/c1/d2: Made directory with modification time 2001-02-03 04:13:06.499999999 +0000 UTC 2025/08/25 02:19:19 DEBUG : Added delayed dir = "a1/b1/c1/d2", newDst= 2025/08/25 02:19:19 INFO : a1/b1/c1/d2/e1: Making directory 2025/08/25 02:19:19 INFO : a1/b1/c1/d1/e1: Making directory 2025/08/25 02:19:19 INFO : a1/b2/c1/d1/e1: Made directory with modification time 2001-02-03 04:06:06.499999999 +0000 UTC 2025/08/25 02:19:19 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1", newDst= 2025/08/25 02:19:19 INFO : a1/b2/c1/d1/e1/f1: Making directory 2025/08/25 02:19:20 INFO : a1/b1/c1/d2/e1: Made directory with modification time 2001-02-03 04:12:06.499999999 +0000 UTC 2025/08/25 02:19:20 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1", newDst= 2025/08/25 02:19:20 INFO : a1/b1/c1/d2/e1/f1: Making directory 2025/08/25 02:19:20 INFO : a1/b1/c1/d1/e1: Made directory with modification time 2001-02-03 04:15:06.499999999 +0000 UTC 2025/08/25 02:19:20 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1", newDst= 2025/08/25 02:19:20 INFO : a1/b1/c1/d1/e1/f1: Making directory 2025/08/25 02:19:20 INFO : a1/b2/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2025/08/25 02:19:20 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1/f1", newDst= 2025/08/25 02:19:20 INFO : a1/b1/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:14:06.499999999 +0000 UTC 2025/08/25 02:19:20 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1/f1", newDst= 2025/08/25 02:19:20 INFO : a1/b1/c1/d2/e1/f1: Made directory with modification time 2001-02-03 04:11:06.499999999 +0000 UTC 2025/08/25 02:19:20 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f1", newDst= 2025/08/25 02:19:20 INFO : a1/b1/c1/d2/e1/f2: Making directory 2025/08/25 02:19:20 INFO : a1/b1/c1/d2/e1/f2: Made directory with modification time 2001-02-03 04:10:06.499999999 +0000 UTC 2025/08/25 02:19:20 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f2", newDst= 2025/08/25 02:19:20 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for checks to finish 2025/08/25 02:19:20 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for transfers to finish 2025/08/25 02:19:20 DEBUG : Waiting for deletions to finish 2025/08/25 02:19:21 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:21 INFO : a1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:26 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b2/c1/d1/e1 not empty`) 2025/08/25 02:19:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/08/25 02:19:26 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b2/c1/d1/e1 not empty`) 2025/08/25 02:19:26 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/08/25 02:19:26 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b2/c1/d1/e1 not empty`) 2025/08/25 02:19:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/08/25 02:19:26 DEBUG : pacer: Reducing sleep to 60ms 2025/08/25 02:19:26 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b2/c1/d1 not empty`) 2025/08/25 02:19:26 DEBUG : pacer: Rate limited, increasing sleep to 120ms 2025/08/25 02:19:26 DEBUG : pacer: Reducing sleep to 90ms 2025/08/25 02:19:27 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b2/c1 not empty`) 2025/08/25 02:19:27 DEBUG : pacer: Rate limited, increasing sleep to 180ms 2025/08/25 02:19:27 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b2/c1 not empty`) 2025/08/25 02:19:27 DEBUG : pacer: Rate limited, increasing sleep to 360ms 2025/08/25 02:19:27 DEBUG : pacer: Reducing sleep to 270ms 2025/08/25 02:19:27 DEBUG : pacer: Reducing sleep to 202.5ms 2025/08/25 02:19:27 DEBUG : pacer: Reducing sleep to 151.875ms 2025/08/25 02:19:28 DEBUG : pacer: Reducing sleep to 113.90625ms 2025/08/25 02:19:28 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b1/c1/d2/e1 not empty`) 2025/08/25 02:19:28 DEBUG : pacer: Rate limited, increasing sleep to 227.8125ms 2025/08/25 02:19:28 DEBUG : pacer: Reducing sleep to 170.859375ms 2025/08/25 02:19:28 DEBUG : pacer: Reducing sleep to 128.144531ms 2025/08/25 02:19:28 DEBUG : pacer: Reducing sleep to 96.108398ms 2025/08/25 02:19:29 DEBUG : pacer: Reducing sleep to 72.081298ms 2025/08/25 02:19:29 DEBUG : pacer: Reducing sleep to 54.060973ms 2025/08/25 02:19:29 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b1/c1 not empty`) 2025/08/25 02:19:29 DEBUG : pacer: Rate limited, increasing sleep to 108.121946ms 2025/08/25 02:19:29 DEBUG : pacer: Reducing sleep to 81.091459ms 2025/08/25 02:19:29 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b1 not empty`) 2025/08/25 02:19:29 DEBUG : pacer: Rate limited, increasing sleep to 162.182918ms 2025/08/25 02:19:30 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/a1/b1 not empty`) 2025/08/25 02:19:30 DEBUG : pacer: Rate limited, increasing sleep to 324.365836ms 2025/08/25 02:19:30 DEBUG : pacer: Reducing sleep to 243.274377ms 2025/08/25 02:19:30 DEBUG : pacer: Reducing sleep to 182.455782ms 2025/08/25 02:19:30 DEBUG : pacer: Reducing sleep to 136.841836ms --- PASS: TestSyncSetDelayedModTimes (13.15s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "files root 'rclone-test-favimuz9qohi'", Local "Local file system at /tmp/rclone525977954", Modify Window "1s" 2025/08/25 02:19:30 INFO : sub dir2: Making directory 2025/08/25 02:19:30 DEBUG : pacer: Reducing sleep to 102.631377ms 2025/08/25 02:19:31 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/08/25 02:19:31 DEBUG : Added delayed dir = "sub dir", newDst= 2025/08/25 02:19:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/25 02:19:31 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for checks to finish 2025/08/25 02:19:31 DEBUG : files root 'rclone-test-favimuz9qohi': Waiting for transfers to finish 2025/08/25 02:19:32 DEBUG : pacer: Reducing sleep to 76.973532ms 2025/08/25 02:19:32 DEBUG : pacer: Reducing sleep to 57.730149ms 2025/08/25 02:19:32 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/08/25 02:19:32 INFO : sub dir/hello world: Copied (new) 2025/08/25 02:19:32 DEBUG : Waiting for deletions to finish 2025/08/25 02:19:32 DEBUG : pacer: Reducing sleep to 43.297611ms 2025/08/25 02:19:32 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:32 DEBUG : pacer: Reducing sleep to 32.473208ms 2025/08/25 02:19:32 DEBUG : pacer: Reducing sleep to 24.354906ms 2025/08/25 02:19:32 DEBUG : pacer: Reducing sleep to 18.266179ms 2025/08/25 02:19:32 DEBUG : pacer: Reducing sleep to 13.699634ms 2025/08/25 02:19:33 DEBUG : pacer: Reducing sleep to 10.274725ms 2025/08/25 02:19:33 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestSyncNoEmptyDirectories (2.57s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "files root 'rclone-test-favimuz9qohi'", Local "Local file system at /tmp/rclone525977954", Modify Window "1s" 2025/08/25 02:19:36 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-favimuz9qohi/dst" 2025/08/25 02:19:37 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-favimuz9qohi/backup" 2025/08/25 02:19:38 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/25 02:19:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/25 02:19:38 DEBUG : two: Unchanged skipping 2025/08/25 02:19:38 DEBUG : files root 'rclone-test-favimuz9qohi/dst': Waiting for checks to finish 2025/08/25 02:19:38 INFO : one: Moved (server-side) to: one.bak 2025/08/25 02:19:38 DEBUG : files root 'rclone-test-favimuz9qohi/dst': Waiting for transfers to finish 2025/08/25 02:19:40 DEBUG : one: Dst hash empty - aborting Src hash check 2025/08/25 02:19:40 INFO : one: Copied (new) 2025/08/25 02:19:40 DEBUG : Waiting for deletions to finish 2025/08/25 02:19:40 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/08/25 02:19:40 INFO : three.txt: Moved into backup dir 2025/08/25 02:19:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/25 02:19:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/25 02:19:43 DEBUG : two: Unchanged skipping 2025/08/25 02:19:43 DEBUG : files root 'rclone-test-favimuz9qohi/dst': Waiting for checks to finish 2025/08/25 02:19:43 INFO : one.bak: Deleted 2025/08/25 02:19:44 INFO : one: Moved (server-side) to: one.bak 2025/08/25 02:19:44 DEBUG : files root 'rclone-test-favimuz9qohi/dst': Waiting for transfers to finish 2025/08/25 02:19:45 DEBUG : one: Dst hash empty - aborting Src hash check 2025/08/25 02:19:45 INFO : one: Copied (new) 2025/08/25 02:19:45 DEBUG : Waiting for deletions to finish 2025/08/25 02:19:45 INFO : three.txt.bak: Deleted 2025/08/25 02:19:46 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/08/25 02:19:46 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (14.30s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "files root 'rclone-test-favimuz9qohi'", Local "Local file system at /tmp/rclone525977954", Modify Window "1s" 2025/08/25 02:19:47 DEBUG : empty_dir: Making directory with metadata 2025/08/25 02:19:47 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/08/25 02:19:47 DEBUG : empty_on_remote: Making directory with metadata 2025/08/25 02:19:47 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/08/25 02:19:47 INFO : empty_on_remote: Making directory 2025/08/25 02:19:48 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/08/25 02:19:50 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:50 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:50 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:50 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:50 INFO : empty_dir: Set directory modification time (using DirSetModTime) 2025/08/25 02:19:54 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/test_dir2 not empty`) 2025/08/25 02:19:54 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/08/25 02:19:54 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-favimuz9qohi/test_dir2 not empty`) 2025/08/25 02:19:54 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/08/25 02:19:54 DEBUG : pacer: Reducing sleep to 30ms 2025/08/25 02:19:54 DEBUG : pacer: Reducing sleep to 22.5ms 2025/08/25 02:19:54 DEBUG : pacer: Reducing sleep to 16.875ms 2025/08/25 02:19:54 DEBUG : pacer: Reducing sleep to 12.65625ms 2025/08/25 02:19:54 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (7.40s) PASS 2025/08/25 02:19:55 DEBUG : files root 'rclone-test-favimuz9qohi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestSyncBackupDirWithSuffix|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse|TestSyncNoUpdateDirModtime|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSetDelayedModTimes)$'" - Finished OK in 46.554742718s (try 2/5)