"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestFixCase|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopy|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncReplaceDirModTime|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSetDelayedModTimes)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Starting (try 2/5) 2026/06/27 05:22:20 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-revobog8bine" 2026/06/27 05:22:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/06/27 05:22:21 DEBUG : Creating backend with remote "/tmp/rclone3871995422" === RUN TestCopyNoEmptyDirectories run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:22:21 INFO : sub dir2: Making directory 2026/06/27 05:22:21 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/06/27 05:22:21 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/27 05:22:21 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/06/27 05:22:21 DEBUG : Added delayed dir = "sub dir", newDst= 2026/06/27 05:22:21 DEBUG : Added delayed dir = "sub dir2/sub sub dir2", newDst= 2026/06/27 05:22:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/06/27 05:22:21 DEBUG : files root 'rclone-test-revobog8bine': Waiting for checks to finish 2026/06/27 05:22:21 DEBUG : files root 'rclone-test-revobog8bine': Waiting for transfers to finish 2026/06/27 05:22:23 DEBUG : sub dir/hello world: size = 11 OK 2026/06/27 05:22:23 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/06/27 05:22:23 INFO : sub dir/hello world: Copied (new) 2026/06/27 05:22:23 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyNoEmptyDirectories (3.21s) === RUN TestSyncNoUpdateDirModtime run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:22:24 DEBUG : sub dir no update dir modtime: Making directory with metadata 2026/06/27 05:22:24 INFO : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/06/27 05:22:25 DEBUG : files root 'rclone-test-revobog8bine': Waiting for checks to finish 2026/06/27 05:22:25 DEBUG : files root 'rclone-test-revobog8bine': Waiting for transfers to finish 2026/06/27 05:22:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoUpdateDirModtime (1.52s) === RUN TestSyncEmptyDirectories run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:22:26 DEBUG : sub dir2: Making directory with metadata 2026/06/27 05:22:26 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/27 05:22:26 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:26 INFO : sub dir2: Making directory 2026/06/27 05:22:26 INFO : sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/06/27 05:22:26 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/06/27 05:22:26 INFO : sub dir: Making directory 2026/06/27 05:22:26 INFO : sub dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2026/06/27 05:22:26 DEBUG : Added delayed dir = "sub dir", newDst= 2026/06/27 05:22:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/06/27 05:22:26 DEBUG : files root 'rclone-test-revobog8bine': Waiting for checks to finish 2026/06/27 05:22:26 DEBUG : files root 'rclone-test-revobog8bine': Waiting for transfers to finish 2026/06/27 05:22:27 DEBUG : sub dir/hello world: size = 11 OK 2026/06/27 05:22:27 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/06/27 05:22:27 INFO : sub dir/hello world: Copied (new) 2026/06/27 05:22:27 DEBUG : Waiting for deletions to finish 2026/06/27 05:22:27 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:27 INFO : sub dir2: Set directory modification time (using DirSetModTime) --- PASS: TestSyncEmptyDirectories (3.18s) === RUN TestSyncSetDelayedModTimes run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:22:29 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:29 INFO : a1: Making directory 2026/06/27 05:22:29 INFO : a1: Made directory with modification time 2001-02-03 04:19:06.499999999 +0000 UTC 2026/06/27 05:22:29 DEBUG : Added delayed dir = "a1", newDst= 2026/06/27 05:22:29 INFO : a1/b1: Making directory 2026/06/27 05:22:30 INFO : a1/b1: Made directory with modification time 2001-02-03 04:18:06.499999999 +0000 UTC 2026/06/27 05:22:30 DEBUG : Added delayed dir = "a1/b1", newDst= 2026/06/27 05:22:30 INFO : a1/b2: Making directory 2026/06/27 05:22:30 INFO : a1/b2: Made directory with modification time 2001-02-03 04:09:06.499999999 +0000 UTC 2026/06/27 05:22:30 DEBUG : Added delayed dir = "a1/b2", newDst= 2026/06/27 05:22:30 INFO : a1/b1/c1: Making directory 2026/06/27 05:22:30 INFO : a1/b2/c1: Making directory 2026/06/27 05:22:30 INFO : a1/b1/c1: Made directory with modification time 2001-02-03 04:17:06.499999999 +0000 UTC 2026/06/27 05:22:30 DEBUG : Added delayed dir = "a1/b1/c1", newDst= 2026/06/27 05:22:30 INFO : a1/b1/c1/d1: Making directory 2026/06/27 05:22:30 INFO : a1/b2/c1: Made directory with modification time 2001-02-03 04:08:06.499999999 +0000 UTC 2026/06/27 05:22:30 DEBUG : Added delayed dir = "a1/b2/c1", newDst= 2026/06/27 05:22:30 INFO : a1/b2/c1/d1: Making directory 2026/06/27 05:22:31 INFO : a1/b2/c1/d1: Made directory with modification time 2001-02-03 04:07:06.499999999 +0000 UTC 2026/06/27 05:22:31 DEBUG : Added delayed dir = "a1/b2/c1/d1", newDst= 2026/06/27 05:22:31 INFO : a1/b2/c1/d1/e1: Making directory 2026/06/27 05:22:31 INFO : a1/b1/c1/d1: Made directory with modification time 2001-02-03 04:16:06.499999999 +0000 UTC 2026/06/27 05:22:31 DEBUG : Added delayed dir = "a1/b1/c1/d1", newDst= 2026/06/27 05:22:31 INFO : a1/b1/c1/d2: Making directory 2026/06/27 05:22:31 INFO : a1/b2/c1/d1/e1: Made directory with modification time 2001-02-03 04:06:06.499999999 +0000 UTC 2026/06/27 05:22:31 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1", newDst= 2026/06/27 05:22:31 INFO : a1/b2/c1/d1/e1/f1: Making directory 2026/06/27 05:22:31 INFO : a1/b1/c1/d2: Made directory with modification time 2001-02-03 04:13:06.499999999 +0000 UTC 2026/06/27 05:22:31 DEBUG : Added delayed dir = "a1/b1/c1/d2", newDst= 2026/06/27 05:22:31 INFO : a1/b1/c1/d2/e1: Making directory 2026/06/27 05:22:31 INFO : a1/b1/c1/d1/e1: Making directory 2026/06/27 05:22:31 INFO : a1/b1/c1/d2/e1: Made directory with modification time 2001-02-03 04:12:06.499999999 +0000 UTC 2026/06/27 05:22:31 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1", newDst= 2026/06/27 05:22:31 INFO : a1/b1/c1/d2/e1/f1: Making directory 2026/06/27 05:22:31 INFO : a1/b1/c1/d1/e1: Made directory with modification time 2001-02-03 04:15:06.499999999 +0000 UTC 2026/06/27 05:22:31 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1", newDst= 2026/06/27 05:22:31 INFO : a1/b1/c1/d1/e1/f1: Making directory 2026/06/27 05:22:31 INFO : a1/b2/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2026/06/27 05:22:31 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1/f1", newDst= 2026/06/27 05:22:32 INFO : a1/b1/c1/d2/e1/f1: Made directory with modification time 2001-02-03 04:11:06.499999999 +0000 UTC 2026/06/27 05:22:32 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f1", newDst= 2026/06/27 05:22:32 INFO : a1/b1/c1/d2/e1/f2: Making directory 2026/06/27 05:22:32 INFO : a1/b1/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:14:06.499999999 +0000 UTC 2026/06/27 05:22:32 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1/f1", newDst= 2026/06/27 05:22:32 INFO : a1/b1/c1/d2/e1/f2: Made directory with modification time 2001-02-03 04:10:06.499999999 +0000 UTC 2026/06/27 05:22:32 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f2", newDst= 2026/06/27 05:22:32 DEBUG : files root 'rclone-test-revobog8bine': Waiting for checks to finish 2026/06/27 05:22:32 DEBUG : files root 'rclone-test-revobog8bine': Waiting for transfers to finish 2026/06/27 05:22:32 DEBUG : Waiting for deletions to finish 2026/06/27 05:22:32 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:32 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:32 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:32 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:32 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:32 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:32 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:32 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:32 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:32 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:33 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:33 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:33 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:33 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:33 INFO : a1: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:37 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/a1/b2/c1/d1/e1 not empty`) 2026/06/27 05:22:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/06/27 05:22:37 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/a1/b2/c1/d1/e1 not empty`) 2026/06/27 05:22:37 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/06/27 05:22:37 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/a1/b2/c1/d1/e1 not empty`) 2026/06/27 05:22:37 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/06/27 05:22:37 DEBUG : pacer: Reducing sleep to 60ms 2026/06/27 05:22:38 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/a1/b2/c1/d1 not empty`) 2026/06/27 05:22:38 DEBUG : pacer: Rate limited, increasing sleep to 120ms 2026/06/27 05:22:38 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/a1/b2/c1/d1 not empty`) 2026/06/27 05:22:38 DEBUG : pacer: Rate limited, increasing sleep to 240ms 2026/06/27 05:22:38 DEBUG : pacer: Reducing sleep to 180ms 2026/06/27 05:22:38 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/a1/b2/c1 not empty`) 2026/06/27 05:22:38 DEBUG : pacer: Rate limited, increasing sleep to 360ms 2026/06/27 05:22:38 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/a1/b2/c1 not empty`) 2026/06/27 05:22:38 DEBUG : pacer: Rate limited, increasing sleep to 720ms 2026/06/27 05:22:39 DEBUG : pacer: Reducing sleep to 540ms 2026/06/27 05:22:39 DEBUG : pacer: Reducing sleep to 405ms 2026/06/27 05:22:40 DEBUG : pacer: Reducing sleep to 303.75ms 2026/06/27 05:22:40 DEBUG : pacer: Reducing sleep to 227.8125ms 2026/06/27 05:22:41 DEBUG : pacer: Reducing sleep to 170.859375ms 2026/06/27 05:22:41 DEBUG : pacer: Reducing sleep to 128.144531ms 2026/06/27 05:22:41 DEBUG : pacer: Reducing sleep to 96.108398ms 2026/06/27 05:22:41 DEBUG : pacer: Reducing sleep to 72.081298ms 2026/06/27 05:22:41 DEBUG : pacer: Reducing sleep to 54.060973ms 2026/06/27 05:22:41 DEBUG : pacer: Reducing sleep to 40.545729ms 2026/06/27 05:22:42 DEBUG : pacer: Reducing sleep to 30.409296ms 2026/06/27 05:22:42 DEBUG : pacer: Reducing sleep to 22.806972ms 2026/06/27 05:22:42 DEBUG : pacer: Reducing sleep to 17.105229ms --- PASS: TestSyncSetDelayedModTimes (13.13s) === RUN TestSyncNoEmptyDirectories run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:22:42 INFO : sub dir2: Making directory 2026/06/27 05:22:42 DEBUG : pacer: Reducing sleep to 12.828921ms 2026/06/27 05:22:42 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/06/27 05:22:42 DEBUG : Added delayed dir = "sub dir", newDst= 2026/06/27 05:22:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/06/27 05:22:42 DEBUG : files root 'rclone-test-revobog8bine': Waiting for checks to finish 2026/06/27 05:22:42 DEBUG : files root 'rclone-test-revobog8bine': Waiting for transfers to finish 2026/06/27 05:22:43 DEBUG : pacer: Reducing sleep to 10ms 2026/06/27 05:22:43 DEBUG : sub dir/hello world: size = 11 OK 2026/06/27 05:22:43 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/06/27 05:22:43 INFO : sub dir/hello world: Copied (new) 2026/06/27 05:22:43 DEBUG : Waiting for deletions to finish 2026/06/27 05:22:43 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncNoEmptyDirectories (2.32s) === RUN TestServerSideCopy run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:22:45 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-borereb9yovi" sync_test.go:620: Server side copy (if possible) files root 'rclone-test-revobog8bine' -> files root 'rclone-test-borereb9yovi' 2026/06/27 05:22:46 DEBUG : Added delayed dir = "sub dir", newDst= 2026/06/27 05:22:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/06/27 05:22:46 DEBUG : files root 'rclone-test-borereb9yovi': Waiting for checks to finish 2026/06/27 05:22:46 DEBUG : files root 'rclone-test-borereb9yovi': Waiting for transfers to finish 2026/06/27 05:22:47 DEBUG : sub dir/hello world: size = 11 OK 2026/06/27 05:22:47 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/06/27 05:22:47 INFO : sub dir/hello world: Copied (server-side copy) 2026/06/27 05:22:47 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/06/27 05:22:47 DEBUG : files root 'rclone-test-borereb9yovi': Purge remote --- PASS: TestServerSideCopy (4.14s) === RUN TestSyncCopyDest run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:22:48 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-revobog8bine/dst" 2026/06/27 05:22:49 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-revobog8bine/CopyDest" 2026/06/27 05:22:51 DEBUG : one: Need to transfer - File not found at Destination 2026/06/27 05:22:51 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for checks to finish 2026/06/27 05:22:51 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for transfers to finish 2026/06/27 05:22:52 DEBUG : one: size = 3 OK 2026/06/27 05:22:52 DEBUG : one: Dst hash empty - aborting Src hash check 2026/06/27 05:22:52 INFO : one: Copied (new) 2026/06/27 05:22:52 DEBUG : Waiting for deletions to finish 2026/06/27 05:22:53 DEBUG : one: size = 5 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:22:53 DEBUG : one: size = 3 (files root 'rclone-test-revobog8bine/dst') 2026/06/27 05:22:53 DEBUG : one: Sizes differ 2026/06/27 05:22:53 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for checks to finish 2026/06/27 05:22:53 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for transfers to finish 2026/06/27 05:22:54 DEBUG : one: size = 5 OK 2026/06/27 05:22:54 DEBUG : one: Dst hash empty - aborting Src hash check 2026/06/27 05:22:54 INFO : one: Copied (replaced existing) 2026/06/27 05:22:54 DEBUG : Waiting for deletions to finish 2026/06/27 05:22:57 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-revobog8bine/BackupDir" 2026/06/27 05:22:58 DEBUG : one: size = 5 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:22:58 DEBUG : one: size = 3 (files root 'rclone-test-revobog8bine/dst') 2026/06/27 05:22:58 DEBUG : one: Sizes differ 2026/06/27 05:22:58 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for checks to finish 2026/06/27 05:22:58 DEBUG : one: size = 5 OK 2026/06/27 05:22:58 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/06/27 05:22:58 DEBUG : one: size = 5 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:22:58 DEBUG : one: size = 3 (files root 'rclone-test-revobog8bine/dst') 2026/06/27 05:22:58 DEBUG : one: Sizes differ 2026/06/27 05:22:59 INFO : one: Moved (server-side) 2026/06/27 05:23:01 DEBUG : one: size = 5 OK 2026/06/27 05:23:01 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2026/06/27 05:23:01 INFO : one: Copied (server-side copy) 2026/06/27 05:23:01 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/06/27 05:23:01 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for transfers to finish 2026/06/27 05:23:01 DEBUG : Waiting for deletions to finish 2026/06/27 05:23:02 DEBUG : one: size = 5 OK 2026/06/27 05:23:02 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/06/27 05:23:02 DEBUG : one: Unchanged skipping 2026/06/27 05:23:03 DEBUG : two: size = 3 OK 2026/06/27 05:23:03 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/06/27 05:23:04 DEBUG : two: size = 3 OK 2026/06/27 05:23:04 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2026/06/27 05:23:04 INFO : two: Copied (server-side copy) 2026/06/27 05:23:04 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/06/27 05:23:04 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for checks to finish 2026/06/27 05:23:04 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for transfers to finish 2026/06/27 05:23:04 DEBUG : Waiting for deletions to finish 2026/06/27 05:23:04 DEBUG : one: size = 5 OK 2026/06/27 05:23:04 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for checks to finish 2026/06/27 05:23:04 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/06/27 05:23:04 DEBUG : one: Unchanged skipping 2026/06/27 05:23:04 DEBUG : two: size = 3 OK 2026/06/27 05:23:04 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/06/27 05:23:04 DEBUG : two: Unchanged skipping 2026/06/27 05:23:04 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for transfers to finish 2026/06/27 05:23:04 DEBUG : Waiting for deletions to finish 2026/06/27 05:23:04 INFO : There was nothing to transfer 2026/06/27 05:23:06 DEBUG : one: size = 5 OK 2026/06/27 05:23:06 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/06/27 05:23:06 DEBUG : one: Unchanged skipping 2026/06/27 05:23:06 DEBUG : three: size = 7 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:23:06 DEBUG : three: size = 5 (files root 'rclone-test-revobog8bine/CopyDest') 2026/06/27 05:23:06 DEBUG : three: Sizes differ 2026/06/27 05:23:06 DEBUG : three: Destination not found in --copy-dest 2026/06/27 05:23:06 DEBUG : three: Need to transfer - File not found at Destination 2026/06/27 05:23:06 DEBUG : two: size = 3 OK 2026/06/27 05:23:06 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2026/06/27 05:23:06 DEBUG : two: Unchanged skipping 2026/06/27 05:23:06 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for checks to finish 2026/06/27 05:23:06 DEBUG : files root 'rclone-test-revobog8bine/dst': Waiting for transfers to finish 2026/06/27 05:23:07 DEBUG : three: size = 7 OK 2026/06/27 05:23:07 DEBUG : three: Dst hash empty - aborting Src hash check 2026/06/27 05:23:07 INFO : three: Copied (new) 2026/06/27 05:23:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (20.58s) === RUN TestFixCase run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:23:13 DEBUG : EXISTING: size = 6 OK 2026/06/27 05:23:13 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:13 DEBUG : existing: Unchanged skipping 2026/06/27 05:23:13 DEBUG : existingbutdifferent: size = 5 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:23:13 DEBUG : EXISTINGBUTDIFFERENT: size = 8 (files root 'rclone-test-revobog8bine') 2026/06/27 05:23:13 DEBUG : existingbutdifferent: Sizes differ 2026/06/27 05:23:13 INFO : SUBDIRA: Set directory modification time (using DirSetModTime) 2026/06/27 05:23:13 DEBUG : Added delayed dir = "SUBDIRA", newDst=SUBDIRA 2026/06/27 05:23:13 DEBUG : EXISTING: moving to existing-rclone-move-lasujeq6 2026/06/27 05:23:13 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-yowiwim8 2026/06/27 05:23:14 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-lasujeq6 2026/06/27 05:23:14 DEBUG : EXISTING: moving to existing 2026/06/27 05:23:14 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-yowiwim8 2026/06/27 05:23:14 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2026/06/27 05:23:14 INFO : existing-rclone-move-lasujeq6: Moved (server-side) to: existing 2026/06/27 05:23:14 INFO : EXISTING: Moved (server-side) to: existing 2026/06/27 05:23:14 INFO : EXISTING: Fixed case by renaming to: existing 2026/06/27 05:23:14 INFO : existingbutdifferent-rclone-move-yowiwim8: Moved (server-side) to: existingbutdifferent 2026/06/27 05:23:14 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2026/06/27 05:23:14 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2026/06/27 05:23:15 INFO : SUBDIRA: Fixed case by renaming to: subdira 2026/06/27 05:23:15 INFO : SUBDIRA/subdirb: Set directory modification time (using DirSetModTime) 2026/06/27 05:23:15 DEBUG : Added delayed dir = "SUBDIRA/subdirb", newDst=SUBDIRA/subdirb 2026/06/27 05:23:15 DEBUG : existingbutdifferent: size = 5 OK 2026/06/27 05:23:15 DEBUG : existingbutdifferent: Dst hash empty - aborting Src hash check 2026/06/27 05:23:15 INFO : existingbutdifferent: Copied (replaced existing) 2026/06/27 05:23:16 INFO : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb 2026/06/27 05:23:16 INFO : SUBDIRA/subdirb/SUBDIRC: Set directory modification time (using DirSetModTime) 2026/06/27 05:23:16 DEBUG : Added delayed dir = "SUBDIRA/subdirb/SUBDIRC", newDst=SUBDIRA/subdirb/SUBDIRC 2026/06/27 05:23:18 INFO : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc 2026/06/27 05:23:18 DEBUG : subdira/subdirb/subdirc/hello: size = 5 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:23:18 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: size = 8 (files root 'rclone-test-revobog8bine') 2026/06/27 05:23:18 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ 2026/06/27 05:23:18 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Set directory modification time (using DirSetModTime) 2026/06/27 05:23:18 DEBUG : Added delayed dir = "SUBDIRA/subdirb/SUBDIRC/subdird", newDst=SUBDIRA/subdirb/SUBDIRC/subdird 2026/06/27 05:23:18 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-baheron7 2026/06/27 05:23:19 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-baheron7 2026/06/27 05:23:19 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello 2026/06/27 05:23:19 INFO : subdira/subdirb/subdirc/hello-rclone-move-baheron7: Moved (server-side) to: subdira/subdirb/subdirc/hello 2026/06/27 05:23:19 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello 2026/06/27 05:23:19 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello 2026/06/27 05:23:19 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird 2026/06/27 05:23:19 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: size = 5 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:23:19 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: size = 8 (files root 'rclone-test-revobog8bine') 2026/06/27 05:23:19 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ 2026/06/27 05:23:19 DEBUG : files root 'rclone-test-revobog8bine': Waiting for checks to finish 2026/06/27 05:23:20 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-yujaham8 2026/06/27 05:23:20 DEBUG : subdira/subdirb/subdirc/hello: size = 5 OK 2026/06/27 05:23:20 DEBUG : subdira/subdirb/subdirc/hello: Dst hash empty - aborting Src hash check 2026/06/27 05:23:20 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) 2026/06/27 05:23:20 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-yujaham8 2026/06/27 05:23:20 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2026/06/27 05:23:21 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-yujaham8: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2026/06/27 05:23:21 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2026/06/27 05:23:21 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2026/06/27 05:23:21 DEBUG : files root 'rclone-test-revobog8bine': Waiting for transfers to finish 2026/06/27 05:23:21 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: size = 5 OK 2026/06/27 05:23:21 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Dst hash empty - aborting Src hash check 2026/06/27 05:23:21 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing) 2026/06/27 05:23:21 DEBUG : Waiting for deletions to finish 2026/06/27 05:23:24 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/subdira/subdirb not empty`) 2026/06/27 05:23:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/06/27 05:23:24 DEBUG : pacer: Reducing sleep to 15ms 2026/06/27 05:23:24 DEBUG : pacer: Reducing sleep to 11.25ms 2026/06/27 05:23:24 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestFixCase (15.33s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" sync_test.go:2705: This test only runs on local === RUN TestMaxTransfer/Soft run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" sync_test.go:2705: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" sync_test.go:2705: This test only runs on local --- PASS: TestMaxTransfer (0.77s) --- SKIP: TestMaxTransfer/Hard (0.26s) --- SKIP: TestMaxTransfer/Soft (0.26s) --- SKIP: TestMaxTransfer/Cautious (0.25s) === RUN TestSyncConcurrentDelete run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:23:56 DEBUG : both0: size = 6 OK 2026/06/27 05:23:56 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both0: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both10: size = 6 OK 2026/06/27 05:23:56 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both10: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both11: size = 6 OK 2026/06/27 05:23:56 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both11: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both12: size = 6 OK 2026/06/27 05:23:56 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both12: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both13: size = 6 OK 2026/06/27 05:23:56 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both13: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both14: size = 6 OK 2026/06/27 05:23:56 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both14: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both15: size = 6 OK 2026/06/27 05:23:56 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both15: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both16: size = 6 OK 2026/06/27 05:23:56 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both16: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both17: size = 6 OK 2026/06/27 05:23:56 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both17: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both18: size = 6 OK 2026/06/27 05:23:56 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both18: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both19: size = 6 OK 2026/06/27 05:23:56 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both19: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both1: size = 6 OK 2026/06/27 05:23:56 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both1: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both2: size = 6 OK 2026/06/27 05:23:56 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both2: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both3: size = 6 OK 2026/06/27 05:23:56 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both3: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both4: size = 6 OK 2026/06/27 05:23:56 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both4: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both5: size = 6 OK 2026/06/27 05:23:56 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both5: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both6: size = 6 OK 2026/06/27 05:23:56 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both6: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both8: size = 6 OK 2026/06/27 05:23:56 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both8: Unchanged skipping 2026/06/27 05:23:56 DEBUG : both9: size = 6 OK 2026/06/27 05:23:56 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both9: Unchanged skipping 2026/06/27 05:23:56 DEBUG : files root 'rclone-test-revobog8bine': Waiting for checks to finish 2026/06/27 05:23:56 DEBUG : both7: size = 6 OK 2026/06/27 05:23:56 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:23:56 DEBUG : both7: Unchanged skipping 2026/06/27 05:23:56 DEBUG : files root 'rclone-test-revobog8bine': Waiting for transfers to finish 2026/06/27 05:23:56 DEBUG : Waiting for deletions to finish 2026/06/27 05:23:56 INFO : only10: Deleted 2026/06/27 05:23:56 INFO : only5: Deleted 2026/06/27 05:23:56 INFO : only16: Deleted 2026/06/27 05:23:56 INFO : only18: Deleted 2026/06/27 05:23:56 INFO : only1: Deleted 2026/06/27 05:23:56 INFO : only3: Deleted 2026/06/27 05:23:56 INFO : only17: Deleted 2026/06/27 05:23:56 INFO : only6: Deleted 2026/06/27 05:23:56 INFO : only7: Deleted 2026/06/27 05:23:56 INFO : only12: Deleted 2026/06/27 05:23:56 INFO : only13: Deleted 2026/06/27 05:23:56 INFO : only11: Deleted 2026/06/27 05:23:57 INFO : only4: Deleted 2026/06/27 05:23:57 INFO : only14: Deleted 2026/06/27 05:23:57 INFO : only9: Deleted 2026/06/27 05:23:57 INFO : only0: Deleted 2026/06/27 05:23:57 INFO : only15: Deleted 2026/06/27 05:23:57 INFO : only2: Deleted 2026/06/27 05:23:57 INFO : only19: Deleted 2026/06/27 05:23:57 INFO : only8: Deleted --- PASS: TestSyncConcurrentDelete (35.38s) === RUN TestSyncConcurrentTruncate run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:24:32 DEBUG : both0: size = 6 OK 2026/06/27 05:24:32 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both0: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both10: size = 6 OK 2026/06/27 05:24:32 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both10: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both11: size = 6 OK 2026/06/27 05:24:32 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both11: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both12: size = 6 OK 2026/06/27 05:24:32 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both12: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both13: size = 6 OK 2026/06/27 05:24:32 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both13: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both14: size = 6 OK 2026/06/27 05:24:32 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both14: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both15: size = 6 OK 2026/06/27 05:24:32 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both15: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both16: size = 6 OK 2026/06/27 05:24:32 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both16: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both17: size = 6 OK 2026/06/27 05:24:32 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both18: size = 6 OK 2026/06/27 05:24:32 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both1: size = 6 OK 2026/06/27 05:24:32 DEBUG : both17: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both2: size = 6 OK 2026/06/27 05:24:32 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both19: size = 6 OK 2026/06/27 05:24:32 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : files root 'rclone-test-revobog8bine': Waiting for checks to finish 2026/06/27 05:24:32 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both2: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both3: size = 6 OK 2026/06/27 05:24:32 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both1: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both4: size = 6 OK 2026/06/27 05:24:32 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both4: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both5: size = 6 OK 2026/06/27 05:24:32 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both5: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both6: size = 6 OK 2026/06/27 05:24:32 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both6: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both7: size = 6 OK 2026/06/27 05:24:32 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both19: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both8: size = 6 OK 2026/06/27 05:24:32 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both3: Unchanged skipping 2026/06/27 05:24:32 DEBUG : both9: size = 6 OK 2026/06/27 05:24:32 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/27 05:24:32 DEBUG : both9: Unchanged skipping 2026/06/27 05:24:32 DEBUG : only0: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only0: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only0: Sizes differ 2026/06/27 05:24:32 DEBUG : both18: Unchanged skipping 2026/06/27 05:24:32 DEBUG : only10: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only10: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only10: Sizes differ 2026/06/27 05:24:32 DEBUG : only11: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only11: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only11: Sizes differ 2026/06/27 05:24:32 DEBUG : only12: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only12: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only12: Sizes differ 2026/06/27 05:24:32 DEBUG : only13: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only13: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only13: Sizes differ 2026/06/27 05:24:32 DEBUG : only14: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only14: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only14: Sizes differ 2026/06/27 05:24:32 DEBUG : only15: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only15: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only15: Sizes differ 2026/06/27 05:24:32 DEBUG : only16: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only16: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only16: Sizes differ 2026/06/27 05:24:32 DEBUG : only17: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only17: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only17: Sizes differ 2026/06/27 05:24:32 DEBUG : only18: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only18: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only18: Sizes differ 2026/06/27 05:24:32 DEBUG : only19: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only19: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only19: Sizes differ 2026/06/27 05:24:32 DEBUG : only1: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only1: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only1: Sizes differ 2026/06/27 05:24:32 DEBUG : only2: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only2: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only2: Sizes differ 2026/06/27 05:24:32 DEBUG : only3: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only3: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only3: Sizes differ 2026/06/27 05:24:32 DEBUG : only4: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only4: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only4: Sizes differ 2026/06/27 05:24:32 DEBUG : only5: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only5: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only5: Sizes differ 2026/06/27 05:24:32 DEBUG : only6: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only6: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only6: Sizes differ 2026/06/27 05:24:32 DEBUG : both7: Unchanged skipping 2026/06/27 05:24:32 DEBUG : only7: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only7: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only7: Sizes differ 2026/06/27 05:24:32 DEBUG : both8: Unchanged skipping 2026/06/27 05:24:32 DEBUG : only8: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only8: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only8: Sizes differ 2026/06/27 05:24:32 DEBUG : only9: size = 0 (Local file system at /tmp/rclone3871995422) 2026/06/27 05:24:32 DEBUG : only9: size = 6 (files root 'rclone-test-revobog8bine') 2026/06/27 05:24:32 DEBUG : only9: Sizes differ 2026/06/27 05:24:32 DEBUG : files root 'rclone-test-revobog8bine': Waiting for transfers to finish 2026/06/27 05:24:33 DEBUG : only10: size = 0 OK 2026/06/27 05:24:33 DEBUG : only10: Dst hash empty - aborting Src hash check 2026/06/27 05:24:33 INFO : only10: Copied (replaced existing) 2026/06/27 05:24:33 DEBUG : only12: size = 0 OK 2026/06/27 05:24:33 DEBUG : only12: Dst hash empty - aborting Src hash check 2026/06/27 05:24:33 INFO : only12: Copied (replaced existing) 2026/06/27 05:24:33 DEBUG : only11: size = 0 OK 2026/06/27 05:24:33 DEBUG : only11: Dst hash empty - aborting Src hash check 2026/06/27 05:24:33 INFO : only11: Copied (replaced existing) 2026/06/27 05:24:33 DEBUG : only13: size = 0 OK 2026/06/27 05:24:33 DEBUG : only13: Dst hash empty - aborting Src hash check 2026/06/27 05:24:33 INFO : only13: Copied (replaced existing) 2026/06/27 05:24:34 DEBUG : only14: size = 0 OK 2026/06/27 05:24:34 DEBUG : only14: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/06/27 05:24:34 INFO : only14: Copied (replaced existing) 2026/06/27 05:24:34 DEBUG : only15: size = 0 OK 2026/06/27 05:24:34 DEBUG : only15: Dst hash empty - aborting Src hash check 2026/06/27 05:24:34 INFO : only15: Copied (replaced existing) 2026/06/27 05:24:34 DEBUG : only17: size = 0 OK 2026/06/27 05:24:34 DEBUG : only17: Dst hash empty - aborting Src hash check 2026/06/27 05:24:34 INFO : only17: Copied (replaced existing) 2026/06/27 05:24:34 DEBUG : only16: size = 0 OK 2026/06/27 05:24:34 DEBUG : only16: Dst hash empty - aborting Src hash check 2026/06/27 05:24:34 INFO : only16: Copied (replaced existing) 2026/06/27 05:24:35 DEBUG : only19: size = 0 OK 2026/06/27 05:24:35 DEBUG : only19: Dst hash empty - aborting Src hash check 2026/06/27 05:24:35 INFO : only19: Copied (replaced existing) 2026/06/27 05:24:35 DEBUG : only18: size = 0 OK 2026/06/27 05:24:35 DEBUG : only18: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/06/27 05:24:35 INFO : only18: Copied (replaced existing) 2026/06/27 05:24:35 DEBUG : only1: size = 0 OK 2026/06/27 05:24:35 DEBUG : only1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/06/27 05:24:35 INFO : only1: Copied (replaced existing) 2026/06/27 05:24:35 DEBUG : only2: size = 0 OK 2026/06/27 05:24:35 DEBUG : only2: Dst hash empty - aborting Src hash check 2026/06/27 05:24:35 INFO : only2: Copied (replaced existing) 2026/06/27 05:24:35 DEBUG : only3: size = 0 OK 2026/06/27 05:24:35 DEBUG : only3: Dst hash empty - aborting Src hash check 2026/06/27 05:24:35 INFO : only3: Copied (replaced existing) 2026/06/27 05:24:36 DEBUG : only4: size = 0 OK 2026/06/27 05:24:36 DEBUG : only4: Dst hash empty - aborting Src hash check 2026/06/27 05:24:36 INFO : only4: Copied (replaced existing) 2026/06/27 05:24:36 DEBUG : only5: size = 0 OK 2026/06/27 05:24:36 DEBUG : only5: Dst hash empty - aborting Src hash check 2026/06/27 05:24:36 INFO : only5: Copied (replaced existing) 2026/06/27 05:24:36 DEBUG : only0: size = 0 OK 2026/06/27 05:24:36 DEBUG : only0: Dst hash empty - aborting Src hash check 2026/06/27 05:24:36 INFO : only0: Copied (replaced existing) 2026/06/27 05:24:36 DEBUG : only9: size = 0 OK 2026/06/27 05:24:36 DEBUG : only9: Dst hash empty - aborting Src hash check 2026/06/27 05:24:36 INFO : only9: Copied (replaced existing) 2026/06/27 05:24:36 DEBUG : only7: size = 0 OK 2026/06/27 05:24:36 DEBUG : only7: Dst hash empty - aborting Src hash check 2026/06/27 05:24:36 INFO : only7: Copied (replaced existing) 2026/06/27 05:24:36 DEBUG : only6: size = 0 OK 2026/06/27 05:24:36 DEBUG : only6: Dst hash empty - aborting Src hash check 2026/06/27 05:24:36 INFO : only6: Copied (replaced existing) 2026/06/27 05:24:36 DEBUG : only8: size = 0 OK 2026/06/27 05:24:36 DEBUG : only8: Dst hash empty - aborting Src hash check 2026/06/27 05:24:36 INFO : only8: Copied (replaced existing) 2026/06/27 05:24:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (42.85s) === RUN TestSyncReplaceDirModTime run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:24:43 DEBUG : empty_dir: Making directory with metadata 2026/06/27 05:24:43 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/27 05:24:43 DEBUG : empty_on_remote: Making directory with metadata 2026/06/27 05:24:43 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/27 05:24:43 INFO : empty_on_remote: Making directory 2026/06/27 05:24:44 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/06/27 05:24:45 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2026/06/27 05:24:45 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2026/06/27 05:24:45 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2026/06/27 05:24:45 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2026/06/27 05:24:48 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/test_dir2 not empty`) 2026/06/27 05:24:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/06/27 05:24:48 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/test_dir2 not empty`) 2026/06/27 05:24:48 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/06/27 05:24:48 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/test_dir2 not empty`) 2026/06/27 05:24:48 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/06/27 05:24:49 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/test_dir2 not empty`) 2026/06/27 05:24:49 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/06/27 05:24:49 DEBUG : pacer: Reducing sleep to 120ms 2026/06/27 05:24:49 DEBUG : pacer: Reducing sleep to 90ms 2026/06/27 05:24:49 DEBUG : pacer: Reducing sleep to 67.5ms 2026/06/27 05:24:49 DEBUG : pacer: Reducing sleep to 50.625ms --- PASS: TestSyncReplaceDirModTime (5.86s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:24:49 DEBUG : empty_dir: Making directory with metadata 2026/06/27 05:24:49 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/27 05:24:49 DEBUG : empty_on_remote: Making directory with metadata 2026/06/27 05:24:49 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/27 05:24:49 INFO : empty_on_remote: Making directory 2026/06/27 05:24:49 DEBUG : pacer: Reducing sleep to 37.96875ms 2026/06/27 05:24:49 DEBUG : pacer: Reducing sleep to 28.476562ms 2026/06/27 05:24:49 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC sync_test.go:2814: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2814 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2868 Error: Received unexpected error: Not Found - `Not Found. This may be related to your permissions.` Test: TestSyncReplaceDirModTimeWithEmptyDirs 2026/06/27 05:24:49 DEBUG : pacer: Reducing sleep to 21.357421ms 2026/06/27 05:24:50 DEBUG : pacer: Reducing sleep to 16.018065ms --- FAIL: TestSyncReplaceDirModTimeWithEmptyDirs (0.52s) === RUN TestNothingToTransferWithEmptyDirs run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:24:50 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/06/27 05:24:50 INFO : sub dir: Making directory 2026/06/27 05:24:50 DEBUG : pacer: Reducing sleep to 12.013548ms 2026/06/27 05:24:50 DEBUG : pacer: Reducing sleep to 10ms 2026/06/27 05:24:50 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC 2026/06/27 05:25:00 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/06/27 05:25:00 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/06/27 05:25:00 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/27 05:25:00 INFO : sub dirEmpty: Set directory modification time (using DirSetModTime) 2026/06/27 05:25:06 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dirEmpty not empty`) 2026/06/27 05:25:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/06/27 05:25:06 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dirEmpty not empty`) 2026/06/27 05:25:06 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/06/27 05:25:06 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dirEmpty not empty`) 2026/06/27 05:25:06 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/06/27 05:25:06 DEBUG : pacer: Reducing sleep to 60ms 2026/06/27 05:25:07 DEBUG : pacer: Reducing sleep to 45ms 2026/06/27 05:25:07 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very/very/very/very/very/nested not empty`) 2026/06/27 05:25:07 DEBUG : pacer: Rate limited, increasing sleep to 90ms 2026/06/27 05:25:07 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very/very/very/very/very/nested not empty`) 2026/06/27 05:25:07 DEBUG : pacer: Rate limited, increasing sleep to 180ms 2026/06/27 05:25:07 DEBUG : pacer: Reducing sleep to 135ms 2026/06/27 05:25:07 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very/very/very/very/very not empty`) 2026/06/27 05:25:07 DEBUG : pacer: Rate limited, increasing sleep to 270ms 2026/06/27 05:25:07 DEBUG : pacer: Reducing sleep to 202.5ms 2026/06/27 05:25:08 DEBUG : pacer: Reducing sleep to 151.875ms 2026/06/27 05:25:08 DEBUG : pacer: Reducing sleep to 113.90625ms 2026/06/27 05:25:08 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very/very not empty`) 2026/06/27 05:25:08 DEBUG : pacer: Rate limited, increasing sleep to 227.8125ms 2026/06/27 05:25:08 DEBUG : pacer: Reducing sleep to 170.859375ms 2026/06/27 05:25:08 DEBUG : pacer: Reducing sleep to 128.144531ms 2026/06/27 05:25:08 DEBUG : pacer: Reducing sleep to 96.108398ms 2026/06/27 05:25:09 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2 not empty`) 2026/06/27 05:25:09 DEBUG : pacer: Rate limited, increasing sleep to 192.216796ms 2026/06/27 05:25:09 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2 not empty`) 2026/06/27 05:25:09 DEBUG : pacer: Rate limited, increasing sleep to 384.433592ms 2026/06/27 05:25:09 DEBUG : pacer: Reducing sleep to 288.325194ms 2026/06/27 05:25:09 DEBUG : pacer: Reducing sleep to 216.243895ms 2026/06/27 05:25:09 DEBUG : pacer: Reducing sleep to 162.182921ms --- PASS: TestNothingToTransferWithEmptyDirs (19.96s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:198: Remote "files root 'rclone-test-revobog8bine'", Local "Local file system at /tmp/rclone3871995422", Modify Window "1s" 2026/06/27 05:25:10 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/06/27 05:25:10 INFO : sub dir: Making directory 2026/06/27 05:25:10 DEBUG : pacer: Reducing sleep to 121.63719ms 2026/06/27 05:25:10 DEBUG : pacer: Reducing sleep to 91.227892ms 2026/06/27 05:25:10 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC 2026/06/27 05:25:18 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/06/27 05:25:18 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/06/27 05:25:18 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/27 05:25:18 INFO : sub dirEmpty: Set directory modification time (using DirSetModTime) 2026/06/27 05:25:24 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very/very/very/very/very/nested not empty`) 2026/06/27 05:25:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/06/27 05:25:24 DEBUG : pacer: Reducing sleep to 15ms 2026/06/27 05:25:24 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very/very/very/very/very not empty`) 2026/06/27 05:25:24 DEBUG : pacer: Rate limited, increasing sleep to 30ms 2026/06/27 05:25:24 DEBUG : pacer: Reducing sleep to 22.5ms 2026/06/27 05:25:24 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very/very/very/very not empty`) 2026/06/27 05:25:24 DEBUG : pacer: Rate limited, increasing sleep to 45ms 2026/06/27 05:25:25 DEBUG : pacer: Reducing sleep to 33.75ms 2026/06/27 05:25:25 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very/very/very not empty`) 2026/06/27 05:25:25 DEBUG : pacer: Rate limited, increasing sleep to 67.5ms 2026/06/27 05:25:25 DEBUG : pacer: Reducing sleep to 50.625ms 2026/06/27 05:25:25 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very/very not empty`) 2026/06/27 05:25:25 DEBUG : pacer: Rate limited, increasing sleep to 101.25ms 2026/06/27 05:25:25 DEBUG : pacer: Reducing sleep to 75.9375ms 2026/06/27 05:25:25 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very not empty`) 2026/06/27 05:25:25 DEBUG : pacer: Rate limited, increasing sleep to 151.875ms 2026/06/27 05:25:25 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very not empty`) 2026/06/27 05:25:25 DEBUG : pacer: Rate limited, increasing sleep to 303.75ms 2026/06/27 05:25:26 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-revobog8bine/sub dir2/very not empty`) 2026/06/27 05:25:26 DEBUG : pacer: Rate limited, increasing sleep to 607.5ms 2026/06/27 05:25:26 DEBUG : pacer: Reducing sleep to 455.625ms 2026/06/27 05:25:27 DEBUG : pacer: Reducing sleep to 341.71875ms 2026/06/27 05:25:27 DEBUG : pacer: Reducing sleep to 256.289062ms 2026/06/27 05:25:27 DEBUG : pacer: Reducing sleep to 192.216796ms 2026/06/27 05:25:27 DEBUG : pacer: Reducing sleep to 144.162597ms --- PASS: TestNothingToTransferWithoutEmptyDirs (17.93s) FAIL 2026/06/27 05:25:28 DEBUG : files root 'rclone-test-revobog8bine': Purge remote 2026/06/27 05:25:28 DEBUG : pacer: Reducing sleep to 108.121947ms "./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestFixCase|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopy|TestSyncConcurrentDelete|TestSyncConcurrentTruncate|TestSyncCopyDest|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncReplaceDirModTime|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSetDelayedModTimes)$|^TestMaxTransfer$/^(Cautious|Hard|Soft)$'" - Finished ERROR in 3m7.668556859s (try 2/5): exit status 1: Failed [TestSyncReplaceDirModTimeWithEmptyDirs]