"./operations.test -test.v -test.timeout 1h0m0s -remote TestS3MinioEdge: -verbose -test.run '^TestMultithreadCopy$/^{size:131071_streams:2}$'" - Starting (try 2/5)
2021/06/01 05:03:34 DEBUG : Creating backend with remote "TestS3MinioEdge:rclone-test-wiximet4zaqujal1coteyep2"
2021/06/01 05:03:34 DEBUG : TestS3MinioEdge: detected overridden config - adding "{Dqa1-}" suffix to name
2021/06/01 05:03:34 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2021/06/01 05:03:34 DEBUG : Creating backend with remote "/tmp/rclone637411646"
=== RUN   TestMultithreadCopy
    run.go:176: Remote "S3 bucket rclone-test-wiximet4zaqujal1coteyep2", Local "Local file system at /tmp/rclone637411646", Modify Window "1ns"
=== RUN   TestMultithreadCopy/{size:131071_streams:2}
2021/06/01 05:03:34 DEBUG : pacer: low level retry 1/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 16845CD003D70F74, host id: )
2021/06/01 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 10ms
2021/06/01 05:03:34 DEBUG : pacer: low level retry 2/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 16845CD005C10E94, host id: )
2021/06/01 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/06/01 05:03:34 DEBUG : pacer: low level retry 3/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 16845CD0063DAAA3, host id: )
2021/06/01 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2021/06/01 05:03:34 DEBUG : pacer: low level retry 4/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 16845CD00712E659, host id: )
2021/06/01 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2021/06/01 05:03:34 DEBUG : pacer: low level retry 5/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 16845CD00B66C589, host id: )
2021/06/01 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2021/06/01 05:03:34 DEBUG : pacer: low level retry 6/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 16845CD0125066AB, host id: )
2021/06/01 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2021/06/01 05:03:34 DEBUG : pacer: low level retry 7/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 16845CD0194827B0, host id: )
2021/06/01 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2021/06/01 05:03:34 DEBUG : pacer: low level retry 8/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 16845CD02B864509, host id: )
2021/06/01 05:03:34 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2021/06/01 05:03:35 DEBUG : pacer: low level retry 9/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 16845CD052979D68, host id: )
2021/06/01 05:03:35 DEBUG : pacer: Rate limited, increasing sleep to 2s
2021/06/01 05:03:36 DEBUG : pacer: Reducing sleep to 1.5s
2021/06/01 05:03:36 INFO  : S3 bucket rclone-test-wiximet4zaqujal1coteyep2: Bucket "rclone-test-wiximet4zaqujal1coteyep2" created with ACL "private"
2021/06/01 05:03:38 DEBUG : pacer: Reducing sleep to 1.125s
2021/06/01 05:03:40 DEBUG : pacer: Reducing sleep to 843.75ms
2021/06/01 05:03:41 DEBUG : pacer: Reducing sleep to 632.8125ms
2021/06/01 05:03:42 DEBUG : pacer: Reducing sleep to 474.609375ms
2021/06/01 05:03:42 DEBUG : pacer: Reducing sleep to 355.957031ms
2021/06/01 05:03:42 DEBUG : file1: Starting multi-thread copy with 2 parts of size 64Ki
2021/06/01 05:03:42 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki starting
2021/06/01 05:03:42 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki starting
2021/06/01 05:03:43 DEBUG : pacer: Reducing sleep to 266.967773ms
2021/06/01 05:03:43 DEBUG : file1: multi-thread copy: stream 2/2 (65536-131071) size 63.999Ki finished
2021/06/01 05:03:43 DEBUG : pacer: Reducing sleep to 200.225829ms
2021/06/01 05:03:43 DEBUG : file1: multi-thread copy: stream 1/2 (0-65536) size 64Ki finished
2021/06/01 05:03:43 DEBUG : file1: Finished multi-thread copy with 2 parts of size 64Ki
2021/06/01 05:03:44 DEBUG : pacer: Reducing sleep to 150.169371ms
2021/06/01 05:03:44 DEBUG : pacer: Reducing sleep to 112.627028ms
2021/06/01 05:03:44 DEBUG : pacer: Reducing sleep to 84.470271ms
--- PASS: TestMultithreadCopy (10.21s)
    --- PASS: TestMultithreadCopy/{size:131071_streams:2} (9.62s)
PASS
2021/06/01 05:03:44 DEBUG : pacer: Reducing sleep to 63.352703ms
2021/06/01 05:03:44 DEBUG : S3 bucket rclone-test-wiximet4zaqujal1coteyep2: Purge dir ""
2021/06/01 05:03:44 DEBUG : pacer: Reducing sleep to 47.514527ms
2021/06/01 05:03:44 INFO  : S3 bucket rclone-test-wiximet4zaqujal1coteyep2: Bucket "rclone-test-wiximet4zaqujal1coteyep2" deleted
"./operations.test -test.v -test.timeout 1h0m0s -remote TestS3MinioEdge: -verbose -test.run '^TestMultithreadCopy$/^{size:131071_streams:2}$'" - Finished OK in 10.596502996s (try 2/5)