"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestCopyOverSelf|TestMoveWithIgnoreExisting|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes|TestSyncSuffix)$'" - Starting (try 2/5) 2026/06/04 02:17:54 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-pibukez6fova" 2026/06/04 02:17:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/06/04 02:17:54 DEBUG : Creating backend with remote "/tmp/rclone3252141926" === RUN TestCopyNoEmptyDirectories run.go:198: Remote "files root 'rclone-test-pibukez6fova'", Local "Local file system at /tmp/rclone3252141926", Modify Window "1s" 2026/06/04 02:17:54 INFO : sub dir2: Making directory 2026/06/04 02:17:54 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/06/04 02:17:54 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/04 02:17:55 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/06/04 02:17:55 DEBUG : Added delayed dir = "sub dir", newDst= 2026/06/04 02:17:55 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/06/04 02:17:55 DEBUG : Added delayed dir = "sub dir2/sub sub dir2", newDst= 2026/06/04 02:17:55 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for checks to finish 2026/06/04 02:17:55 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for transfers to finish 2026/06/04 02:17:56 DEBUG : sub dir/hello world: size = 11 OK 2026/06/04 02:17:56 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/06/04 02:17:56 INFO : sub dir/hello world: Copied (new) 2026/06/04 02:17:57 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyNoEmptyDirectories (3.07s) === RUN TestSyncNoUpdateDirModtime run.go:198: Remote "files root 'rclone-test-pibukez6fova'", Local "Local file system at /tmp/rclone3252141926", Modify Window "1s" 2026/06/04 02:17:58 DEBUG : sub dir no update dir modtime: Making directory with metadata 2026/06/04 02:17:58 INFO : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/06/04 02:17:58 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for checks to finish 2026/06/04 02:17:58 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for transfers to finish 2026/06/04 02:17:58 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoUpdateDirModtime (1.47s) === RUN TestSyncEmptyDirectories run.go:198: Remote "files root 'rclone-test-pibukez6fova'", Local "Local file system at /tmp/rclone3252141926", Modify Window "1s" 2026/06/04 02:17:59 DEBUG : sub dir2: Making directory with metadata 2026/06/04 02:17:59 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/06/04 02:17:59 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/06/04 02:17:59 INFO : sub dir2: Making directory 2026/06/04 02:17:59 INFO : sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/06/04 02:17:59 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/06/04 02:17:59 INFO : sub dir: Making directory 2026/06/04 02:18:00 INFO : sub dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2026/06/04 02:18:00 DEBUG : Added delayed dir = "sub dir", newDst= 2026/06/04 02:18:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/06/04 02:18:00 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for checks to finish 2026/06/04 02:18:00 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for transfers to finish 2026/06/04 02:18:00 DEBUG : sub dir/hello world: size = 11 OK 2026/06/04 02:18:00 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/06/04 02:18:00 INFO : sub dir/hello world: Copied (new) 2026/06/04 02:18:00 DEBUG : Waiting for deletions to finish 2026/06/04 02:18:01 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:01 INFO : sub dir2: Set directory modification time (using DirSetModTime) --- PASS: TestSyncEmptyDirectories (3.02s) === RUN TestSyncSetDelayedModTimes run.go:198: Remote "files root 'rclone-test-pibukez6fova'", Local "Local file system at /tmp/rclone3252141926", Modify Window "1s" 2026/06/04 02:18:02 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:02 INFO : a1: Making directory 2026/06/04 02:18:02 INFO : a1: Made directory with modification time 2001-02-03 04:19:06.499999999 +0000 UTC 2026/06/04 02:18:02 DEBUG : Added delayed dir = "a1", newDst= 2026/06/04 02:18:02 INFO : a1/b1: Making directory 2026/06/04 02:18:03 INFO : a1/b1: Made directory with modification time 2001-02-03 04:18:06.499999999 +0000 UTC 2026/06/04 02:18:03 DEBUG : Added delayed dir = "a1/b1", newDst= 2026/06/04 02:18:03 INFO : a1/b2: Making directory 2026/06/04 02:18:03 INFO : a1/b2: Made directory with modification time 2001-02-03 04:09:06.499999999 +0000 UTC 2026/06/04 02:18:03 DEBUG : Added delayed dir = "a1/b2", newDst= 2026/06/04 02:18:03 INFO : a1/b2/c1: Making directory 2026/06/04 02:18:03 INFO : a1/b1/c1: Making directory 2026/06/04 02:18:03 INFO : a1/b2/c1: Made directory with modification time 2001-02-03 04:08:06.499999999 +0000 UTC 2026/06/04 02:18:03 DEBUG : Added delayed dir = "a1/b2/c1", newDst= 2026/06/04 02:18:03 INFO : a1/b2/c1/d1: Making directory 2026/06/04 02:18:03 INFO : a1/b1/c1: Made directory with modification time 2001-02-03 04:17:06.499999999 +0000 UTC 2026/06/04 02:18:03 DEBUG : Added delayed dir = "a1/b1/c1", newDst= 2026/06/04 02:18:03 INFO : a1/b1/c1/d1: Making directory 2026/06/04 02:18:04 INFO : a1/b1/c1/d1: Made directory with modification time 2001-02-03 04:16:06.499999999 +0000 UTC 2026/06/04 02:18:04 DEBUG : Added delayed dir = "a1/b1/c1/d1", newDst= 2026/06/04 02:18:04 INFO : a1/b1/c1/d2: Making directory 2026/06/04 02:18:04 INFO : a1/b2/c1/d1: Made directory with modification time 2001-02-03 04:07:06.499999999 +0000 UTC 2026/06/04 02:18:04 DEBUG : Added delayed dir = "a1/b2/c1/d1", newDst= 2026/06/04 02:18:04 INFO : a1/b2/c1/d1/e1: Making directory 2026/06/04 02:18:04 INFO : a1/b1/c1/d2: Made directory with modification time 2001-02-03 04:13:06.499999999 +0000 UTC 2026/06/04 02:18:04 DEBUG : Added delayed dir = "a1/b1/c1/d2", newDst= 2026/06/04 02:18:04 INFO : a1/b1/c1/d2/e1: Making directory 2026/06/04 02:18:04 INFO : a1/b1/c1/d1/e1: Making directory 2026/06/04 02:18:04 INFO : a1/b2/c1/d1/e1: Made directory with modification time 2001-02-03 04:06:06.499999999 +0000 UTC 2026/06/04 02:18:04 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1", newDst= 2026/06/04 02:18:04 INFO : a1/b2/c1/d1/e1/f1: Making directory 2026/06/04 02:18:04 INFO : a1/b1/c1/d1/e1: Made directory with modification time 2001-02-03 04:15:06.499999999 +0000 UTC 2026/06/04 02:18:04 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1", newDst= 2026/06/04 02:18:04 INFO : a1/b1/c1/d1/e1/f1: Making directory 2026/06/04 02:18:04 INFO : a1/b1/c1/d2/e1: Made directory with modification time 2001-02-03 04:12:06.499999999 +0000 UTC 2026/06/04 02:18:04 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1", newDst= 2026/06/04 02:18:04 INFO : a1/b1/c1/d2/e1/f1: Making directory 2026/06/04 02:18:04 INFO : a1/b2/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2026/06/04 02:18:04 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1/f1", newDst= 2026/06/04 02:18:05 INFO : a1/b1/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:14:06.499999999 +0000 UTC 2026/06/04 02:18:05 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1/f1", newDst= 2026/06/04 02:18:05 INFO : a1/b1/c1/d2/e1/f1: Made directory with modification time 2001-02-03 04:11:06.499999999 +0000 UTC 2026/06/04 02:18:05 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f1", newDst= 2026/06/04 02:18:05 INFO : a1/b1/c1/d2/e1/f2: Making directory 2026/06/04 02:18:05 INFO : a1/b1/c1/d2/e1/f2: Made directory with modification time 2001-02-03 04:10:06.499999999 +0000 UTC 2026/06/04 02:18:05 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f2", newDst= 2026/06/04 02:18:05 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for checks to finish 2026/06/04 02:18:05 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for transfers to finish 2026/06/04 02:18:05 DEBUG : Waiting for deletions to finish 2026/06/04 02:18:05 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:05 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:06 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:06 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:06 INFO : a1: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:10 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b2/c1/d1/e1 not empty`) 2026/06/04 02:18:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/06/04 02:18:10 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b2/c1/d1/e1 not empty`) 2026/06/04 02:18:10 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/06/04 02:18:10 DEBUG : pacer: Reducing sleep to 30ms 2026/06/04 02:18:10 DEBUG : pacer: Reducing sleep to 22.5ms 2026/06/04 02:18:11 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b2/c1 not empty`) 2026/06/04 02:18:11 DEBUG : pacer: Rate limited, increasing sleep to 45ms 2026/06/04 02:18:11 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b2/c1 not empty`) 2026/06/04 02:18:11 DEBUG : pacer: Rate limited, increasing sleep to 90ms 2026/06/04 02:18:11 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b2/c1 not empty`) 2026/06/04 02:18:11 DEBUG : pacer: Rate limited, increasing sleep to 180ms 2026/06/04 02:18:11 DEBUG : pacer: Reducing sleep to 135ms 2026/06/04 02:18:11 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b2 not empty`) 2026/06/04 02:18:11 DEBUG : pacer: Rate limited, increasing sleep to 270ms 2026/06/04 02:18:11 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b2 not empty`) 2026/06/04 02:18:11 DEBUG : pacer: Rate limited, increasing sleep to 540ms 2026/06/04 02:18:11 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b2 not empty`) 2026/06/04 02:18:11 DEBUG : pacer: Rate limited, increasing sleep to 1.08s 2026/06/04 02:18:12 DEBUG : pacer: Reducing sleep to 810ms 2026/06/04 02:18:13 DEBUG : pacer: Reducing sleep to 607.5ms 2026/06/04 02:18:14 DEBUG : pacer: Reducing sleep to 455.625ms 2026/06/04 02:18:15 DEBUG : pacer: Reducing sleep to 341.71875ms 2026/06/04 02:18:15 DEBUG : pacer: Reducing sleep to 256.289062ms 2026/06/04 02:18:15 DEBUG : pacer: Reducing sleep to 192.216796ms 2026/06/04 02:18:16 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b1/c1/d1/e1 not empty`) 2026/06/04 02:18:16 DEBUG : pacer: Rate limited, increasing sleep to 384.433592ms 2026/06/04 02:18:16 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b1/c1/d1/e1 not empty`) 2026/06/04 02:18:16 DEBUG : pacer: Rate limited, increasing sleep to 768.867184ms 2026/06/04 02:18:16 DEBUG : pacer: Reducing sleep to 576.650388ms 2026/06/04 02:18:17 DEBUG : pacer: Reducing sleep to 432.487791ms 2026/06/04 02:18:18 DEBUG : pacer: Reducing sleep to 324.365843ms 2026/06/04 02:18:18 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-pibukez6fova/a1/b1 not empty`) 2026/06/04 02:18:18 DEBUG : pacer: Rate limited, increasing sleep to 648.731686ms 2026/06/04 02:18:18 DEBUG : pacer: Reducing sleep to 486.548764ms 2026/06/04 02:18:19 DEBUG : pacer: Reducing sleep to 364.911573ms 2026/06/04 02:18:19 DEBUG : pacer: Reducing sleep to 273.683679ms --- PASS: TestSyncSetDelayedModTimes (17.40s) === RUN TestSyncNoEmptyDirectories run.go:198: Remote "files root 'rclone-test-pibukez6fova'", Local "Local file system at /tmp/rclone3252141926", Modify Window "1s" 2026/06/04 02:18:19 INFO : sub dir2: Making directory 2026/06/04 02:18:20 DEBUG : pacer: Reducing sleep to 205.262759ms 2026/06/04 02:18:20 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/06/04 02:18:20 DEBUG : Added delayed dir = "sub dir", newDst= 2026/06/04 02:18:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/06/04 02:18:20 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for checks to finish 2026/06/04 02:18:20 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for transfers to finish 2026/06/04 02:18:21 DEBUG : pacer: Reducing sleep to 153.947069ms 2026/06/04 02:18:21 DEBUG : pacer: Reducing sleep to 115.460301ms 2026/06/04 02:18:21 DEBUG : sub dir/hello world: size = 11 OK 2026/06/04 02:18:21 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/06/04 02:18:21 INFO : sub dir/hello world: Copied (new) 2026/06/04 02:18:21 DEBUG : Waiting for deletions to finish 2026/06/04 02:18:21 DEBUG : pacer: Reducing sleep to 86.595225ms 2026/06/04 02:18:21 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/06/04 02:18:21 DEBUG : pacer: Reducing sleep to 64.946418ms 2026/06/04 02:18:21 DEBUG : pacer: Reducing sleep to 48.709813ms 2026/06/04 02:18:21 DEBUG : pacer: Reducing sleep to 36.532359ms 2026/06/04 02:18:22 DEBUG : pacer: Reducing sleep to 27.399269ms 2026/06/04 02:18:22 DEBUG : pacer: Reducing sleep to 20.549451ms 2026/06/04 02:18:22 DEBUG : pacer: Reducing sleep to 15.412088ms 2026/06/04 02:18:22 DEBUG : pacer: Reducing sleep to 11.559066ms --- PASS: TestSyncNoEmptyDirectories (2.74s) === RUN TestCopyOverSelf run.go:198: Remote "files root 'rclone-test-pibukez6fova'", Local "Local file system at /tmp/rclone3252141926", Modify Window "1s" 2026/06/04 02:18:23 DEBUG : pacer: Reducing sleep to 10ms 2026/06/04 02:18:23 DEBUG : sub dir: Directory modification time the same (differ by -734.365542ms, within tolerance 1s) 2026/06/04 02:18:23 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2026/06/04 02:18:24 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3252141926) 2026/06/04 02:18:24 DEBUG : sub dir/hello world: size = 11 (files root 'rclone-test-pibukez6fova') 2026/06/04 02:18:24 DEBUG : sub dir/hello world: Sizes differ 2026/06/04 02:18:24 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for checks to finish 2026/06/04 02:18:24 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for transfers to finish 2026/06/04 02:18:24 DEBUG : sub dir/hello world: size = 17 OK 2026/06/04 02:18:24 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/06/04 02:18:24 INFO : sub dir/hello world: Copied (replaced existing) 2026/06/04 02:18:24 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyOverSelf (3.34s) === RUN TestMoveWithIgnoreExisting run.go:198: Remote "files root 'rclone-test-pibukez6fova'", Local "Local file system at /tmp/rclone3252141926", Modify Window "1s" 2026/06/04 02:18:26 DEBUG : existing-b: Need to transfer - File not found at Destination 2026/06/04 02:18:26 DEBUG : existing: Need to transfer - File not found at Destination 2026/06/04 02:18:26 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for checks to finish 2026/06/04 02:18:26 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for transfers to finish 2026/06/04 02:18:26 DEBUG : existing-b: size = 6 OK 2026/06/04 02:18:26 DEBUG : existing-b: md5 = 006f87892f47ef9aa60fa5ed01a440fb OK 2026/06/04 02:18:26 INFO : existing-b: Copied (new) 2026/06/04 02:18:26 INFO : existing-b: Deleted 2026/06/04 02:18:27 DEBUG : existing: size = 6 OK 2026/06/04 02:18:27 DEBUG : existing: Dst hash empty - aborting Src hash check 2026/06/04 02:18:27 INFO : existing: Copied (new) 2026/06/04 02:18:27 INFO : existing: Deleted 2026/06/04 02:18:27 DEBUG : existing: Destination exists, skipping 2026/06/04 02:18:27 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for checks to finish 2026/06/04 02:18:27 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2026/06/04 02:18:27 DEBUG : files root 'rclone-test-pibukez6fova': Waiting for transfers to finish 2026/06/04 02:18:27 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (2.06s) === RUN TestSyncSuffix run.go:198: Remote "files root 'rclone-test-pibukez6fova'", Local "Local file system at /tmp/rclone3252141926", Modify Window "1s" 2026/06/04 02:18:30 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-pibukez6fova/dst" 2026/06/04 02:18:31 DEBUG : one: size = 4 (Local file system at /tmp/rclone3252141926) 2026/06/04 02:18:31 DEBUG : one: size = 3 (files root 'rclone-test-pibukez6fova/dst') 2026/06/04 02:18:31 DEBUG : one: Sizes differ 2026/06/04 02:18:32 INFO : one: Moved (server-side) to: one.bak 2026/06/04 02:18:33 DEBUG : one: size = 4 OK 2026/06/04 02:18:33 DEBUG : one: Dst hash empty - aborting Src hash check 2026/06/04 02:18:33 INFO : one: Copied (new) 2026/06/04 02:18:33 DEBUG : two: size = 3 OK 2026/06/04 02:18:33 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/04 02:18:33 DEBUG : two: Unchanged skipping 2026/06/04 02:18:33 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone3252141926) 2026/06/04 02:18:33 DEBUG : three.txt: size = 5 (files root 'rclone-test-pibukez6fova/dst') 2026/06/04 02:18:33 DEBUG : three.txt: Sizes differ 2026/06/04 02:18:34 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/06/04 02:18:35 DEBUG : three.txt: size = 6 OK 2026/06/04 02:18:35 DEBUG : three.txt: Dst hash empty - aborting Src hash check 2026/06/04 02:18:35 INFO : three.txt: Copied (new) 2026/06/04 02:18:36 DEBUG : one: size = 5 (Local file system at /tmp/rclone3252141926) 2026/06/04 02:18:36 DEBUG : one: size = 4 (files root 'rclone-test-pibukez6fova/dst') 2026/06/04 02:18:36 DEBUG : one: Sizes differ 2026/06/04 02:18:36 INFO : one.bak: Deleted 2026/06/04 02:18:37 INFO : one: Moved (server-side) to: one.bak 2026/06/04 02:18:38 DEBUG : one: size = 5 OK 2026/06/04 02:18:38 DEBUG : one: Dst hash empty - aborting Src hash check 2026/06/04 02:18:38 INFO : one: Copied (new) 2026/06/04 02:18:38 DEBUG : two: size = 3 OK 2026/06/04 02:18:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/06/04 02:18:38 DEBUG : two: Unchanged skipping 2026/06/04 02:18:38 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone3252141926) 2026/06/04 02:18:38 DEBUG : three.txt: size = 6 (files root 'rclone-test-pibukez6fova/dst') 2026/06/04 02:18:38 DEBUG : three.txt: Sizes differ 2026/06/04 02:18:38 INFO : three.txt.bak: Deleted 2026/06/04 02:18:39 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/06/04 02:18:39 DEBUG : three.txt: size = 19 OK 2026/06/04 02:18:39 DEBUG : three.txt: Dst hash empty - aborting Src hash check 2026/06/04 02:18:39 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (13.47s) PASS 2026/06/04 02:18:41 DEBUG : files root 'rclone-test-pibukez6fova': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestCopyOverSelf|TestMoveWithIgnoreExisting|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes|TestSyncSuffix)$'" - Finished OK in 47.439476732s (try 2/5)