"./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestServerSideCopyOverSelf|TestSyncBackupDirWithSuffix|TestSyncConcurrentTruncate|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes)$'" - Starting (try 2/5) 2026/07/02 03:23:08 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-detomom1qoli" 2026/07/02 03:23:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/02 03:23:09 DEBUG : Creating backend with remote "/tmp/rclone2017260792" === RUN TestCopyNoEmptyDirectories run.go:198: Remote "files root 'rclone-test-detomom1qoli'", Local "Local file system at /tmp/rclone2017260792", Modify Window "1s" 2026/07/02 03:23:09 INFO : sub dir2: Making directory 2026/07/02 03:23:09 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/07/02 03:23:09 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/02 03:23:09 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/07/02 03:23:09 DEBUG : Added delayed dir = "sub dir", newDst= 2026/07/02 03:23:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/02 03:23:09 DEBUG : Added delayed dir = "sub dir2/sub sub dir2", newDst= 2026/07/02 03:23:09 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for checks to finish 2026/07/02 03:23:09 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for transfers to finish 2026/07/02 03:23:10 DEBUG : sub dir/hello world: size = 11 OK 2026/07/02 03:23:10 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/07/02 03:23:10 INFO : sub dir/hello world: Copied (new) 2026/07/02 03:23:11 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyNoEmptyDirectories (2.99s) === RUN TestSyncNoUpdateDirModtime run.go:198: Remote "files root 'rclone-test-detomom1qoli'", Local "Local file system at /tmp/rclone2017260792", Modify Window "1s" 2026/07/02 03:23:12 DEBUG : sub dir no update dir modtime: Making directory with metadata 2026/07/02 03:23:12 INFO : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/07/02 03:23:12 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for checks to finish 2026/07/02 03:23:12 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for transfers to finish 2026/07/02 03:23:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoUpdateDirModtime (1.59s) === RUN TestSyncEmptyDirectories run.go:198: Remote "files root 'rclone-test-detomom1qoli'", Local "Local file system at /tmp/rclone2017260792", Modify Window "1s" 2026/07/02 03:23:13 DEBUG : sub dir2: Making directory with metadata 2026/07/02 03:23:13 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/07/02 03:23:13 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:13 INFO : sub dir2: Making directory 2026/07/02 03:23:14 INFO : sub dir2: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2026/07/02 03:23:14 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/07/02 03:23:14 INFO : sub dir: Making directory 2026/07/02 03:23:14 INFO : sub dir: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2026/07/02 03:23:14 DEBUG : Added delayed dir = "sub dir", newDst= 2026/07/02 03:23:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/02 03:23:14 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for checks to finish 2026/07/02 03:23:14 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for transfers to finish 2026/07/02 03:23:15 DEBUG : sub dir/hello world: size = 11 OK 2026/07/02 03:23:15 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/07/02 03:23:15 INFO : sub dir/hello world: Copied (new) 2026/07/02 03:23:15 DEBUG : Waiting for deletions to finish 2026/07/02 03:23:15 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:15 INFO : sub dir2: Set directory modification time (using DirSetModTime) --- PASS: TestSyncEmptyDirectories (3.29s) === RUN TestSyncSetDelayedModTimes run.go:198: Remote "files root 'rclone-test-detomom1qoli'", Local "Local file system at /tmp/rclone2017260792", Modify Window "1s" 2026/07/02 03:23:17 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:17 INFO : a1: Making directory 2026/07/02 03:23:17 INFO : a1: Made directory with modification time 2001-02-03 04:19:06.499999999 +0000 UTC 2026/07/02 03:23:17 DEBUG : Added delayed dir = "a1", newDst= 2026/07/02 03:23:17 INFO : a1/b1: Making directory 2026/07/02 03:23:17 INFO : a1/b1: Made directory with modification time 2001-02-03 04:18:06.499999999 +0000 UTC 2026/07/02 03:23:17 DEBUG : Added delayed dir = "a1/b1", newDst= 2026/07/02 03:23:17 INFO : a1/b2: Making directory 2026/07/02 03:23:18 INFO : a1/b2: Made directory with modification time 2001-02-03 04:09:06.499999999 +0000 UTC 2026/07/02 03:23:18 DEBUG : Added delayed dir = "a1/b2", newDst= 2026/07/02 03:23:18 INFO : a1/b2/c1: Making directory 2026/07/02 03:23:18 INFO : a1/b1/c1: Making directory 2026/07/02 03:23:18 INFO : a1/b1/c1: Made directory with modification time 2001-02-03 04:17:06.499999999 +0000 UTC 2026/07/02 03:23:18 DEBUG : Added delayed dir = "a1/b1/c1", newDst= 2026/07/02 03:23:18 INFO : a1/b1/c1/d1: Making directory 2026/07/02 03:23:18 INFO : a1/b2/c1: Made directory with modification time 2001-02-03 04:08:06.499999999 +0000 UTC 2026/07/02 03:23:18 DEBUG : Added delayed dir = "a1/b2/c1", newDst= 2026/07/02 03:23:18 INFO : a1/b2/c1/d1: Making directory 2026/07/02 03:23:18 INFO : a1/b1/c1/d1: Made directory with modification time 2001-02-03 04:16:06.499999999 +0000 UTC 2026/07/02 03:23:18 DEBUG : Added delayed dir = "a1/b1/c1/d1", newDst= 2026/07/02 03:23:18 INFO : a1/b1/c1/d2: Making directory 2026/07/02 03:23:18 INFO : a1/b2/c1/d1: Made directory with modification time 2001-02-03 04:07:06.499999999 +0000 UTC 2026/07/02 03:23:18 DEBUG : Added delayed dir = "a1/b2/c1/d1", newDst= 2026/07/02 03:23:18 INFO : a1/b2/c1/d1/e1: Making directory 2026/07/02 03:23:19 INFO : a1/b2/c1/d1/e1: Made directory with modification time 2001-02-03 04:06:06.499999999 +0000 UTC 2026/07/02 03:23:19 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1", newDst= 2026/07/02 03:23:19 INFO : a1/b2/c1/d1/e1/f1: Making directory 2026/07/02 03:23:19 INFO : a1/b1/c1/d2: Made directory with modification time 2001-02-03 04:13:06.499999999 +0000 UTC 2026/07/02 03:23:19 DEBUG : Added delayed dir = "a1/b1/c1/d2", newDst= 2026/07/02 03:23:19 INFO : a1/b1/c1/d2/e1: Making directory 2026/07/02 03:23:19 INFO : a1/b1/c1/d1/e1: Making directory 2026/07/02 03:23:19 INFO : a1/b2/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2026/07/02 03:23:19 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1/f1", newDst= 2026/07/02 03:23:19 INFO : a1/b1/c1/d2/e1: Made directory with modification time 2001-02-03 04:12:06.499999999 +0000 UTC 2026/07/02 03:23:19 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1", newDst= 2026/07/02 03:23:19 INFO : a1/b1/c1/d2/e1/f1: Making directory 2026/07/02 03:23:19 INFO : a1/b1/c1/d1/e1: Made directory with modification time 2001-02-03 04:15:06.499999999 +0000 UTC 2026/07/02 03:23:19 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1", newDst= 2026/07/02 03:23:19 INFO : a1/b1/c1/d1/e1/f1: Making directory 2026/07/02 03:23:19 INFO : a1/b1/c1/d2/e1/f1: Made directory with modification time 2001-02-03 04:11:06.499999999 +0000 UTC 2026/07/02 03:23:19 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f1", newDst= 2026/07/02 03:23:19 INFO : a1/b1/c1/d2/e1/f2: Making directory 2026/07/02 03:23:19 INFO : a1/b1/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:14:06.499999999 +0000 UTC 2026/07/02 03:23:19 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1/f1", newDst= 2026/07/02 03:23:20 INFO : a1/b1/c1/d2/e1/f2: Made directory with modification time 2001-02-03 04:10:06.499999999 +0000 UTC 2026/07/02 03:23:20 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f2", newDst= 2026/07/02 03:23:20 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for checks to finish 2026/07/02 03:23:20 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for transfers to finish 2026/07/02 03:23:20 DEBUG : Waiting for deletions to finish 2026/07/02 03:23:20 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:20 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:21 INFO : a1: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:25 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b2/c1/d1/e1 not empty`) 2026/07/02 03:23:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/07/02 03:23:25 DEBUG : pacer: Reducing sleep to 15ms 2026/07/02 03:23:25 DEBUG : pacer: Reducing sleep to 11.25ms 2026/07/02 03:23:25 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b2/c1 not empty`) 2026/07/02 03:23:25 DEBUG : pacer: Rate limited, increasing sleep to 22.5ms 2026/07/02 03:23:25 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b2/c1 not empty`) 2026/07/02 03:23:25 DEBUG : pacer: Rate limited, increasing sleep to 45ms 2026/07/02 03:23:25 DEBUG : pacer: Reducing sleep to 33.75ms 2026/07/02 03:23:26 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b2 not empty`) 2026/07/02 03:23:26 DEBUG : pacer: Rate limited, increasing sleep to 67.5ms 2026/07/02 03:23:26 DEBUG : pacer: Reducing sleep to 50.625ms 2026/07/02 03:23:26 DEBUG : pacer: Reducing sleep to 37.96875ms 2026/07/02 03:23:26 DEBUG : pacer: Reducing sleep to 28.476562ms 2026/07/02 03:23:26 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b1/c1/d2/e1 not empty`) 2026/07/02 03:23:26 DEBUG : pacer: Rate limited, increasing sleep to 56.953124ms 2026/07/02 03:23:26 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b1/c1/d2/e1 not empty`) 2026/07/02 03:23:26 DEBUG : pacer: Rate limited, increasing sleep to 113.906248ms 2026/07/02 03:23:26 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b1/c1/d2/e1 not empty`) 2026/07/02 03:23:26 DEBUG : pacer: Rate limited, increasing sleep to 227.812496ms 2026/07/02 03:23:27 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b1/c1/d2/e1 not empty`) 2026/07/02 03:23:27 DEBUG : pacer: Rate limited, increasing sleep to 455.624992ms 2026/07/02 03:23:27 DEBUG : pacer: Reducing sleep to 341.718744ms 2026/07/02 03:23:27 DEBUG : pacer: Reducing sleep to 256.289058ms 2026/07/02 03:23:28 DEBUG : pacer: Reducing sleep to 192.216793ms 2026/07/02 03:23:28 DEBUG : pacer: Reducing sleep to 144.162594ms 2026/07/02 03:23:28 DEBUG : pacer: Reducing sleep to 108.121945ms 2026/07/02 03:23:28 DEBUG : pacer: Reducing sleep to 81.091458ms 2026/07/02 03:23:28 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b1 not empty`) 2026/07/02 03:23:28 DEBUG : pacer: Rate limited, increasing sleep to 162.182916ms 2026/07/02 03:23:29 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b1 not empty`) 2026/07/02 03:23:29 DEBUG : pacer: Rate limited, increasing sleep to 324.365832ms 2026/07/02 03:23:29 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b1 not empty`) 2026/07/02 03:23:29 DEBUG : pacer: Rate limited, increasing sleep to 648.731664ms 2026/07/02 03:23:29 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-detomom1qoli/a1/b1 not empty`) 2026/07/02 03:23:29 DEBUG : pacer: Rate limited, increasing sleep to 1.297463328s 2026/07/02 03:23:30 DEBUG : pacer: Reducing sleep to 973.097496ms 2026/07/02 03:23:31 DEBUG : pacer: Reducing sleep to 729.823122ms 2026/07/02 03:23:32 DEBUG : pacer: Reducing sleep to 547.367341ms --- PASS: TestSyncSetDelayedModTimes (15.41s) === RUN TestSyncNoEmptyDirectories run.go:198: Remote "files root 'rclone-test-detomom1qoli'", Local "Local file system at /tmp/rclone2017260792", Modify Window "1s" 2026/07/02 03:23:32 INFO : sub dir2: Making directory 2026/07/02 03:23:33 DEBUG : pacer: Reducing sleep to 410.525505ms 2026/07/02 03:23:33 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/07/02 03:23:33 DEBUG : Added delayed dir = "sub dir", newDst= 2026/07/02 03:23:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/02 03:23:33 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for checks to finish 2026/07/02 03:23:33 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for transfers to finish 2026/07/02 03:23:34 DEBUG : pacer: Reducing sleep to 307.894128ms 2026/07/02 03:23:35 DEBUG : pacer: Reducing sleep to 230.920596ms 2026/07/02 03:23:35 DEBUG : sub dir/hello world: size = 11 OK 2026/07/02 03:23:35 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/07/02 03:23:35 INFO : sub dir/hello world: Copied (new) 2026/07/02 03:23:35 DEBUG : Waiting for deletions to finish 2026/07/02 03:23:35 DEBUG : pacer: Reducing sleep to 173.190447ms 2026/07/02 03:23:35 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:35 DEBUG : pacer: Reducing sleep to 129.892835ms 2026/07/02 03:23:35 DEBUG : pacer: Reducing sleep to 97.419626ms 2026/07/02 03:23:35 DEBUG : pacer: Reducing sleep to 73.064719ms 2026/07/02 03:23:35 DEBUG : pacer: Reducing sleep to 54.798539ms 2026/07/02 03:23:36 DEBUG : pacer: Reducing sleep to 41.098904ms 2026/07/02 03:23:36 DEBUG : pacer: Reducing sleep to 30.824178ms 2026/07/02 03:23:36 DEBUG : pacer: Reducing sleep to 23.118133ms --- PASS: TestSyncNoEmptyDirectories (3.90s) === RUN TestServerSideCopyOverSelf run.go:198: Remote "files root 'rclone-test-detomom1qoli'", Local "Local file system at /tmp/rclone2017260792", Modify Window "1s" 2026/07/02 03:23:37 DEBUG : pacer: Reducing sleep to 17.338599ms 2026/07/02 03:23:37 DEBUG : pacer: Reducing sleep to 13.003949ms 2026/07/02 03:23:37 DEBUG : pacer: Reducing sleep to 10ms 2026/07/02 03:23:37 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-jemojok2fofi" sync_test.go:656: Server side copy (if possible) files root 'rclone-test-detomom1qoli' -> files root 'rclone-test-jemojok2fofi' 2026/07/02 03:23:38 DEBUG : Added delayed dir = "sub dir", newDst= 2026/07/02 03:23:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/02 03:23:38 DEBUG : files root 'rclone-test-jemojok2fofi': Waiting for checks to finish 2026/07/02 03:23:38 DEBUG : files root 'rclone-test-jemojok2fofi': Waiting for transfers to finish 2026/07/02 03:23:40 DEBUG : sub dir/hello world: size = 11 OK 2026/07/02 03:23:40 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/07/02 03:23:40 INFO : sub dir/hello world: Copied (server-side copy) 2026/07/02 03:23:40 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:42 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:42 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2026/07/02 03:23:42 DEBUG : sub dir/hello world: size = 17 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:23:42 DEBUG : sub dir/hello world: size = 11 (files root 'rclone-test-jemojok2fofi') 2026/07/02 03:23:42 DEBUG : sub dir/hello world: Sizes differ 2026/07/02 03:23:42 DEBUG : files root 'rclone-test-jemojok2fofi': Waiting for checks to finish 2026/07/02 03:23:42 DEBUG : files root 'rclone-test-jemojok2fofi': Waiting for transfers to finish 2026/07/02 03:23:43 DEBUG : sub dir/hello world: size = 17 OK 2026/07/02 03:23:43 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2026/07/02 03:23:43 INFO : sub dir/hello world: Copied (server-side copy) 2026/07/02 03:23:43 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/07/02 03:23:44 DEBUG : files root 'rclone-test-jemojok2fofi': Purge remote --- PASS: TestServerSideCopyOverSelf (8.86s) === RUN TestSyncBackupDirWithSuffix run.go:198: Remote "files root 'rclone-test-detomom1qoli'", Local "Local file system at /tmp/rclone2017260792", Modify Window "1s" 2026/07/02 03:23:47 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-detomom1qoli/dst" 2026/07/02 03:23:47 DEBUG : Config file has changed externally - reloading 2026/07/02 03:23:48 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-detomom1qoli/backup" 2026/07/02 03:23:48 DEBUG : one: size = 4 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:23:48 DEBUG : one: size = 3 (files root 'rclone-test-detomom1qoli/dst') 2026/07/02 03:23:48 DEBUG : one: Sizes differ 2026/07/02 03:23:48 DEBUG : two: size = 3 OK 2026/07/02 03:23:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:23:48 DEBUG : files root 'rclone-test-detomom1qoli/dst': Waiting for checks to finish 2026/07/02 03:23:48 DEBUG : two: Unchanged skipping 2026/07/02 03:23:49 INFO : one: Moved (server-side) to: one.bak 2026/07/02 03:23:49 DEBUG : files root 'rclone-test-detomom1qoli/dst': Waiting for transfers to finish 2026/07/02 03:23:50 DEBUG : one: size = 4 OK 2026/07/02 03:23:50 DEBUG : one: Dst hash empty - aborting Src hash check 2026/07/02 03:23:50 INFO : one: Copied (new) 2026/07/02 03:23:50 DEBUG : Waiting for deletions to finish 2026/07/02 03:23:51 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/07/02 03:23:51 INFO : three.txt: Moved into backup dir 2026/07/02 03:23:53 DEBUG : one: size = 5 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:23:53 DEBUG : one: size = 4 (files root 'rclone-test-detomom1qoli/dst') 2026/07/02 03:23:53 DEBUG : one: Sizes differ 2026/07/02 03:23:53 DEBUG : two: size = 3 OK 2026/07/02 03:23:53 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:23:53 DEBUG : two: Unchanged skipping 2026/07/02 03:23:53 DEBUG : files root 'rclone-test-detomom1qoli/dst': Waiting for checks to finish 2026/07/02 03:23:53 INFO : one.bak: Deleted 2026/07/02 03:23:53 INFO : one: Moved (server-side) to: one.bak 2026/07/02 03:23:53 DEBUG : files root 'rclone-test-detomom1qoli/dst': Waiting for transfers to finish 2026/07/02 03:23:55 DEBUG : one: size = 5 OK 2026/07/02 03:23:55 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/07/02 03:23:55 INFO : one: Copied (new) 2026/07/02 03:23:55 DEBUG : Waiting for deletions to finish 2026/07/02 03:23:55 INFO : three.txt.bak: Deleted 2026/07/02 03:23:55 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/07/02 03:23:55 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (12.46s) === RUN TestSyncConcurrentTruncate run.go:198: Remote "files root 'rclone-test-detomom1qoli'", Local "Local file system at /tmp/rclone2017260792", Modify Window "1s" 2026/07/02 03:24:29 DEBUG : both11: size = 6 OK 2026/07/02 03:24:29 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both0: size = 6 OK 2026/07/02 03:24:29 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both12: size = 6 OK 2026/07/02 03:24:29 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for checks to finish 2026/07/02 03:24:29 DEBUG : both10: size = 6 OK 2026/07/02 03:24:29 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both11: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both13: size = 6 OK 2026/07/02 03:24:29 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both10: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both14: size = 6 OK 2026/07/02 03:24:29 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both14: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both15: size = 6 OK 2026/07/02 03:24:29 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both15: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both16: size = 6 OK 2026/07/02 03:24:29 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both16: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both17: size = 6 OK 2026/07/02 03:24:29 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both17: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both18: size = 6 OK 2026/07/02 03:24:29 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both18: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both19: size = 6 OK 2026/07/02 03:24:29 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both19: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both1: size = 6 OK 2026/07/02 03:24:29 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both13: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both2: size = 6 OK 2026/07/02 03:24:29 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both2: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both3: size = 6 OK 2026/07/02 03:24:29 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both3: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both4: size = 6 OK 2026/07/02 03:24:29 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both4: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both5: size = 6 OK 2026/07/02 03:24:29 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both5: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both6: size = 6 OK 2026/07/02 03:24:29 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both6: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both7: size = 6 OK 2026/07/02 03:24:29 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both7: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both8: size = 6 OK 2026/07/02 03:24:29 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both8: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both9: size = 6 OK 2026/07/02 03:24:29 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/07/02 03:24:29 DEBUG : both9: Unchanged skipping 2026/07/02 03:24:29 DEBUG : only0: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only0: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only0: Sizes differ 2026/07/02 03:24:29 DEBUG : only10: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only10: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only10: Sizes differ 2026/07/02 03:24:29 DEBUG : only11: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only11: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only11: Sizes differ 2026/07/02 03:24:29 DEBUG : only12: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only12: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only12: Sizes differ 2026/07/02 03:24:29 DEBUG : only13: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only13: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only13: Sizes differ 2026/07/02 03:24:29 DEBUG : only14: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only14: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only14: Sizes differ 2026/07/02 03:24:29 DEBUG : only15: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only15: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only15: Sizes differ 2026/07/02 03:24:29 DEBUG : only16: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only16: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only16: Sizes differ 2026/07/02 03:24:29 DEBUG : only17: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only17: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only17: Sizes differ 2026/07/02 03:24:29 DEBUG : only18: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only18: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only18: Sizes differ 2026/07/02 03:24:29 DEBUG : only19: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only19: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only19: Sizes differ 2026/07/02 03:24:29 DEBUG : only1: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only1: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only1: Sizes differ 2026/07/02 03:24:29 DEBUG : only2: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only2: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only2: Sizes differ 2026/07/02 03:24:29 DEBUG : only3: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only3: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only3: Sizes differ 2026/07/02 03:24:29 DEBUG : only4: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only4: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only4: Sizes differ 2026/07/02 03:24:29 DEBUG : only5: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only5: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only5: Sizes differ 2026/07/02 03:24:29 DEBUG : only6: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only6: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only6: Sizes differ 2026/07/02 03:24:29 DEBUG : only7: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only7: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only7: Sizes differ 2026/07/02 03:24:29 DEBUG : only8: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only8: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only8: Sizes differ 2026/07/02 03:24:29 DEBUG : only9: size = 0 (Local file system at /tmp/rclone2017260792) 2026/07/02 03:24:29 DEBUG : only9: size = 6 (files root 'rclone-test-detomom1qoli') 2026/07/02 03:24:29 DEBUG : only9: Sizes differ 2026/07/02 03:24:29 DEBUG : both12: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both0: Unchanged skipping 2026/07/02 03:24:29 DEBUG : both1: Unchanged skipping 2026/07/02 03:24:29 DEBUG : files root 'rclone-test-detomom1qoli': Waiting for transfers to finish 2026/07/02 03:24:30 DEBUG : only0: size = 0 OK 2026/07/02 03:24:30 DEBUG : only0: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/02 03:24:30 INFO : only0: Copied (replaced existing) 2026/07/02 03:24:30 DEBUG : only11: size = 0 OK 2026/07/02 03:24:30 DEBUG : only11: Dst hash empty - aborting Src hash check 2026/07/02 03:24:30 INFO : only11: Copied (replaced existing) 2026/07/02 03:24:30 DEBUG : only10: size = 0 OK 2026/07/02 03:24:30 DEBUG : only10: Dst hash empty - aborting Src hash check 2026/07/02 03:24:30 INFO : only10: Copied (replaced existing) 2026/07/02 03:24:31 DEBUG : only12: size = 0 OK 2026/07/02 03:24:31 DEBUG : only12: Dst hash empty - aborting Src hash check 2026/07/02 03:24:31 INFO : only12: Copied (replaced existing) 2026/07/02 03:24:31 DEBUG : only13: size = 0 OK 2026/07/02 03:24:31 DEBUG : only13: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/07/02 03:24:31 INFO : only13: Copied (replaced existing) 2026/07/02 03:24:31 DEBUG : only14: size = 0 OK 2026/07/02 03:24:31 DEBUG : only14: Dst hash empty - aborting Src hash check 2026/07/02 03:24:31 INFO : only14: Copied (replaced existing) 2026/07/02 03:24:31 DEBUG : only15: size = 0 OK 2026/07/02 03:24:31 DEBUG : only15: Dst hash empty - aborting Src hash check 2026/07/02 03:24:31 INFO : only15: Copied (replaced existing) 2026/07/02 03:24:31 DEBUG : only16: size = 0 OK 2026/07/02 03:24:31 DEBUG : only16: Dst hash empty - aborting Src hash check 2026/07/02 03:24:31 INFO : only16: Copied (replaced existing) 2026/07/02 03:24:32 DEBUG : only17: size = 0 OK 2026/07/02 03:24:32 DEBUG : only17: Dst hash empty - aborting Src hash check 2026/07/02 03:24:32 INFO : only17: Copied (replaced existing) 2026/07/02 03:24:32 DEBUG : only18: size = 0 OK 2026/07/02 03:24:32 DEBUG : only18: Dst hash empty - aborting Src hash check 2026/07/02 03:24:32 INFO : only18: Copied (replaced existing) 2026/07/02 03:24:32 DEBUG : only19: size = 0 OK 2026/07/02 03:24:32 DEBUG : only19: Dst hash empty - aborting Src hash check 2026/07/02 03:24:32 INFO : only19: Copied (replaced existing) 2026/07/02 03:24:32 DEBUG : only1: size = 0 OK 2026/07/02 03:24:32 DEBUG : only1: Dst hash empty - aborting Src hash check 2026/07/02 03:24:32 INFO : only1: Copied (replaced existing) 2026/07/02 03:24:33 DEBUG : only2: size = 0 OK 2026/07/02 03:24:33 DEBUG : only2: Dst hash empty - aborting Src hash check 2026/07/02 03:24:33 INFO : only2: Copied (replaced existing) 2026/07/02 03:24:33 DEBUG : only3: size = 0 OK 2026/07/02 03:24:33 DEBUG : only3: Dst hash empty - aborting Src hash check 2026/07/02 03:24:33 INFO : only3: Copied (replaced existing) 2026/07/02 03:24:33 DEBUG : only4: size = 0 OK 2026/07/02 03:24:33 DEBUG : only4: Dst hash empty - aborting Src hash check 2026/07/02 03:24:33 INFO : only4: Copied (replaced existing) 2026/07/02 03:24:33 DEBUG : only5: size = 0 OK 2026/07/02 03:24:33 DEBUG : only5: Dst hash empty - aborting Src hash check 2026/07/02 03:24:33 INFO : only5: Copied (replaced existing) 2026/07/02 03:24:34 DEBUG : only6: size = 0 OK 2026/07/02 03:24:34 DEBUG : only6: Dst hash empty - aborting Src hash check 2026/07/02 03:24:34 INFO : only6: Copied (replaced existing) 2026/07/02 03:24:34 DEBUG : only7: size = 0 OK 2026/07/02 03:24:34 DEBUG : only7: Dst hash empty - aborting Src hash check 2026/07/02 03:24:34 INFO : only7: Copied (replaced existing) 2026/07/02 03:24:34 DEBUG : only8: size = 0 OK 2026/07/02 03:24:34 DEBUG : only8: Dst hash empty - aborting Src hash check 2026/07/02 03:24:34 INFO : only8: Copied (replaced existing) 2026/07/02 03:24:34 DEBUG : only9: size = 0 OK 2026/07/02 03:24:34 DEBUG : only9: Dst hash empty - aborting Src hash check 2026/07/02 03:24:34 INFO : only9: Copied (replaced existing) 2026/07/02 03:24:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (43.73s) PASS 2026/07/02 03:24:41 DEBUG : files root 'rclone-test-detomom1qoli': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCopyNoEmptyDirectories|TestServerSideCopyOverSelf|TestSyncBackupDirWithSuffix|TestSyncConcurrentTruncate|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes)$'" - Finished OK in 1m33.117288426s (try 2/5)