"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestSyncBackupDir$'" - Starting (try 2/5)
2024/12/07 01:17:45 DEBUG : Creating backend with remote "TestGoFile:rclone-test-cihogas7zaze"
2024/12/07 01:17:45 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/07 01:17:45 DEBUG : Creating backend with remote "/tmp/rclone2927042785"
=== RUN   TestSyncBackupDir
    run.go:180: Remote "gofile root 'rclone-test-cihogas7zaze'", Local "Local file system at /tmp/rclone2927042785", Modify Window "1s"
2024/12/07 01:17:46 DEBUG : Upload server store-eu-par-1 (eu) responded in 125.135036ms
2024/12/07 01:17:46 DEBUG : Upload server store2 (eu) responded in 127.158171ms
2024/12/07 01:17:46 DEBUG : dst/one: Using upload server store2 (eu)
2024/12/07 01:17:47 DEBUG : dst/two: Using upload server store-eu-par-1 (eu)
2024/12/07 01:17:47 DEBUG : dst/three.txt: Using upload server store-eu-par-1 (eu)
2024/12/07 01:17:47 DEBUG : Creating backend with remote "TestGoFile:rclone-test-cihogas7zaze/dst"
2024/12/07 01:17:48 DEBUG : Creating backend with remote "TestGoFile:rclone-test-cihogas7zaze/backup"
2024/12/07 01:17:49 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/07 01:17:49 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/07 01:17:49 DEBUG : two: Unchanged skipping
2024/12/07 01:17:49 DEBUG : gofile root 'rclone-test-cihogas7zaze/dst': Waiting for checks to finish
2024/12/07 01:17:50 INFO  : one: Moved (server-side)
2024/12/07 01:17:50 DEBUG : gofile root 'rclone-test-cihogas7zaze/dst': Waiting for transfers to finish
2024/12/07 01:17:50 DEBUG : Upload server store5 (eu) responded in 126.532303ms
2024/12/07 01:17:50 DEBUG : Upload server store1 (eu) responded in 129.924122ms
2024/12/07 01:17:50 DEBUG : one: Using upload server store5 (eu)
2024/12/07 01:17:50 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2024/12/07 01:17:50 INFO  : one: Copied (new)
2024/12/07 01:17:50 DEBUG : Waiting for deletions to finish
2024/12/07 01:17:51 INFO  : three.txt: Moved (server-side)
2024/12/07 01:17:51 INFO  : three.txt: Moved into backup dir
2024/12/07 01:17:51 DEBUG : dst/three.txt: Using upload server store-eu-par-1 (eu)
2024/12/07 01:17:52 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/07 01:17:52 DEBUG : gofile root 'rclone-test-cihogas7zaze/dst': Waiting for checks to finish
2024/12/07 01:17:52 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/07 01:17:52 DEBUG : two: Unchanged skipping
2024/12/07 01:17:52 INFO  : one: Deleted
2024/12/07 01:17:53 INFO  : one: Moved (server-side)
2024/12/07 01:17:53 DEBUG : gofile root 'rclone-test-cihogas7zaze/dst': Waiting for transfers to finish
2024/12/07 01:17:53 DEBUG : one: Using upload server store5 (eu)
2024/12/07 01:17:53 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2024/12/07 01:17:53 INFO  : one: Copied (new)
2024/12/07 01:17:53 DEBUG : Waiting for deletions to finish
2024/12/07 01:17:54 INFO  : three.txt: Deleted
2024/12/07 01:17:54 DEBUG : Rate limited, sleep for 5s
2024/12/07 01:17:59 DEBUG : pacer: low level retry 1/10 (error Error "error-rateLimit")
2024/12/07 01:17:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/07 01:17:59 DEBUG : Rate limited, sleep for 5s
2024/12/07 01:18:04 DEBUG : pacer: low level retry 2/10 (error Error "error-rateLimit")
2024/12/07 01:18:04 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2024/12/07 01:18:04 DEBUG : pacer: Reducing sleep to 20ms
2024/12/07 01:18:04 INFO  : three.txt: Moved (server-side)
2024/12/07 01:18:04 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (21.66s)
PASS
2024/12/07 01:18:07 DEBUG : gofile root 'rclone-test-cihogas7zaze': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestSyncBackupDir$'" - Finished OK in 22.297915829s (try 2/5)