VPS Plex Server with Cache not performing well


#1

I have three Plex Servers

  • One on a local Xeon E5-2670 dual CPU server running Unraid OS and utilising Rclone Cache
  • One on a high spec’d SSDnodes VPS, using Rlcone Cache. Gigabit connection
  • and one low spec’d VPS using Plexdrive. Gigabit connection

All Plexservers connect to the same Google Drive.

Since switching the local server and SSDnodes VPS over to Rclone Cache from Plexdrive I have had a lot of problems playing media files on my Nvidia Shield TV. It often takes a long time to start and once it does it frequently pauses and flashes up messages saying that the connection to the server is not fast enough for the current bitrate.

If I play the same file from the low spec’d VPS using Plexdrive then it starts play quickly and has no issues with freezing or messages. This is really frustrating as I want to keep using Rclone Cache for all of the other benefits. I’m hoping i’ve missed something simple.

My internet connection is 200Mbit down and 20 up.

Cache Remote

[GdriveCache]
type = cache
remote = google:encrypted
plex_url =
plex_username = email@gmail.com
plex_password = *** ENCRYPTED ***
chunk_size = 5M
info_age = 6h
chunk_total_size = 10G

(i never see a Plex integration token)

My systemd

[Unit]
Description=Mount local-crypt to /mnt/local-crypt
After=syslog.target local-fs.target network.target plexdrive.service
[Service]
Type=simple
User=root
ExecStartPre=-/bin/mkdir /mnt/local-crypt
ExecStart=/usr/bin/rclone mount
–config /home/nodeuser/.config/rclone/rclone.conf
–allow-other
–allow-non-empty
–dir-cache-time=1m
–buffer-size=0M
–cache-info-age 8h
–cache-writes
–cache-workers=5
–cache-chunk-size=5M
–attr-timeout=1s
–log-level DEBUG
–log-file=/home/nodeuser/log/rclonecache.log
local-crypt: /mnt/local-crypt
ExecStop=/bin/fusermount -u -z /mnt/local-crypt
ExecStop=/bin/rmdir /mnt/local-crypt
Restart=always
[Install]
WantedBy=multi-user.target

Here is some of the the Rclone Cache log file for the SSDnodes VPS. I don’t know what to look for in the logs.

