I’m pretty new to rclone so apologies if I’m making any obvious mistakes.
The problem is that the cache backend is very slow for me, while direct (unwrapped) remote downloads are very fast. In the debug output I see a lot of “chunk retry storage: 0, 1, 2, …” messages which causes network traffic to stall.
Downloads via the (empty) cache are never above 5MB/s, while those direct are always at least 20MB/s.
I’m not using crypt, nor mount for these tests. Just plain copy commands.
- Rclone version: 1.42. Tested both in Windows and Arch Linux.
- Bandwidth: tested 1 Gbps dedicated server and 200 Mbps line at work.
- Cloud provider: Google Drive
Rclone.conf:
[gdrive]
type = drive
client_id =
client_secret =
scope = drive
root_folder_id =
service_account_file =
token = {"access_token":"...","token_type":"Bearer","refresh_token":"...","expiry":"2018-07-26T21:44:38.185665219+02:00"}
[gcache]
type = cache
remote = gdrive:
plex_url =
plex_username =
plex_password =
chunk_size =
info_age =
chunk_total_size = 2G
[gdrive-test]
type = alias
remote = gdrive:test
[gcache-test]
type = alias
remote = gcache:test
Direct download:
$ rclone copy gdrive-test:vietnam.mkv . -v
2018/07/26 21:33:45 DEBUG : rclone: Version "v1.42" starting with parameters ["rclone" "-v" "copy" "gdrive-test:vietnam.mkv" "." "-v"]
2018/07/26 21:33:45 DEBUG : Using config file from "/home/kunio/.config/rclone/rclone.conf"
2018/07/26 21:33:45 DEBUG : pacer: Rate limited, sleeping for 1.991937468s (1 consecutive low level retries)
2018/07/26 21:33:45 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:33:45 DEBUG : pacer: Rate limited, sleeping for 2.885713156s (2 consecutive low level retries)
2018/07/26 21:33:45 DEBUG : pacer: low level retry 2/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:33:50 DEBUG : pacer: Resetting sleep to minimum 10ms on success
2018/07/26 21:33:52 DEBUG : vietnam.mkv: Couldn't find file - need to transfer
2018/07/26 21:33:56 INFO : vietnam.mkv: Copied (new)
2018/07/26 21:33:56 INFO :
Transferred: 390.325 MBytes (34.849 MBytes/s)
Errors: 0
Checks: 0
Transferred: 1
Elapsed time: 11.2s
2018/07/26 21:33:56 DEBUG : 6 go routines active
2018/07/26 21:33:56 DEBUG : rclone: Version "v1.42" finishing with parameters ["rclone" "-v" "copy" "gdrive-test:vietnam.mkv" "." "-v"]
Download via empty cache:
The full output was too long for this post, so I put it up on Pastebin: https://pastebin.com/beWVFjQk
However, here’s an excerpt:
2018/07/26 21:04:13 DEBUG : test/vietnam.mkv: 256901120: chunk retry storage: 0
2018/07/26 21:04:13 DEBUG : worker-3 <vietnam.mkv>: downloaded chunk 256901120
2018/07/26 21:04:13 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 0
2018/07/26 21:04:14 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 1
2018/07/26 21:04:14 DEBUG : pacer: Rate limited, sleeping for 1.243364331s (1 consecutive low level retries)
2018/07/26 21:04:14 DEBUG : pacer: low level retry 3/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:14 DEBUG : pacer: Rate limited, sleeping for 2.34956455s (2 consecutive low level retries)
2018/07/26 21:04:14 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:14 DEBUG : pacer: Rate limited, sleeping for 4.657515688s (3 consecutive low level retries)
2018/07/26 21:04:14 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:14 DEBUG : pacer: Rate limited, sleeping for 8.836516241s (4 consecutive low level retries)
2018/07/26 21:04:14 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:14 DEBUG : pacer: Rate limited, sleeping for 16.7914385s (5 consecutive low level retries)
2018/07/26 21:04:14 DEBUG : pacer: low level retry 4/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:14 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 2
2018/07/26 21:04:15 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 3
2018/07/26 21:04:15 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 4
2018/07/26 21:04:16 DEBUG : pacer: Resetting sleep to minimum 10ms on success
2018/07/26 21:04:16 DEBUG : worker-0 <vietnam.mkv>: downloaded chunk 272629760
2018/07/26 21:04:16 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 5
2018/07/26 21:04:16 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 6
2018/07/26 21:04:17 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 7
2018/07/26 21:04:17 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 8
2018/07/26 21:04:18 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 9
2018/07/26 21:04:18 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 10
2018/07/26 21:04:19 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 11
2018/07/26 21:04:19 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 12
2018/07/26 21:04:20 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 13
2018/07/26 21:04:20 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 14
2018/07/26 21:04:21 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 15
2018/07/26 21:04:21 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 16
2018/07/26 21:04:22 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 17
2018/07/26 21:04:22 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 18
2018/07/26 21:04:23 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 19
2018/07/26 21:04:23 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 20
2018/07/26 21:04:24 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 21
2018/07/26 21:04:24 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 22
2018/07/26 21:04:25 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 23
2018/07/26 21:04:25 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 24
2018/07/26 21:04:26 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 25
2018/07/26 21:04:26 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 26
2018/07/26 21:04:27 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 27
2018/07/26 21:04:27 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 28
2018/07/26 21:04:28 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 29
2018/07/26 21:04:28 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 30
2018/07/26 21:04:29 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 31
2018/07/26 21:04:29 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 32
2018/07/26 21:04:30 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 33
2018/07/26 21:04:30 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 34
2018/07/26 21:04:31 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 35
2018/07/26 21:04:31 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 36
2018/07/26 21:04:32 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 37
2018/07/26 21:04:32 DEBUG : test/vietnam.mkv: 267386880: chunk retry storage: 38
2018/07/26 21:04:33 DEBUG : worker-1 <vietnam.mkv>: downloaded chunk 277872640
2018/07/26 21:04:33 DEBUG : worker-2 <vietnam.mkv>: downloaded chunk 267386880
2018/07/26 21:04:33 DEBUG : worker-3 <vietnam.mkv>: downloaded chunk 283115520
2018/07/26 21:04:33 DEBUG : test/vietnam.mkv: 288358400: chunk retry storage: 0
2018/07/26 21:04:33 DEBUG : pacer: Rate limited, sleeping for 1.0964908s (1 consecutive low level retries)
2018/07/26 21:04:33 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:33 DEBUG : pacer: Rate limited, sleeping for 2.408862967s (2 consecutive low level retries)
2018/07/26 21:04:33 DEBUG : pacer: low level retry 2/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:33 DEBUG : pacer: Resetting sleep to minimum 10ms on success
2018/07/26 21:04:33 DEBUG : worker-2 <vietnam.mkv>: downloaded chunk 298844160
2018/07/26 21:04:33 DEBUG : worker-0 <vietnam.mkv>: downloaded chunk 288358400
2018/07/26 21:04:33 DEBUG : worker-1 <vietnam.mkv>: downloaded chunk 293601280
2018/07/26 21:04:33 DEBUG : test/vietnam.mkv: 304087040: chunk retry storage: 0
2018/07/26 21:04:34 DEBUG : test/vietnam.mkv: 304087040: chunk retry storage: 1
2018/07/26 21:04:34 DEBUG : pacer: Rate limited, sleeping for 1.843628418s (1 consecutive low level retries)
2018/07/26 21:04:34 DEBUG : pacer: low level retry 3/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:34 DEBUG : pacer: Rate limited, sleeping for 2.819349828s (2 consecutive low level retries)
2018/07/26 21:04:34 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:34 DEBUG : pacer: Rate limited, sleeping for 4.944461911s (3 consecutive low level retries)
2018/07/26 21:04:34 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:34 DEBUG : pacer: Rate limited, sleeping for 8.802814445s (4 consecutive low level retries)
2018/07/26 21:04:34 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:34 DEBUG : pacer: Rate limited, sleeping for 16.413970945s (5 consecutive low level retries)
2018/07/26 21:04:34 DEBUG : pacer: low level retry 4/10 (error googleapi: Error 403: Rate Limit Exceeded, rateLimitExceeded)
2018/07/26 21:04:34 DEBUG : test/vietnam.mkv: 304087040: chunk retry storage: 2
2018/07/26 21:04:35 DEBUG : test/vietnam.mkv: 304087040: chunk retry storage: 3
2018/07/26 21:04:35 DEBUG : test/vietnam.mkv: 304087040: chunk retry storage: 4
2018/07/26 21:04:36 DEBUG : test/vietnam.mkv: 304087040: chunk retry storage: 5
2018/07/26 21:04:36 DEBUG : pacer: Resetting sleep to minimum 10ms on success
2018/07/26 21:04:36 DEBUG : worker-2 <vietnam.mkv>: downloaded chunk 309329920
2018/07/26 21:05:21 INFO :
Transferred: 390.325 MBytes (4.179 MBytes/s)
Errors: 0
Checks: 0
Transferred: 1
Elapsed time: 1m33.4s