"./sync.test -test.v -test.timeout 1h0m0s -remote TestS3MinioEdge: -verbose -fast-list -test.run '^TestSyncBackupDirWithSuffixKeepExtension$'" - Starting (try 3/5) 2021/03/23 06:18:02 DEBUG : Creating backend with remote "TestS3MinioEdge:rclone-test-dogoboj1zolugof5cajigir2" 2021/03/23 06:18:02 DEBUG : TestS3MinioEdge: detected overridden config - adding "{4Z9wr}" suffix to name 2021/03/23 06:18:02 DEBUG : Creating backend with remote "/tmp/rclone063858469" === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "S3 bucket rclone-test-dogoboj1zolugof5cajigir2", Local "Local file system at /tmp/rclone063858469", Modify Window "1ns" 2021/03/23 06:18:02 DEBUG : pacer: low level retry 1/10 (error XMinioServerNotInitialized: Server not initialized, please try again. status code: 503, request id: 166EE440A4C621BD, host id: ) 2021/03/23 06:18:02 DEBUG : pacer: Rate limited, increasing sleep to 10ms 2021/03/23 06:18:02 DEBUG : pacer: low level retry 2/10 (error XMinioServerNotInitialized: Server not initialized, please try again. status code: 503, request id: 166EE440A519CBD3, host id: ) 2021/03/23 06:18:02 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/03/23 06:18:02 DEBUG : pacer: low level retry 3/10 (error XMinioServerNotInitialized: Server not initialized, please try again. status code: 503, request id: 166EE440A587781D, host id: ) 2021/03/23 06:18:02 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2021/03/23 06:18:02 DEBUG : pacer: low level retry 4/10 (error XMinioServerNotInitialized: Server not initialized, please try again. status code: 503, request id: 166EE440A724FE7F, host id: ) 2021/03/23 06:18:02 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2021/03/23 06:18:02 DEBUG : pacer: low level retry 5/10 (error XMinioServerNotInitialized: Server not initialized, please try again. status code: 503, request id: 166EE440AA6F7DFA, host id: ) 2021/03/23 06:18:02 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2021/03/23 06:18:02 DEBUG : pacer: low level retry 6/10 (error XMinioServerNotInitialized: Server not initialized, please try again. status code: 503, request id: 166EE440AE8C71F2, host id: ) 2021/03/23 06:18:02 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2021/03/23 06:18:02 DEBUG : pacer: low level retry 7/10 (error XMinioServerNotInitialized: Server not initialized, please try again. status code: 503, request id: 166EE440B7DD471A, host id: ) 2021/03/23 06:18:02 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2021/03/23 06:18:03 DEBUG : pacer: Reducing sleep to 480ms 2021/03/23 06:18:03 INFO : S3 bucket rclone-test-dogoboj1zolugof5cajigir2: Bucket "rclone-test-dogoboj1zolugof5cajigir2" created with ACL "private" 2021/03/23 06:18:03 DEBUG : pacer: Reducing sleep to 360ms 2021/03/23 06:18:04 DEBUG : pacer: Reducing sleep to 270ms 2021/03/23 06:18:04 DEBUG : pacer: Reducing sleep to 202.5ms 2021/03/23 06:18:04 DEBUG : pacer: Reducing sleep to 151.875ms 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 113.90625ms 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 85.429687ms 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 64.072265ms 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 48.054198ms 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 36.040648ms 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 27.030486ms 2021/03/23 06:18:05 DEBUG : Creating backend with remote "TestS3MinioEdge:rclone-test-dogoboj1zolugof5cajigir2/dst" 2021/03/23 06:18:05 DEBUG : TestS3MinioEdge: detected overridden config - adding "{4Z9wr}" suffix to name 2021/03/23 06:18:05 DEBUG : Creating backend with remote "TestS3MinioEdge:rclone-test-dogoboj1zolugof5cajigir2/backup" 2021/03/23 06:18:05 DEBUG : TestS3MinioEdge: detected overridden config - adding "{4Z9wr}" suffix to name 2021/03/23 06:18:05 DEBUG : fs cache: renaming cache item "TestS3MinioEdge:rclone-test-dogoboj1zolugof5cajigir2/backup" to be canonical "TestS3MinioEdge{4Z9wr}:rclone-test-dogoboj1zolugof5cajigir2/backup" 2021/03/23 06:18:05 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 06:18:05 DEBUG : S3 bucket rclone-test-dogoboj1zolugof5cajigir2 path dst: Waiting for checks to finish 2021/03/23 06:18:05 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 06:18:05 DEBUG : two: Unchanged skipping 2021/03/23 06:18:05 DEBUG : one: MD5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2021/03/23 06:18:05 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 06:18:05 INFO : one: Deleted 2021/03/23 06:18:05 DEBUG : S3 bucket rclone-test-dogoboj1zolugof5cajigir2 path dst: Waiting for transfers to finish 2021/03/23 06:18:05 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2021/03/23 06:18:05 INFO : one: Copied (new) 2021/03/23 06:18:05 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:05 DEBUG : three.txt: MD5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2021/03/23 06:18:05 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 06:18:05 INFO : three.txt: Deleted 2021/03/23 06:18:05 INFO : three.txt: Moved into backup dir 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 20.272864ms 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 15.204648ms 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 11.403486ms 2021/03/23 06:18:05 DEBUG : pacer: Reducing sleep to 0s 2021/03/23 06:18:05 DEBUG : fs cache: switching user supplied name "TestS3MinioEdge:rclone-test-dogoboj1zolugof5cajigir2/backup" for canonical name "TestS3MinioEdge{4Z9wr}:rclone-test-dogoboj1zolugof5cajigir2/backup" 2021/03/23 06:18:05 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 06:18:05 DEBUG : S3 bucket rclone-test-dogoboj1zolugof5cajigir2 path dst: Waiting for checks to finish 2021/03/23 06:18:05 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 06:18:05 DEBUG : two: Unchanged skipping 2021/03/23 06:18:05 DEBUG : one: MD5 = c7957179c41f69d44f217a108c7915d8 OK 2021/03/23 06:18:05 INFO : one: Copied (server-side copy) to: one-2019-01-01 2021/03/23 06:18:05 INFO : one: Deleted 2021/03/23 06:18:05 DEBUG : S3 bucket rclone-test-dogoboj1zolugof5cajigir2 path dst: Waiting for transfers to finish 2021/03/23 06:18:05 DEBUG : one: MD5 = 0f93e81041f0cab37c37a05ae998b219 OK 2021/03/23 06:18:05 INFO : one: Copied (new) 2021/03/23 06:18:05 DEBUG : Waiting for deletions to finish 2021/03/23 06:18:05 DEBUG : three.txt: MD5 = 91341eed84691a83caea73aa785736d5 OK 2021/03/23 06:18:05 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2021/03/23 06:18:05 INFO : three.txt: Deleted 2021/03/23 06:18:05 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (3.24s) PASS 2021/03/23 06:18:05 DEBUG : S3 bucket rclone-test-dogoboj1zolugof5cajigir2: Purge dir "" 2021/03/23 06:18:05 INFO : S3 bucket rclone-test-dogoboj1zolugof5cajigir2: Bucket "rclone-test-dogoboj1zolugof5cajigir2" deleted "./sync.test -test.v -test.timeout 1h0m0s -remote TestS3MinioEdge: -verbose -fast-list -test.run '^TestSyncBackupDirWithSuffixKeepExtension$'" - Finished OK in 3.384094936s (try 3/5)