2018/04/10 11:46:06 DEBUG : rclone: Version “v1.40-056-g06e3fa3aβ” starting with parameters ["/usr/bin/rclone" “mount” “–config” “/home/nodeuser/.config/rclone/rclone.conf” “–allow-other” “$
2018/04/10 11:46:06 DEBUG : Using config file from “/home/nodeuser/.config/rclone/rclone.conf”
2018/04/10 11:46:07 DEBUG : GdriveCache: wrapped google:encrypted at root
2018/04/10 11:46:07 INFO : GdriveCache: Cache DB path: /root/.cache/rclone/cache-backend/GdriveCache.db
2018/04/10 11:46:07 INFO : GdriveCache: Cache chunk path: /root/.cache/rclone/cache-backend/GdriveCache
2018/04/10 11:46:07 INFO : GdriveCache: Chunk Memory: true
2018/04/10 11:46:07 INFO : GdriveCache: Chunk Size: 5M
2018/04/10 11:46:07 INFO : GdriveCache: Chunk Total Size: 10G
2018/04/10 11:46:07 INFO : GdriveCache: Chunk Clean Interval: 1m0s
2018/04/10 11:46:07 INFO : GdriveCache: Workers: 5
2018/04/10 11:46:07 INFO : GdriveCache: File Age: 8h0m0s
2018/04/10 11:46:07 INFO : GdriveCache: Cache Writes: enabled
2018/04/10 11:46:07 DEBUG : Adding path “cache/expire” to remote control registry
2018/04/10 11:46:07 DEBUG : Adding path “cache/stats” to remote control registry
2018/04/10 11:46:08 DEBUG : Google drive root ‘encrypted’: Checking for changes on remote
2018/04/10 11:46:08 DEBUG : GdriveCache: wrapped google:encrypted at root
2018/04/10 11:46:08 INFO : GdriveCache: Cache DB path: /root/.cache/rclone/cache-backend/GdriveCache.db
2018/04/10 11:46:08 INFO : GdriveCache: Cache chunk path: /root/.cache/rclone/cache-backend/GdriveCache
2018/04/10 11:46:08 INFO : GdriveCache: Chunk Memory: true
2018/04/10 11:46:08 INFO : GdriveCache: Chunk Size: 5M
2018/04/10 11:46:08 INFO : GdriveCache: Chunk Total Size: 10G
2018/04/10 11:46:08 INFO : GdriveCache: Chunk Clean Interval: 1m0s
2018/04/10 11:46:08 INFO : GdriveCache: Workers: 5
2018/04/10 11:46:08 INFO : GdriveCache: File Age: 8h0m0s
2018/04/10 11:46:08 INFO : GdriveCache: Cache Writes: enabled
2018/04/10 11:46:08 DEBUG : Adding path “cache/expire” to remote control registry
2018/04/10 11:46:08 DEBUG : Adding path “cache/stats” to remote control registry
2018/04/10 11:46:08 INFO : Encrypted drive ‘local-crypt:’: Modify window is 1ms
2018/04/10 11:46:08 DEBUG : Encrypted drive ‘local-crypt:’: Mounting on “/mnt/local-crypt”
2018/04/10 11:46:08 DEBUG : Cache remote GdriveCache:: subscribing to ChangeNotify
2018/04/10 11:46:08 DEBUG : Encrypted drive ‘local-crypt:’: Mounting on “/mnt/local-crypt”
2018/04/10 11:46:08 DEBUG : Cache remote GdriveCache:: subscribing to ChangeNotify
2018/04/10 11:46:08 DEBUG : vfs cache root is “/root/.cache/rclone/vfs/local-crypt”
2018/04/10 11:46:08 DEBUG : Adding path “vfs/forget” to remote control registry
2018/04/10 11:46:08 DEBUG : : Root:
2018/04/10 11:46:08 DEBUG : : >Root: node=/, err=
2018/04/10 11:46:08 DEBUG : Google drive root ‘encrypted’: Checking for changes on remote
2018/04/10 11:46:08 DEBUG : Google drive root ‘encrypted’: All changes were processed. Waiting for more.
2018/04/10 11:46:08 DEBUG : Google drive root ‘encrypted’: All changes were processed. Waiting for more.
2018/04/10 11:46:09 DEBUG : /: Lookup: name=“movies”
2018/04/10 11:46:09 DEBUG : Cache remote GdriveCache:: list ‘’
2018/04/10 11:46:09 DEBUG : : list: warm 9 from cache for: , expiring on: 2018-04-10 17:57:27.240949583 -0500 CDT
2018/04/10 11:46:09 DEBUG : : list: cached entries: [0b0eoji9ojtpaqjulpche5cgak 15bloj97nim84m4hcr0rc8af6s 6vf88h3rjl9c33eck7gqkgacf8 8k67oeh5icn1s03pg7eqjb32n4 nihqtcoevpbalc2cm3o8o5jbvl87io$
2018/04/10 11:46:09 DEBUG : /: >Lookup: node=movies/, err=
2018/04/10 11:46:09 DEBUG : movies/: Attr:
2018/04/10 11:46:09 DEBUG : movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:46:09 DEBUG : movies/: Lookup: name=”.grab"
2018/04/10 11:46:09 DEBUG : Cache remote GdriveCache:: list ‘15bloj97nim84m4hcr0rc8af6s’
2018/04/10 11:46:10 DEBUG : 15bloj97nim84m4hcr0rc8af6s: list: warm 668 from cache for: 15bloj97nim84m4hcr0rc8af6s, expiring on: 2018-04-10 17:57:27.239238458 -0500 CDT
2018/04/10 11:46:10 DEBUG : 15bloj97nim84m4hcr0rc8af6s: list: cached entries: [15bloj97nim84m4hcr0rc8af6s/01nvbdh7pghhr5k1jn99n77jpgh01dgg7ved8f3fh3f9vs3hsba0 15bloj97nim84m4hcr0rc8af6s/05n96$
2018/04/10 11:46:10 DEBUG : movies/: >Lookup: node=, err=no such file or directory
2018/04/10 11:46:10 DEBUG : /: Lookup: name=“tv”
2018/04/10 11:46:10 DEBUG : /: >Lookup: node=tv/, err=
2018/04/10 11:46:10 DEBUG : tv/: Attr:
2018/04/10 11:46:10 DEBUG : tv/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:46:10 DEBUG : tv/: Lookup: name=".grab"
2018/04/10 11:46:10 DEBUG : Cache remote GdriveCache:: list ‘une67pf5ihss3ktc40cumrbslg’
2018/04/10 11:46:10 DEBUG : une67pf5ihss3ktc40cumrbslg: list: warm 171 from cache for: une67pf5ihss3ktc40cumrbslg, expiring on: 2018-04-10 18:07:28.272211384 -0500 CDT
2018/04/10 11:46:10 DEBUG : une67pf5ihss3ktc40cumrbslg: list: cached entries: [une67pf5ihss3ktc40cumrbslg/01ll9ul028u9p8ivi2oh80sol8 une67pf5ihss3ktc40cumrbslg/0d2kd98qn4fkvodamfs99rfc1g une6$
2018/04/10 11:46:10 DEBUG : tv/: >Lookup: node=, err=no such file or directory
2018/04/10 11:46:10 DEBUG : une67pf5ihss3ktc40cumrbslg: list: cached entries: [une67pf5ihss3ktc40cumrbslg/01ll9ul028u9p8ivi2oh80sol8 une67pf5ihss3ktc40cumrbslg/0d2kd98qn4fkvodamfs99rfc1g une6$
2018/04/10 11:46:10 DEBUG : tv/: >Lookup: node=, err=no such file or directory
2018/04/10 11:47:07 DEBUG : Cache remote GdriveCache:: starting cleanup
2018/04/10 11:47:08 DEBUG : Cache remote GdriveCache:: starting cleanup
2018/04/10 11:47:08 DEBUG : Google drive root ‘encrypted’: Checking for changes on remote
2018/04/10 11:47:08 DEBUG : Google drive root ‘encrypted’: Checking for changes on remote
2018/04/10 11:47:08 DEBUG : Google drive root ‘encrypted’: All changes were processed. Waiting for more.
2018/04/10 11:47:08 DEBUG : Google drive root ‘encrypted’: All changes were processed. Waiting for more.
2018/04/10 11:48:08 DEBUG : Google drive root ‘encrypted’: Checking for changes on remote
2018/04/10 11:48:08 DEBUG : Cache remote GdriveCache:: starting cleanup
2018/04/10 11:48:08 DEBUG : Cache remote GdriveCache:: starting cleanup
2018/04/10 11:48:08 DEBUG : Google drive root ‘encrypted’: Checking for changes on remote
2018/04/10 11:48:08 DEBUG : Google drive root ‘encrypted’: All changes were processed. Waiting for more.
2018/04/10 11:48:08 DEBUG : Google drive root ‘encrypted’: All changes were processed. Waiting for more.
2018/04/10 11:48:51 DEBUG : /: Lookup: name=“movies”
2018/04/10 11:48:51 DEBUG : : Re-reading directory (2m41.669202624s old)
2018/04/10 11:48:51 DEBUG : Cache remote GdriveCache:: list ‘’
2018/04/10 11:48:51 DEBUG : : list: warm 9 from cache for: , expiring on: 2018-04-10 17:57:27.240949583 -0500 CDT
2018/04/10 11:48:51 DEBUG : : list: cached entries: [0b0eoji9ojtpaqjulpche5cgak 15bloj97nim84m4hcr0rc8af6s 6vf88h3rjl9c33eck7gqkgacf8 8k67oeh5icn1s03pg7eqjb32n4 nihqtcoevpbalc2cm3o8o5jbvl87io$
2018/04/10 11:48:51 DEBUG : /: >Lookup: node=movies/, err=
2018/04/10 11:48:51 DEBUG : movies/: Attr:
2018/04/10 11:48:51 DEBUG : movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:48:51 DEBUG : /: Lookup: name=“movies”
2018/04/10 11:48:51 DEBUG : /: >Lookup: node=movies/, err=
2018/04/10 11:48:51 DEBUG : movies/: Attr:
2018/04/10 11:48:51 DEBUG : movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:48:51 DEBUG : movies/: Lookup: name=“How to Train your Dragon 2 (2014)”
2018/04/10 11:48:51 DEBUG : movies: Re-reading directory (2m41.64976201s old)
2018/04/10 11:48:51 DEBUG : Cache remote GdriveCache:: list ‘15bloj97nim84m4hcr0rc8af6s’
2018/04/10 11:48:51 DEBUG : 15bloj97nim84m4hcr0rc8af6s: list: warm 668 from cache for: 15bloj97nim84m4hcr0rc8af6s, expiring on: 2018-04-10 17:57:27.239238458 -0500 CDT
2018/04/10 11:48:51 DEBUG : Cache remote GdriveCache:: list ‘15bloj97nim84m4hcr0rc8af6s’
2018/04/10 11:48:51 DEBUG : 15bloj97nim84m4hcr0rc8af6s: list: warm 668 from cache for: 15bloj97nim84m4hcr0rc8af6s, expiring on: 2018-04-10 17:57:27.239238458 -0500 CDT
2018/04/10 11:48:51 DEBUG : 15bloj97nim84m4hcr0rc8af6s: list: cached entries: [15bloj97nim84m4hcr0rc8af6s/01nvbdh7pghhr5k1jn99n77jpgh01dgg7ved8f3fh3f9vs3hsba0 15bloj97nim84m4hcr0rc8af6s/05n96$
2018/04/10 11:48:51 DEBUG : movies/: >Lookup: node=movies/How to Train your Dragon 2 (2014)/, err=
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/: Attr:
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/: Lookup: name=“How to Train Your Dragon 2.mkv”
2018/04/10 11:48:51 DEBUG : Cache remote GdriveCache:: list ‘15bloj97nim84m4hcr0rc8af6s/q45b421hb24nr1o7srokc3one8863e2v6ptc9gdrhc79840fogtqugj5276qdgd3639ogbjo62hai’
2018/04/10 11:48:51 DEBUG : 15bloj97nim84m4hcr0rc8af6s/q45b421hb24nr1o7srokc3one8863e2v6ptc9gdrhc79840fogtqugj5276qdgd3639ogbjo62hai: list: warm 1 from cache for: 15bloj97nim84m4hcr0rc8af6s/q$
2018/04/10 11:48:51 DEBUG : 15bloj97nim84m4hcr0rc8af6s/q45b421hb24nr1o7srokc3one8863e2v6ptc9gdrhc79840fogtqugj5276qdgd3639ogbjo62hai: list: cached entries: [15bloj97nim84m4hcr0rc8af6s/q45b421$
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/: >Lookup: node=movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv, err=
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Attr:
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Attr: a=valid=1s ino=0 size=10736469786 mode=-rw-r–r--, err=
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Open: flags=OpenReadOnly
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Open: flags=O_RDONLY
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Open: fd=movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®, err$
2018/04/10 11:48:51 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Open: fh=&{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}, $
2018/04/10 11:48:51 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Flush:
2018/04/10 11:48:51 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Flush: err=
2018/04/10 11:48:51 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Release:
2018/04/10 11:48:51 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Release: err=
2018/04/10 11:48:55 DEBUG : /: Lookup: name=“movies”
2018/04/10 11:48:55 DEBUG : /: >Lookup: node=movies/, err=
2018/04/10 11:48:55 DEBUG : movies/: Attr:
2018/04/10 11:48:55 DEBUG : movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:48:55 DEBUG : /: Lookup: name=“movies”
2018/04/10 11:48:55 DEBUG : /: >Lookup: node=movies/, err=
2018/04/10 11:48:55 DEBUG : movies/: Attr:
2018/04/10 11:48:55 DEBUG : movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:48:55 DEBUG : movies/: Lookup: name=“How to Train your Dragon 2 (2014)”
2018/04/10 11:48:57 DEBUG : movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:48:57 DEBUG : /: Lookup: name=“movies”
2018/04/10 11:48:57 DEBUG : /: >Lookup: node=movies/, err=
2018/04/10 11:48:57 DEBUG : movies/: Attr:
2018/04/10 11:48:57 DEBUG : movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:48:57 DEBUG : movies/: Lookup: name=“How to Train your Dragon 2 (2014)”
2018/04/10 11:48:57 DEBUG : movies/: >Lookup: node=movies/How to Train your Dragon 2 (2014)/, err=
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/: Attr:
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/: Lookup: name=“How to Train Your Dragon 2.mkv”
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/: >Lookup: node=movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv, err=
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Attr:
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Attr: a=valid=1s ino=0 size=10736469786 mode=-rw-r–r--, err=
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Open: flags=OpenReadOnly
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Open: flags=O_RDONLY
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Open: fd=movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®, err$
2018/04/10 11:48:57 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Open: fh=&{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}, $
2018/04/10 11:48:57 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Read: len=131072, offset=0
2018/04/10 11:48:59 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Read: read=131072, err=
2018/04/10 11:48:59 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Read: len=131072, offset=131072
2018/04/10 11:48:59 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Read: read=131072, err=
2018/04/10 11:48:59 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Read: len=131072, offset=262144
2018/04/10 11:48:59 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Read: read=131072, err=
2018/04/10 11:48:59 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Read: len=131072, offset=393216
2018/04/10 11:48:59 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Read: read=131072, err=
2018/04/10 11:48:59 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Read: len=131072, offset=524288
2018/04/10 11:48:59 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Read: read=131072, err=

2018/04/10 11:51:46 DEBUG : 15bloj97nim84m4hcr0rc8af6s: list: warm 668 from cache for: 15bloj97nim84m4hcr0rc8af6s, expiring on: 2018-04-10 17:57:27.239238458 -0500 CDT
2018/04/10 11:51:46 DEBUG : 15bloj97nim84m4hcr0rc8af6s: list: cached entries: [15bloj97nim84m4hcr0rc8af6s/01nvbdh7pghhr5k1jn99n77jpgh01dgg7ved8f3fh3f9vs3hsba0 15bloj97nim84m4hcr0rc8af6s/05n96$
2018/04/10 11:51:46 DEBUG : movies/: >Lookup: node=movies/How to Train your Dragon 2 (2014)/, err=
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/: Attr:
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/: Lookup: name=“How to Train Your Dragon 2.mkv”
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014): Re-reading directory (2m54.779168743s old)
2018/04/10 11:51:46 DEBUG : Cache remote GdriveCache:: list ‘15bloj97nim84m4hcr0rc8af6s/q45b421hb24nr1o7srokc3one8863e2v6ptc9gdrhc79840fogtqugj5276qdgd3639ogbjo62hai’
2018/04/10 11:51:46 DEBUG : 15bloj97nim84m4hcr0rc8af6s/q45b421hb24nr1o7srokc3one8863e2v6ptc9gdrhc79840fogtqugj5276qdgd3639ogbjo62hai: list: warm 1 from cache for: 15bloj97nim84m4hcr0rc8af6s/q$
2018/04/10 11:51:46 DEBUG : 15bloj97nim84m4hcr0rc8af6s/q45b421hb24nr1o7srokc3one8863e2v6ptc9gdrhc79840fogtqugj5276qdgd3639ogbjo62hai: list: cached entries: [15bloj97nim84m4hcr0rc8af6s/q45b421$
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/: >Lookup: node=movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv, err=
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Attr:
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Attr: a=valid=1s ino=0 size=10736469786 mode=-rw-r–r--, err=
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Open: flags=OpenReadOnly
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Open: flags=O_RDONLY
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Open: fd=movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®, err$
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Open: fh=&{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}, $
2018/04/10 11:51:46 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Read: len=32768, offset=10736439296
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: ReadFileHandle.seek from 0 to 10736439296 (io.Seeker)
2018/04/10 11:51:46 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Flush:
2018/04/10 11:51:46 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Flush: err=
2018/04/10 11:51:46 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: Release:
2018/04/10 11:51:46 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: ReadFileHandle.Release closing
2018/04/10 11:51:47 DEBUG : 15bloj97nim84m4hcr0rc8af6s/q45b421hb24nr1o7srokc3one8863e2v6ptc9gdrhc79840fogtqugj5276qdgd3639ogbjo62hai/vppf6f6k8aid675l5bga6iica1jfn030qd42ohr0shj05f7bh7qg: cach$
2018/04/10 11:51:47 DEBUG : 15bloj97nim84m4hcr0rc8af6s/q45b421hb24nr1o7srokc3one8863e2v6ptc9gdrhc79840fogtqugj5276qdgd3639ogbjo62hai/vppf6f6k8aid675l5bga6iica1jfn030qd42ohr0shj05f7bh7qg: cach$
2018/04/10 11:51:47 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Release: err=
2018/04/10 11:51:47 DEBUG : 15bloj97nim84m4hcr0rc8af6s/q45b421hb24nr1o7srokc3one8863e2v6ptc9gdrhc79840fogtqugj5276qdgd3639ogbjo62hai/vppf6f6k8aid675l5bga6iica1jfn030qd42ohr0shj05f7bh7qg: movi$
2018/04/10 11:51:47 DEBUG : &{movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv ®}: >Read: read=30490, err=
2018/04/10 11:51:47 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: Attr:
2018/04/10 11:51:47 DEBUG : movies/How to Train your Dragon 2 (2014)/How to Train Your Dragon 2.mkv: >Attr: a=valid=1s ino=0 size=10736469786 mode=-rw-r–r--, err=


#2

If I’m not mistaken, I’m not seeing any of the cache-workers actually there when it starts playback, which would make sense why things would be slow.

Is your plex URL missing or not included on purpose? You can’t use HTTPS for that currently and need to use the HTTP connection, but I’m not sure if you are local or not. I’m guessing if you remove the plex stuff, you’ll see the 5 workers actually kick in.

You normally see something like:

Apr  2 08:39:23 gemini rclone[11122]: worker-6 <p6o0jrkhoto752rvpps5ddf6cmtbfufu9a9k89bk7d57lg5qbeljsh10d6c0a0p61fvcq45c1a4li>: downloaded chunk 618659840
Apr  2 08:39:23 gemini rclone[11122]: worker-3 <p6o0jrkhoto752rvpps5ddf6cmtbfufu9a9k89bk7d57lg5qbeljsh10d6c0a0p61fvcq45c1a4li>: downloaded chunk 608174080
Apr  2 08:39:23 gemini rclone[11122]: worker-4 <p6o0jrkhoto752rvpps5ddf6cmtbfufu9a9k89bk7d57lg5qbeljsh10d6c0a0p61fvcq45c1a4li>: downloaded chunk 587202560
Apr  2 08:39:23 gemini rclone[11122]: worker-1 <p6o0jrkhoto752rvpps5ddf6cmtbfufu9a9k89bk7d57lg5qbeljsh10d6c0a0p61fvcq45c1a4li>: downloaded chunk 639631360
Apr  2 08:39:23 gemini rclone[11122]: worker-5 <p6o0jrkhoto752rvpps5ddf6cmtbfufu9a9k89bk7d57lg5qbeljsh10d6c0a0p61fvcq45c1a4li>: downloaded chunk 660602880
Apr  2 08:39:23 gemini rclone[11122]: worker-7 <p6o0jrkhoto752rvpps5ddf6cmtbfufu9a9k89bk7d57lg5qbeljsh10d6c0a0p61fvcq45c1a4li>: downloaded chunk 650117120
Apr  2 08:39:23 gemini rclone[11122]: worker-2 <p6o0jrkhoto752rvpps5ddf6cmtbfufu9a9k89bk7d57lg5qbeljsh10d6c0a0p61fvcq45c1a4li>: downloaded chunk 597688320

That’s an older log for me, but you get the idea as I only have 5 configured.


#3

I have tried it with the IP address of the server, local host and nothing and it doesn’t ever get it to grab a plex token. My password does have a symbol in it would this matter?

Oddly I can play from my local Plex server onto a Win10 Plex Media Player and its perfect including fast forwarding. From the same Windows 10 Plex Player if I go to the SSD VPS the file will play and then freeze every few seconds.

Here is a section of the log from further down that does appear to show workers

2018/04/10 11:54:30 DEBUG : movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/: >Lookup: node=movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1$
2018/04/10 11:54:30 DEBUG : movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv: Attr:
2018/04/10 11:54:30 DEBUG : movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv: >Attr: a=valid=1s ino=0 size=8208456841 mode=-rw-r–r--, er$
2018/04/10 11:54:30 DEBUG : movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv: Open: flags=OpenReadOnly
2018/04/10 11:54:30 DEBUG : movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv: Open: flags=O_RDONLY
2018/04/10 11:54:30 DEBUG : movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv: >Open: fd=movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-$
2018/04/10 11:54:30 DEBUG : movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv: >Open: fh=&{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.$
2018/04/10 11:54:30 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: Read: len=69632, offset=3712155648
2018/04/10 11:54:30 DEBUG : movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv: ReadFileHandle.seek from 0 to 3712155648 (io.Seeker)
2018/04/10 11:54:30 DEBUG : 15bloj97nim84m4hcr0rc8af6s/9jms4il5naomlr5g4d9di80autvbqit7os1n2j8kheh4ack0roh9afk499o1iigadivkqaqjtg09g/hcuoa8l0h7cj7ovalq5am3kb1ggv3eig5fpg83rg4u78qp2hp56henpkh4$
2018/04/10 11:54:31 DEBUG : 15bloj97nim84m4hcr0rc8af6s/9jms4il5naomlr5g4d9di80autvbqit7os1n2j8kheh4ack0roh9afk499o1iigadivkqaqjtg09g/hcuoa8l0h7cj7ovalq5am3kb1ggv3eig5fpg83rg4u78qp2hp56henpkh4$
2018/04/10 11:54:31 DEBUG : 15bloj97nim84m4hcr0rc8af6s/9jms4il5naomlr5g4d9di80autvbqit7os1n2j8kheh4ack0roh9afk499o1iigadivkqaqjtg09g/hcuoa8l0h7cj7ovalq5am3kb1ggv3eig5fpg83rg4u78qp2hp56henpkh4$
2018/04/10 11:54:31 DEBUG : 15bloj97nim84m4hcr0rc8af6s/9jms4il5naomlr5g4d9di80autvbqit7os1n2j8kheh4ack0roh9afk499o1iigadivkqaqjtg09g/hcuoa8l0h7cj7ovalq5am3kb1ggv3eig5fpg83rg4u78qp2hp56henpkh4$
2018/04/10 11:54:31 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: >Release: err=
2018/04/10 11:54:31 DEBUG : 15bloj97nim84m4hcr0rc8af6s/9jms4il5naomlr5g4d9di80autvbqit7os1n2j8kheh4ack0roh9afk499o1iigadivkqaqjtg09g/hcuoa8l0h7cj7ovalq5am3kb1ggv3eig5fpg83rg4u78qp2hp56henpkh4$
2018/04/10 11:54:31 DEBUG : worker-2 : downloaded chunk 3717201920
2018/04/10 11:54:31 DEBUG : worker-4 : downloaded chunk 3727687680
2018/04/10 11:54:31 DEBUG : worker-3 : downloaded chunk 3722444800
2018/04/10 11:54:31 DEBUG : worker-1 : downloaded chunk 3711959040
2018/04/10 11:54:31 DEBUG : 15bloj97nim84m4hcr0rc8af6s/9jms4il5naomlr5g4d9di80autvbqit7os1n2j8kheh4ack0roh9afk499o1iigadivkqaqjtg09g/hcuoa8l0h7cj7ovalq5am3kb1ggv3eig5fpg83rg4u78qp2hp56henpkh4$
2018/04/10 11:54:31 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: >Release: err=
2018/04/10 11:54:31 DEBUG : worker-0 : downloaded chunk 3706716160
2018/04/10 11:54:32 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: >Read: read=69632, err=
2018/04/10 11:54:32 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: Read: len=131072, offset=3712225280
2018/04/10 11:54:32 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: >Read: read=131072, err=
2018/04/10 11:54:32 DEBUG : worker-2 : downloaded chunk 3732930560
2018/04/10 11:54:32 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: Read: len=131072, offset=3712356352
2018/04/10 11:54:32 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: >Read: read=131072, err=
2018/04/10 11:54:32 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: Read: len=131072, offset=3712487424
2018/04/10 11:54:32 DEBUG : &{movies/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT/Coco.2017.1080p.BluRay.x264.DTS-HD.MA.7.1-FGT.mkv ®}: >Read: read=131072, err=


#4

I just use:

plex_url = http://localhost:32400

I had some issues with my plex config before and I just removed everything and redid my config just using the rclone config.


#5

That worked! I now get a plex token but I am still getting freezing every few seconds.


#6

Hmm, any other contentions you are seeing? 403s? Network performing ok along with the CPU?

I’ve used my config above for streaming 40-50GB 4K Movies and it seems to work for me. Could be something in the logs if you want to share them as well.


#7

I just redid the config again and rebooted everything and the first couple of test plays have been behaving better.

I will check it again in an hour or so and follow up with this post


#8

Tested further last night and its really performing poorly.

I have put in a ticket with SSDnodes to see if it is due to congestion on the node but I think its something to do with my setup and Rclone Cache. Using Plexdrive it works perfectly and had for the last year.

It just doesn’t seem to be providing the data to the local plex players when it is required.

The VPS has the following specs
4 cores
80GB SSD based drive (IO tests are good)
16GB ram
1GB connection

Any suggestions on what to change or test?


#9

Do some timed tests using time cp /path/to/file/onremote /tmp/ and compare with different mount options (clear cache between each try with a SIGHUP signal or mount with --cache-db-purge each time)


#10

@jasanson I’m on SSDNodes with a the same size VM (15GB Memory, 80GB SSD, 4 cores). I’ve been fighting the same issues you describe. When I copy files into the rclone mount, its not uncommon to see 500mbps uploads. But plex playback continues to suffer. I do periodically see 403 errors in the rclone log.

I have noticed that my firestick seems to handle playback better than my chromecast. Plex on android seems to have a fair amount of issues. I have started looking at the plex logs. But haven’t come up with any conclusive.


#11

I agree that some devices seem better than others. Win10 works the best and for me the Nvidia Shield TV 2017 is the worst.

I’m doing some speed tests for copying files as @spicypixel recommended and they don’t look good so far.

I also did a SpeedTest from the server to another ISP

Hosted by Cable One, Inc. (Dallas, TX) [1953.54 km]: 32.391 ms
Testing download speed…
Download: 98.84 Mbit/s
Testing upload speed…
Upload: 108.11 Mbit/s

Not great but I still think it should be able to handle a single stream.

SSDnodes support has upgraded my ticket to L2 Technical support. We’ll see what they say.


#12

Well, they site talks about “topping 1Gbs” bandwidth, but doesn’t say what’s guaranteed or not. Even so, a single stream around 10-20 Mb/s at most should be fine.

I’m not sure of a good way to identify if you are having a bandwidth type issue though.


#13

I am test copying from my decrypted GdriveCache mount to /tmp

sudo rclone copy local-crypt:test.mkv /tmp

watching it using iftop I am only seeing one active download connection to GoogleDrive and the speed is ranging from 20 - 60 Mbs


#14

Every now and then it drops to a few kilobytes for a matter or seconds and then jumps back up


#15

I do see this error at the beginning of the log

2018/04/11 12:20:20 ERROR : plex: websocket.Dial ws://localhost:32400/:/websockets/notifications?X-Plex-Token=g2n8DbVdtVdsUxoQDHif: dial tcp [::1]:32400: connect: connection refused


#16

I completely believe there could be intermittent networking issues. But here is some network performance while doing a copy from the rclone mount to a local directory. With averages well over 100mbit, I’m not sure why plex seems to struggle so much. For the time being I have removed the plex integration with rclone. Because for all activities outside of playback it limits the number of workers to 1.

Here is a screenshot a bit later during the same copy and it looks like there are times were there is no network traffic for 10-15 seconds.


#17

Doesn’t that risk too many API hits (during scans) and getting a 24Hour ban?


#18

How do you get that graphical output when copying?


#19

I’m using nload to monitor network traffic, that is what i provided the graphical output.

I’ve rarely see above 50,000 api requests in a 24 hour period. My understanding of the workers concept with plex integration, is that it allows rclone to dedicate workers to playback, rather then routine tasks like scanning or copying files. I posted in a different thread about why rclone copy seemed so fast but when copying using rsync from the rclone mount to the file system, it seemed so slow. Now looking back at my post, the plex integration might be the reason you are only seeing 20-60Mbps during a copy operation.


#20

I removed Plex integration and I am still seeing the network traffic stop to and from the VPS. I’m pretty certain there is something wrong with their equipment.