"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestSyncSetDelayedModTimes$'" - Starting (try 2/5) 2025/01/02 01:17:13 DEBUG : Creating backend with remote "TestGoFile:rclone-test-mimayim5xape" 2025/01/02 01:17:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/02 01:17:13 DEBUG : Creating backend with remote "/tmp/rclone2903880160" === RUN TestSyncSetDelayedModTimes run.go:180: Remote "gofile root 'rclone-test-mimayim5xape'", Local "Local file system at /tmp/rclone2903880160", Modify Window "1s" 2025/01/02 01:17:13 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:13 INFO : a1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:14 DEBUG : a1: Making directory 2025/01/02 01:17:15 INFO : a1: Made directory with modification time 2001-02-03 04:19:06.499999999 +0000 UTC 2025/01/02 01:17:15 DEBUG : Added delayed dir = "a1", newDst= 2025/01/02 01:17:15 DEBUG : a1/b1: Making directory 2025/01/02 01:17:16 INFO : a1/b1: Made directory with modification time 2001-02-03 04:18:06.499999999 +0000 UTC 2025/01/02 01:17:16 DEBUG : Added delayed dir = "a1/b1", newDst= 2025/01/02 01:17:16 DEBUG : a1/b2: Making directory 2025/01/02 01:17:17 INFO : a1/b2: Made directory with modification time 2001-02-03 04:09:06.499999999 +0000 UTC 2025/01/02 01:17:17 DEBUG : Added delayed dir = "a1/b2", newDst= 2025/01/02 01:17:17 DEBUG : a1/b2/c1: Making directory 2025/01/02 01:17:17 DEBUG : a1/b1/c1: Making directory 2025/01/02 01:17:17 INFO : a1/b2/c1: Made directory with modification time 2001-02-03 04:08:06.499999999 +0000 UTC 2025/01/02 01:17:17 DEBUG : Added delayed dir = "a1/b2/c1", newDst= 2025/01/02 01:17:17 DEBUG : a1/b2/c1/d1: Making directory 2025/01/02 01:17:18 INFO : a1/b1/c1: Made directory with modification time 2001-02-03 04:17:06.499999999 +0000 UTC 2025/01/02 01:17:18 DEBUG : Added delayed dir = "a1/b1/c1", newDst= 2025/01/02 01:17:18 DEBUG : a1/b1/c1/d1: Making directory 2025/01/02 01:17:19 INFO : a1/b2/c1/d1: Made directory with modification time 2001-02-03 04:07:06.499999999 +0000 UTC 2025/01/02 01:17:19 DEBUG : Added delayed dir = "a1/b2/c1/d1", newDst= 2025/01/02 01:17:19 DEBUG : a1/b2/c1/d1/e1: Making directory 2025/01/02 01:17:19 INFO : a1/b1/c1/d1: Made directory with modification time 2001-02-03 04:16:06.499999999 +0000 UTC 2025/01/02 01:17:19 DEBUG : Added delayed dir = "a1/b1/c1/d1", newDst= 2025/01/02 01:17:19 DEBUG : a1/b1/c1/d2: Making directory 2025/01/02 01:17:20 INFO : a1/b2/c1/d1/e1: Made directory with modification time 2001-02-03 04:06:06.499999999 +0000 UTC 2025/01/02 01:17:20 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1", newDst= 2025/01/02 01:17:20 DEBUG : a1/b2/c1/d1/e1/f1: Making directory 2025/01/02 01:17:20 DEBUG : Rate limited, sleep for 5s 2025/01/02 01:17:20 INFO : a1/b1/c1/d2: Made directory with modification time 2001-02-03 04:13:06.499999999 +0000 UTC 2025/01/02 01:17:20 DEBUG : Added delayed dir = "a1/b1/c1/d2", newDst= 2025/01/02 01:17:20 DEBUG : a1/b1/c1/d2/e1: Making directory 2025/01/02 01:17:20 DEBUG : a1/b1/c1/d1/e1: Making directory 2025/01/02 01:17:25 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit") 2025/01/02 01:17:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/01/02 01:17:26 DEBUG : pacer: Reducing sleep to 10ms 2025/01/02 01:17:26 INFO : a1/b2/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:05:06.499999999 +0000 UTC 2025/01/02 01:17:26 DEBUG : Added delayed dir = "a1/b2/c1/d1/e1/f1", newDst= 2025/01/02 01:17:27 INFO : a1/b1/c1/d2/e1: Made directory with modification time 2001-02-03 04:12:06.499999999 +0000 UTC 2025/01/02 01:17:27 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1", newDst= 2025/01/02 01:17:27 DEBUG : a1/b1/c1/d2/e1/f1: Making directory 2025/01/02 01:17:27 INFO : a1/b1/c1/d1/e1: Made directory with modification time 2001-02-03 04:15:06.499999999 +0000 UTC 2025/01/02 01:17:27 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1", newDst= 2025/01/02 01:17:27 DEBUG : a1/b1/c1/d1/e1/f1: Making directory 2025/01/02 01:17:28 INFO : a1/b1/c1/d2/e1/f1: Made directory with modification time 2001-02-03 04:11:06.499999999 +0000 UTC 2025/01/02 01:17:28 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f1", newDst= 2025/01/02 01:17:28 DEBUG : a1/b1/c1/d2/e1/f2: Making directory 2025/01/02 01:17:29 INFO : a1/b1/c1/d1/e1/f1: Made directory with modification time 2001-02-03 04:14:06.499999999 +0000 UTC 2025/01/02 01:17:29 DEBUG : Added delayed dir = "a1/b1/c1/d1/e1/f1", newDst= 2025/01/02 01:17:29 INFO : a1/b1/c1/d2/e1/f2: Made directory with modification time 2001-02-03 04:10:06.499999999 +0000 UTC 2025/01/02 01:17:29 DEBUG : Added delayed dir = "a1/b1/c1/d2/e1/f2", newDst= 2025/01/02 01:17:29 DEBUG : gofile root 'rclone-test-mimayim5xape': Waiting for checks to finish 2025/01/02 01:17:29 DEBUG : gofile root 'rclone-test-mimayim5xape': Waiting for transfers to finish 2025/01/02 01:17:29 DEBUG : Waiting for deletions to finish 2025/01/02 01:17:30 INFO : a1/b1/c1/d2/e1/f2: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b1/c1/d2/e1/f1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b2/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b1/c1/d1/e1/f1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b2/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b1/c1/d1/e1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b1/c1/d2/e1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b1/c1/d2: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b2/c1/d1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b1/c1/d1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b1/c1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:30 INFO : a1/b2/c1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:31 INFO : a1/b2: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:31 INFO : a1/b1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:31 INFO : a1: Set directory modification time (using DirSetModTime) 2025/01/02 01:17:31 INFO : There was nothing to transfer run.go:180: Remote "gofile root 'rclone-test-mimayim5xape'", Local "Local file system at /tmp/rclone2903880160", Modify Window "1s" run.go:125: removing dir "a1/b2/c1/d1/e1" failed - try 1/3: directory not empty --- PASS: TestSyncSetDelayedModTimes (35.85s) PASS 2025/01/02 01:17:49 DEBUG : gofile root 'rclone-test-mimayim5xape': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestSyncSetDelayedModTimes$'" - Finished OK in 36.679185129s (try 2/5)