"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestServerSideCopyOverSelf|TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime|TestSyncBackupDirSuffixOnly|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes)$'" - Starting (try 2/5) 2025/09/24 01:42:29 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-jawutej1zipo" 2025/09/24 01:42:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/24 01:42:30 DEBUG : Creating backend with remote "/tmp/rclone1705264771" === RUN TestCopyNoEmptyDirectories run.go:180: Remote "files root 'rclone-test-jawutej1zipo'", Local "Local file system at /tmp/rclone1705264771", Modify Window "1s" 2025/09/24 01:42:30 INFO : sub dir2: Making directory 2025/09/24 01:42:30 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/09/24 01:42:30 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/24 01:42:30 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/09/24 01:42:30 DEBUG : Added delayed dir = "sub dir", newDst= 2025/09/24 01:42:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/24 01:42:30 DEBUG : Added delayed dir = "sub dir2/sub sub dir2", newDst= 2025/09/24 01:42:30 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for checks to finish 2025/09/24 01:42:30 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for transfers to finish 2025/09/24 01:42:31 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/09/24 01:42:31 INFO : sub dir/hello world: Copied (new) 2025/09/24 01:42:31 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyNoEmptyDirectories (2.41s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "files root 'rclone-test-jawutej1zipo'", Local "Local file system at /tmp/rclone1705264771", Modify Window "1s" 2025/09/24 01:42:32 DEBUG : sub dir no update dir modtime: Making directory with metadata 2025/09/24 01:42:32 INFO : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/09/24 01:42:33 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for checks to finish 2025/09/24 01:42:33 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for transfers to finish 2025/09/24 01:42:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoUpdateDirModtime (1.52s) === RUN TestSyncEmptyDirectories run.go:180: Remote "files root 'rclone-test-jawutej1zipo'", Local "Local file system at /tmp/rclone1705264771", Modify Window "1s" 2025/09/24 01:42:34 DEBUG : sub dir2: Making directory with metadata 2025/09/24 01:42:34 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/24 01:42:34 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:34 INFO : sub dir2: Making directory 2025/09/24 01:42:34 INFO : sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/09/24 01:42:34 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/09/24 01:42:34 INFO : sub dir: Making directory 2025/09/24 01:42:35 INFO : sub dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2025/09/24 01:42:35 DEBUG : Added delayed dir = "sub dir", newDst= 2025/09/24 01:42:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/24 01:42:35 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for checks to finish 2025/09/24 01:42:35 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for transfers to finish 2025/09/24 01:42:35 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/09/24 01:42:35 INFO : sub dir/hello world: Copied (new) 2025/09/24 01:42:35 DEBUG : Waiting for deletions to finish 2025/09/24 01:42:35 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:35 INFO : sub dir2: Set directory modification time (using DirSetModTime) --- PASS: TestSyncEmptyDirectories (3.12s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "files root 'rclone-test-jawutej1zipo'", Local "Local file system at /tmp/rclone1705264771", Modify Window "1s" 2025/09/24 01:42:37 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:37 INFO : a1: Making directory 2025/09/24 01:42:37 INFO : a1: Made directory with modification time 2001-02-03 04:19:06.499999999 +0000 UTC 2025/09/24 01:42:37 DEBUG : Added delayed dir = "a1", newDst= 2025/09/24 01:42:37 INFO : a1/b1: Making directory 2025/09/24 01:42:38 INFO : a1/b1: Made directory with modification time 2001-02-03 04:18:06.499999999 +0000 UTC 2025/09/24 01:42:38 DEBUG : Added delayed dir = "a1/b1", newDst= 2025/09/24 01:42:38 INFO : a1/b2: Making directory 2025/09/24 01:42:38 INFO : a1/b2: Made directory with modification time 2001-02-03 04:09:06.499999999 +0000 UTC 2025/09/24 01:42:38 DEBUG : Added delayed dir = "a1/b2", newDst= 2025/09/24 01:42:38 INFO : a1/b2/c1: Making directory 2025/09/24 01:42:38 INFO : a1/b1/c1: Making directory 2025/09/24 01:42:38 INFO : a1/b2/c1: Made directory with modification time 2001-02-03 04:08:06.499999999 +0000 UTC 2025/09/24 01:42:38 DEBUG : Added delayed dir = "a1/b2/c1", newDst= 2025/09/24 01:42:38 INFO : a1/b2/c1/d1: Making directory 2025/09/24 01:42:38 INFO : a1/b1/c1: Made directory with modification time 2001-02-03 04:17:06.499999999 +0000 UTC 2025/09/24 01:42:38 DEBUG : Added delayed dir = "a1/b1/c1", newDst= 2025/09/24 01:42:38 INFO : a1/b1/c1/d1: Making directory 2025/09/24 01:42:39 INFO : a1/b2/c1/d1: Made directory with modification time 2001-02-03 04:07:06.499999999 +0000 UTC 2025/09/24 01:42:39 DEBUG : Added delayed dir = "a1/b2/c1/d1", newDst= 2025/09/24 01:42:39 INFO : a1/b2/c1/d1/e1: Making directory 2025/09/24 01:42:39 INFO : a1/b1/c1/d1: Made directory with modification time 2001-02-03 04:16:06.499999999 +0000 UTC 2025/09/24 01:42:39 DEBUG : Added delayed dir = "a1/b1/c1/d1", newDst= 2025/09/24 01:42:39 INFO : a1/b1/c1/d2: Making directory 2025/09/24 01:42:39 INFO : a1/b1/c1/d2: Made directory with modification time 2001-02-03 04:13:06.499999999 +0000 UTC 2025/09/24 01:42:39 DEBUG : Added delayed dir = "a1/b1/c1/d2", newDst= 2025/09/24 01:42:39 INFO : a1/b1/c1/d2/e1: Making directory 2025/09/24 01:42:39 INFO : a1/b1/c1/d1/e1: Making directory 2025/09/24 01:42:39 INFO : a1/b2/c1/d1/e1: Made directory with modification time 2001-02-03 04:06:06.499999999 +0000 UTC 2025/09/24 01:42:39 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1", newDst= 2025/09/24 01:42:39 INFO : a1/b2/c1/d1/e1/f1: Making directory 2025/09/24 01:42:39 INFO : a1/b1/c1/d2/e1: Made directory with modification time 2001-02-03 04:12:06.499999999 +0000 UTC 2025/09/24 01:42:39 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1", newDst= 2025/09/24 01:42:39 INFO : a1/b1/c1/d2/e1/f1: Making directory 2025/09/24 01:42:39 INFO : a1/b1/c1/d1/e1: Made directory with modification time 2001-02-03 04:15:06.499999999 +0000 UTC 2025/09/24 01:42:39 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1", newDst= 2025/09/24 01:42:39 INFO : a1/b1/c1/d1/e1/f1: Making directory 2025/09/24 01:42:39 INFO : a1/b2/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2025/09/24 01:42:39 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1/f1", newDst= 2025/09/24 01:42:40 INFO : a1/b1/c1/d2/e1/f1: Made directory with modification time 2001-02-03 04:11:06.499999999 +0000 UTC 2025/09/24 01:42:40 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f1", newDst= 2025/09/24 01:42:40 INFO : a1/b1/c1/d2/e1/f2: Making directory 2025/09/24 01:42:40 INFO : a1/b1/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:14:06.499999999 +0000 UTC 2025/09/24 01:42:40 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1/f1", newDst= 2025/09/24 01:42:40 INFO : a1/b1/c1/d2/e1/f2: Made directory with modification time 2001-02-03 04:10:06.499999999 +0000 UTC 2025/09/24 01:42:40 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f2", newDst= 2025/09/24 01:42:40 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for checks to finish 2025/09/24 01:42:40 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for transfers to finish 2025/09/24 01:42:40 DEBUG : Waiting for deletions to finish 2025/09/24 01:42:40 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:40 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:41 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:41 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:41 INFO : a1: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:45 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1/b2/c1/d1/e1 not empty`) 2025/09/24 01:42:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/24 01:42:45 DEBUG : pacer: Reducing sleep to 15ms 2025/09/24 01:42:45 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/24 01:42:46 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1/b2/c1 not empty`) 2025/09/24 01:42:46 DEBUG : pacer: Rate limited, increasing sleep to 22.5ms 2025/09/24 01:42:46 DEBUG : pacer: Reducing sleep to 16.875ms 2025/09/24 01:42:46 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1/b2 not empty`) 2025/09/24 01:42:46 DEBUG : pacer: Rate limited, increasing sleep to 33.75ms 2025/09/24 01:42:46 DEBUG : pacer: Reducing sleep to 25.3125ms 2025/09/24 01:42:46 DEBUG : pacer: Reducing sleep to 18.984375ms 2025/09/24 01:42:46 DEBUG : pacer: Reducing sleep to 14.238281ms 2025/09/24 01:42:47 DEBUG : pacer: Reducing sleep to 10.67871ms 2025/09/24 01:42:47 DEBUG : pacer: Reducing sleep to 10ms 2025/09/24 01:42:47 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1/b1/c1/d1 not empty`) 2025/09/24 01:42:47 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/24 01:42:47 DEBUG : pacer: Reducing sleep to 15ms 2025/09/24 01:42:47 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1/b1/c1 not empty`) 2025/09/24 01:42:47 DEBUG : pacer: Rate limited, increasing sleep to 30ms 2025/09/24 01:42:48 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1/b1/c1 not empty`) 2025/09/24 01:42:48 DEBUG : pacer: Rate limited, increasing sleep to 60ms 2025/09/24 01:42:48 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1/b1/c1 not empty`) 2025/09/24 01:42:48 DEBUG : pacer: Rate limited, increasing sleep to 120ms 2025/09/24 01:42:48 DEBUG : pacer: Reducing sleep to 90ms 2025/09/24 01:42:48 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1/b1 not empty`) 2025/09/24 01:42:48 DEBUG : pacer: Rate limited, increasing sleep to 180ms 2025/09/24 01:42:48 DEBUG : pacer: Reducing sleep to 135ms 2025/09/24 01:42:48 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1 not empty`) 2025/09/24 01:42:48 DEBUG : pacer: Rate limited, increasing sleep to 270ms 2025/09/24 01:42:48 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1 not empty`) 2025/09/24 01:42:48 DEBUG : pacer: Rate limited, increasing sleep to 540ms 2025/09/24 01:42:49 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-jawutej1zipo/a1 not empty`) 2025/09/24 01:42:49 DEBUG : pacer: Rate limited, increasing sleep to 1.08s 2025/09/24 01:42:49 DEBUG : pacer: Reducing sleep to 810ms 2025/09/24 01:42:50 DEBUG : pacer: Reducing sleep to 607.5ms --- PASS: TestSyncSetDelayedModTimes (13.42s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "files root 'rclone-test-jawutej1zipo'", Local "Local file system at /tmp/rclone1705264771", Modify Window "1s" 2025/09/24 01:42:50 INFO : sub dir2: Making directory 2025/09/24 01:42:51 DEBUG : pacer: Reducing sleep to 455.625ms 2025/09/24 01:42:51 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/09/24 01:42:51 DEBUG : Added delayed dir = "sub dir", newDst= 2025/09/24 01:42:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/24 01:42:51 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for checks to finish 2025/09/24 01:42:51 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for transfers to finish 2025/09/24 01:42:53 DEBUG : pacer: Reducing sleep to 341.71875ms 2025/09/24 01:42:53 DEBUG : pacer: Reducing sleep to 256.289062ms 2025/09/24 01:42:53 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/09/24 01:42:53 INFO : sub dir/hello world: Copied (new) 2025/09/24 01:42:53 DEBUG : Waiting for deletions to finish 2025/09/24 01:42:53 DEBUG : pacer: Reducing sleep to 192.216796ms 2025/09/24 01:42:53 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/24 01:42:53 DEBUG : pacer: Reducing sleep to 144.162597ms 2025/09/24 01:42:53 DEBUG : pacer: Reducing sleep to 108.121947ms 2025/09/24 01:42:54 DEBUG : pacer: Reducing sleep to 81.09146ms 2025/09/24 01:42:54 DEBUG : pacer: Reducing sleep to 60.818595ms 2025/09/24 01:42:55 DEBUG : pacer: Reducing sleep to 45.613946ms 2025/09/24 01:42:55 DEBUG : pacer: Reducing sleep to 34.210459ms 2025/09/24 01:42:55 DEBUG : pacer: Reducing sleep to 25.657844ms --- PASS: TestSyncNoEmptyDirectories (4.67s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "files root 'rclone-test-jawutej1zipo'", Local "Local file system at /tmp/rclone1705264771", Modify Window "1s" 2025/09/24 01:42:56 DEBUG : pacer: Reducing sleep to 19.243383ms 2025/09/24 01:42:56 DEBUG : pacer: Reducing sleep to 14.432537ms 2025/09/24 01:42:56 DEBUG : pacer: Reducing sleep to 10.824402ms 2025/09/24 01:42:56 DEBUG : pacer: Reducing sleep to 10ms 2025/09/24 01:42:56 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-rixukod0ceca" sync_test.go:656: Server side copy (if possible) files root 'rclone-test-jawutej1zipo' -> files root 'rclone-test-rixukod0ceca' 2025/09/24 01:42:57 DEBUG : Added delayed dir = "sub dir", newDst= 2025/09/24 01:42:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/24 01:42:57 DEBUG : files root 'rclone-test-rixukod0ceca': Waiting for checks to finish 2025/09/24 01:42:57 DEBUG : files root 'rclone-test-rixukod0ceca': Waiting for transfers to finish 2025/09/24 01:43:00 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/24 01:43:00 INFO : sub dir/hello world: Copied (server-side copy) 2025/09/24 01:43:00 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/24 01:43:02 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/24 01:43:02 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/09/24 01:43:03 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/24 01:43:03 DEBUG : files root 'rclone-test-rixukod0ceca': Waiting for checks to finish 2025/09/24 01:43:03 DEBUG : files root 'rclone-test-rixukod0ceca': Waiting for transfers to finish 2025/09/24 01:43:05 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/09/24 01:43:05 INFO : sub dir/hello world: Copied (server-side copy) 2025/09/24 01:43:05 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/24 01:43:05 DEBUG : files root 'rclone-test-rixukod0ceca': Purge remote --- PASS: TestServerSideCopyOverSelf (12.11s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "files root 'rclone-test-jawutej1zipo'", Local "Local file system at /tmp/rclone1705264771", Modify Window "1s" 2025/09/24 01:43:09 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for checks to finish 2025/09/24 01:43:09 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2025/09/24 01:43:09 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/09/24 01:43:09 DEBUG : empty space: Unchanged skipping 2025/09/24 01:43:09 DEBUG : files root 'rclone-test-jawutej1zipo': Waiting for transfers to finish 2025/09/24 01:43:09 DEBUG : Waiting for deletions to finish 2025/09/24 01:43:09 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (2.70s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "files root 'rclone-test-jawutej1zipo'", Local "Local file system at /tmp/rclone1705264771", Modify Window "1s" 2025/09/24 01:43:12 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-jawutej1zipo/dst" 2025/09/24 01:43:14 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/09/24 01:43:14 DEBUG : files root 'rclone-test-jawutej1zipo/dst': Waiting for checks to finish 2025/09/24 01:43:14 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/24 01:43:14 DEBUG : two: Unchanged skipping 2025/09/24 01:43:15 INFO : one: Moved (server-side) to: one.bak 2025/09/24 01:43:15 DEBUG : files root 'rclone-test-jawutej1zipo/dst': Waiting for transfers to finish 2025/09/24 01:43:16 DEBUG : one: Dst hash empty - aborting Src hash check 2025/09/24 01:43:16 INFO : one: Copied (new) 2025/09/24 01:43:16 DEBUG : Waiting for deletions to finish 2025/09/24 01:43:17 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/09/24 01:43:17 INFO : three.txt: Moved into backup dir 2025/09/24 01:43:20 DEBUG : one.bak: Excluded (Path Filter) 2025/09/24 01:43:20 DEBUG : one.bak: Excluded 2025/09/24 01:43:20 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/09/24 01:43:20 DEBUG : three.txt.bak: Excluded 2025/09/24 01:43:20 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/09/24 01:43:20 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/09/24 01:43:20 DEBUG : two: Unchanged skipping 2025/09/24 01:43:20 DEBUG : files root 'rclone-test-jawutej1zipo/dst': Waiting for checks to finish 2025/09/24 01:43:20 INFO : one.bak: Deleted 2025/09/24 01:43:21 INFO : one: Moved (server-side) to: one.bak 2025/09/24 01:43:21 DEBUG : files root 'rclone-test-jawutej1zipo/dst': Waiting for transfers to finish 2025/09/24 01:43:23 DEBUG : one: Dst hash empty - aborting Src hash check 2025/09/24 01:43:23 INFO : one: Copied (new) 2025/09/24 01:43:23 DEBUG : Waiting for deletions to finish 2025/09/24 01:43:24 INFO : three.txt.bak: Deleted 2025/09/24 01:43:24 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/09/24 01:43:24 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (17.29s) PASS 2025/09/24 01:43:27 DEBUG : files root 'rclone-test-jawutej1zipo': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestServerSideCopyOverSelf|TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime|TestSyncBackupDirSuffixOnly|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes)$'" - Finished OK in 58.371015028s (try 2/5)