Rclone service file (Ubuntu)

piratesedge · May 2, 2020, 3:41am

What is the problem you are having with rclone?

I can't seem to get get tmp-upload-path to work.

What is your rclone version (output from `rclone version`)

rclone v1.51.0

os/arch: linux/amd64
go version: go1.13.7

Which OS you are using and how many bits (eg Windows 7, 64 bit)

Ubuntu 18.04.4 LTS (GNU/Linux 4.15.0-96-generic x86_64)

Which cloud storage system are you using? (eg Google Drive)

Google Drive

The command you were trying to run (eg `rclone copy /tmp remote:tmp`)

My service file is listed below, does anyone know why it's not picking up the tmp-upload-path, I've enabled debug mode and went through the logs multiple times and saw nothing.

[Unit]
Description=RClone Service
Wants=network-online.target
After=network-online.target
Before=docker.service

[Service]
Type=notify
Environment=RCLONE_CONFIG=/opt/rclone/rclone.conf
KillMode=none
RestartSec=5
ExecStart=/usr/bin/rclone mount --allow-other --dir-cache-time 999h --cache-chunk-size 5M --cache-chunk-total-size 5G --cache-info-age 1006h --cache-workers 5 --cache-tmp-wait-time 15m --cache-tmp-upload-path /opt/rclone/tmp-upload/ --buffer-size 32M --umask 002 --log-level INFO --log-file /opt/rclone/logs/rclone.log --user-agent indulgetv gcache: /mnt/gdrive --rc --rc-addr 127.0.0.1:5572 \

ExecStop=/bin/fusermount -u /mnt/gdrive
Restart=on-failure
User=root
Group=root

[Install]
WantedBy=multi-user.target

A log from the command with the `-vv` flag (eg output from `rclone -vv copy /tmp remote:tmp`)

Animosity022 · May 2, 2020, 3:46am

Can you run with -vv and share the log? What indicates it's not working?

piratesedge · May 2, 2020, 4:00am

I can see it's not working because rclone will directly upload the file to drive and not place it in the tmp-upload-path /opt/rclone/tmp-upload. All permissions on the tmp-upload-path are in root:root etc, event changed it to 777 to see if that would fix it.

This is what I get upon a server reboot when the service starts.

2020/05/02 13:59:30 NOTICE: Serving remote control on http://127.0.0.1:5572/
2020/05/02 13:59:30 DEBUG : Using config file from "/opt/rclone/rclone.conf"
2020/05/02 13:59:30 DEBUG : Google drive root '': Mounting on "/mnt/gdrive"
2020/05/02 13:59:30 DEBUG : Adding path "vfs/forget" to remote control registry
2020/05/02 13:59:30 DEBUG : Adding path "vfs/refresh" to remote control registry
2020/05/02 13:59:30 DEBUG : Adding path "vfs/poll-interval" to remote control registry
2020/05/02 13:59:30 DEBUG : : Root:
2020/05/02 13:59:30 DEBUG : : >Root: node=/, err=
2020/05/02 13:59:35 DEBUG : /: Attr:
2020/05/02 13:59:35 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:38 DEBUG : /: Attr:
2020/05/02 13:59:38 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:38 DEBUG : /: ReadDirAll:
2020/05/02 13:59:39 DEBUG : : Statfs:
2020/05/02 13:59:39 DEBUG : /: >ReadDirAll: item=10, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="1080p-Movies"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=1080p-Movies/, err=
2020/05/02 13:59:39 DEBUG : 1080p-Movies/: Attr:
2020/05/02 13:59:39 DEBUG : 1080p-Movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="4K-Movies"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=4K-Movies/, err=
2020/05/02 13:59:39 DEBUG : 4K-Movies/: Attr:
2020/05/02 13:59:39 DEBUG : 4K-Movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="720p-Movies"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=720p-Movies/, err=
2020/05/02 13:59:39 DEBUG : 720p-Movies/: Attr:
2020/05/02 13:59:39 DEBUG : 720p-Movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="Anime"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=Anime/, err=
2020/05/02 13:59:39 DEBUG : Anime/: Attr:
2020/05/02 13:59:39 DEBUG : Anime/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="Audio"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=Audio/, err=
2020/05/02 13:59:39 DEBUG : Audio/: Attr:
2020/05/02 13:59:39 DEBUG : Audio/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="Movies"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=Movies/, err=
2020/05/02 13:59:39 DEBUG : Movies/: Attr:
2020/05/02 13:59:39 DEBUG : Movies/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="TV-1080p"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=TV-1080p/, err=
2020/05/02 13:59:39 DEBUG : TV-1080p/: Attr:
2020/05/02 13:59:39 DEBUG : TV-1080p/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="TV-4K-HRD"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=TV-4K-HRD/, err=
2020/05/02 13:59:39 DEBUG : TV-4K-HRD/: Attr:
2020/05/02 13:59:39 DEBUG : TV-4K-HRD/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="TV-720p"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=TV-720p/, err=
2020/05/02 13:59:39 DEBUG : TV-720p/: Attr:
2020/05/02 13:59:39 DEBUG : TV-720p/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : /: Lookup: name="e-Books"
2020/05/02 13:59:39 DEBUG : /: >Lookup: node=e-Books/, err=
2020/05/02 13:59:39 DEBUG : e-Books/: Attr:
2020/05/02 13:59:39 DEBUG : e-Books/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/02 13:59:39 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:40 DEBUG : : Statfs:
2020/05/02 13:59:40 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:41 DEBUG : : Statfs:
2020/05/02 13:59:41 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:42 DEBUG : : Statfs:
2020/05/02 13:59:42 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:43 DEBUG : : Statfs:
2020/05/02 13:59:43 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:44 DEBUG : : Statfs:
2020/05/02 13:59:44 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:45 DEBUG : : Statfs:
2020/05/02 13:59:45 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:46 DEBUG : : Statfs:
2020/05/02 13:59:46 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:47 DEBUG : : Statfs:
2020/05/02 13:59:47 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:48 DEBUG : : Statfs:
2020/05/02 13:59:48 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:49 DEBUG : : Statfs:
2020/05/02 13:59:49 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:50 DEBUG : : Statfs:
2020/05/02 13:59:50 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:51 DEBUG : : Statfs:
2020/05/02 13:59:51 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:52 DEBUG : : Statfs:
2020/05/02 13:59:52 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:53 DEBUG : : Statfs:
2020/05/02 13:59:53 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:54 DEBUG : : Statfs:
2020/05/02 13:59:54 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:55 DEBUG : : Statfs:
2020/05/02 13:59:55 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:56 DEBUG : : Statfs:
2020/05/02 13:59:56 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:57 DEBUG : : Statfs:
2020/05/02 13:59:57 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:58 DEBUG : : Statfs:
2020/05/02 13:59:58 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 13:59:59 DEBUG : : Statfs:
2020/05/02 13:59:59 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 14:00:00 DEBUG : : Statfs:
2020/05/02 14:00:00 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 14:00:01 DEBUG : : Statfs:
2020/05/02 14:00:01 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 14:00:02 DEBUG : : Statfs:
2020/05/02 14:00:02 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/02 14:00:03 DEBUG : : Statfs:
2020/05/02 14:00:03 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261932442269 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=

piratesedge · May 2, 2020, 4:03am

Let me know if you need anything more specific.

Animosity022 · May 2, 2020, 10:35am

You cut out the log and missed out the first line. Doesn't seem like cache items are even loaded.

Can you share your rclone.conf without keys and passwords? I'd guess you have the wrong remote in the service file.

piratesedge · May 6, 2020, 4:42am

I deleted the log file it's literally starts at this line, I didn't cut out anything before it.

2020/05/06 14:38:30 DEBUG : Google drive root '': Checking for changes on remote
2020/05/06 14:38:34 DEBUG : TV-1080p/: Lookup: name="!temp!"
2020/05/06 14:38:34 DEBUG : TV-1080p/: >Lookup: node=TV-1080p/!temp!/, err=
2020/05/06 14:38:34 DEBUG : TV-1080p/!temp!/: Attr:
2020/05/06 14:38:34 DEBUG : TV-1080p/!temp!/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/06 14:38:34 DEBUG : TV-1080p/: Lookup: name="!temp!"
2020/05/06 14:38:34 DEBUG : TV-1080p/: >Lookup: node=TV-1080p/!temp!/, err=
2020/05/06 14:38:34 DEBUG : TV-1080p/!temp!/: Attr:
2020/05/06 14:38:34 DEBUG : TV-1080p/!temp!/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=
2020/05/06 14:38:34 DEBUG : TV-1080p/!temp!/: Lookup: name="tv"
2020/05/06 14:38:34 DEBUG : TV-1080p/!temp!/: >Lookup: node=TV-1080p/!temp!/tv/, err=
2020/05/06 14:38:34 DEBUG : TV-1080p/!temp!/tv/: Attr:

Rclone .conf

The mount works fine, i can use it and has been working for about 2 months now, I just can't understand why it's not loading the tmp cache.

[gdrive]
type = drive
client_id = xxxxxxxxxxxxxxxxxxxxxxxxxxxx
client_secret = xxxxxxxxxxxxx
scope = drive
root_folder_id = xxxxxxxxxxxx
token = xxxxxxxxxxxxxxxxxxxxxx

[gcache]
type = drive
client_id = xxxxxxxxxxxxxxxxxxxxxxxxxxxx
client_secret = xxxxxxxx
scope = drive
root_folder_id = xxxxxxxxx
upload_cutoff = 750G
chunk_size = 32M
info_age = 2w
token = xxxxxxxxxxxx

def_rclone · May 6, 2020, 5:27am

so not sure if i'm right but if your directory has "!" or any special characters in it use quotes
ex: rclone copy "/path/to/local/dir" remote:"remote/temp/path/" -vv

i'm not sure if thats problem you're facing right now

please do also provide
The command you were trying to run (eg rclone copy /tmp remote:tmp)
and a complete log after running the command again

piratesedge · May 6, 2020, 6:17am

I always use quotes when copying files with spaces or special charcters, I just can't understand why the tmp cache doesn't seem to work.

Ran from cli instead of a service file.

root@indulge:~# rclone mount --allow-other --dir-cache-time 999h --cache-chunk-size 5M --cache-chunk-total-size 5G --cache-info-age 1006h --cache-workers 5 --cache-tmp-wait-time 15m --cache-tmp-upload-path /opt/rclone/tmp-upload/ --buffer-size 32M --umask 002 -vv gcache: /mnt/gdrive
2020/05/06 16:15:03 DEBUG : rclone: Version "v1.51.0" starting with parameters ["rclone" "mount" "--allow-other" "--dir-cache-time" "999h" "--cache-chunk-size" "5M" "--cache-chunk-total-size" "5G" "--cache-info-age" "1006h" "--cache-workers" "5" "--cache-tmp-wait-time" "15m" "--cache-tmp-upload-path" "/opt/rclone/tmp-upload/" "--buffer-size" "32M" "--umask" "002" "-vv" "gcache:" "/mnt/gdrive"]
2020/05/06 16:15:03 DEBUG : Using config file from "/root/.config/rclone/rclone.conf"
2020/05/06 16:15:03 DEBUG : Google drive root '': Mounting on "/mnt/gdrive"
2020/05/06 16:15:03 DEBUG : gcache: Loaded invalid token from config file - ignoring
2020/05/06 16:15:03 DEBUG : gcache: Saved new token in config file
2020/05/06 16:15:03 DEBUG : Adding path "vfs/forget" to remote control registry
2020/05/06 16:15:03 DEBUG : Adding path "vfs/refresh" to remote control registry
2020/05/06 16:15:03 DEBUG : Adding path "vfs/poll-interval" to remote control registry
2020/05/06 16:15:03 DEBUG : : Root:
2020/05/06 16:15:03 DEBUG : : >Root: node=/, err=
2020/05/06 16:15:07 DEBUG : : Statfs:
2020/05/06 16:15:07 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:08 DEBUG : : Statfs:
2020/05/06 16:15:08 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:09 DEBUG : : Statfs:
2020/05/06 16:15:09 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:10 DEBUG : : Statfs:
2020/05/06 16:15:10 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:11 DEBUG : : Statfs:
2020/05/06 16:15:11 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:12 DEBUG : : Statfs:
2020/05/06 16:15:12 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:13 DEBUG : : Statfs:
2020/05/06 16:15:13 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:14 DEBUG : : Statfs:
2020/05/06 16:15:14 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:15 DEBUG : : Statfs:
2020/05/06 16:15:15 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:16 DEBUG : : Statfs:
2020/05/06 16:15:16 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:17 DEBUG : : Statfs:
2020/05/06 16:15:17 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:18 DEBUG : : Statfs:
2020/05/06 16:15:18 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:19 DEBUG : : Statfs:
2020/05/06 16:15:19 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:20 DEBUG : : Statfs:
2020/05/06 16:15:20 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:21 DEBUG : : Statfs:
2020/05/06 16:15:21 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:22 DEBUG : : Statfs:
2020/05/06 16:15:22 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:23 DEBUG : : Statfs:
2020/05/06 16:15:23 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:24 DEBUG : : Statfs:
2020/05/06 16:15:24 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:25 DEBUG : : Statfs:
2020/05/06 16:15:25 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:26 DEBUG : : Statfs:
2020/05/06 16:15:26 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
2020/05/06 16:15:27 DEBUG : : Statfs:
2020/05/06 16:15:27 DEBUG : : >Statfs: stat={Blocks:274877906944 Bfree:261686329069 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Bsize:4096 Namelen:255 Frsize:4096}, err=
^C2020/05/06 16:15:28 DEBUG : rclone: Version "v1.51.0" finishing with parameters ["rclone" "mount" "--allow-other" "--dir-cache-time" "999h" "--cache-chunk-size" "5M" "--cache-chunk-total-size" "5G" "--cache-info-age" "1006h" "--cache-workers" "5" "--cache-tmp-wait-time" "15m" "--cache-tmp-upload-path" "/opt/rclone/tmp-upload/" "--buffer-size" "32M" "--umask" "002" "-vv" "gcache:" "/mnt/gdrive"]

Animosity022 · May 6, 2020, 10:35am

Did you try to copy something with the debug on? There is zero activity in the logfile.

piratesedge · May 7, 2020, 12:51am

There is activity on the log file just different information, a while back like maybe a year ago, I used your service file on GitHub @Animosity022 It's always worked as expected I just added tmp-upload-path.

Now seems the logs are outputting different things than before.

A Grep for a copy I just made.

There are 1000s of other write logs above this, just cut it out.
2020/05/07 10:15:48 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: >Write: written=8192, err=
2020/05/07 10:15:48 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: Write: len=8192, offset=4394229760
2020/05/07 10:15:48 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: >Write: written=8192, err=
2020/05/07 10:15:48 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: Write: len=8192, offset=4394237952
2020/05/07 10:15:48 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: >Write: written=8192, err=
2020/05/07 10:15:48 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: Write: len=1322, offset=4394246144
2020/05/07 10:15:48 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: >Write: written=1322, err=
2020/05/07 10:15:48 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: Flush:
2020/05/07 10:15:50 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Sending chunk 4362076160 length 32171306
2020/05/07 10:15:54 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Size and modification time the same (differ by -727.448µs, within tolerance 1ms)
2020/05/07 10:15:54 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: >Flush: err=
2020/05/07 10:15:54 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: Release:
2020/05/07 10:15:54 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: WriteFileHandle.Release nothing to do
2020/05/07 10:15:54 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (w)}: >Release: err=
2020/05/07 10:15:55 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:15:55 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:15:55 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:15:55 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:15:55 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Setattr: a=Setattr [ID=0x31eef7 Node=0xb47 Uid=0 Gid=0 Pid=13920] atime=2020-05-07 10:10:16.84764 +1000 AEST mtime=2020-03-27 06:07:26 +1100 AEDT handle=INVALID-0x0
2020/05/07 10:15:56 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: File._applyPendingModTime OK
2020/05/07 10:15:56 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Setattr: err=
2020/05/07 10:15:56 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:15:56 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:15:56 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:15:56 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:15:56 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:15:56 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:16:20 DEBUG : : changeNotify: relativePath="SOME_File_WHICH_WILL_BE_UNAMED", type=3
2020/05/07 10:16:55 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:16:55 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:16:55 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:16:55 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:17:32 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:17:32 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:17:32 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:17:32 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:17:32 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Open: flags=OpenReadOnly
2020/05/07 10:17:32 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Open: flags=O_RDONLY
2020/05/07 10:17:32 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Open: fd=SOME_File_WHICH_WILL_BE_UNAMED (r), err=
2020/05/07 10:17:32 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Open: fh=&{SOME_File_WHICH_WILL_BE_UNAMED (r)}, err=
2020/05/07 10:17:32 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=0
2020/05/07 10:17:32 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 0 length 134217728
2020/05/07 10:17:33 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 0 length 4096 chunkOffset 0 chunkSize 134217728
2020/05/07 10:17:33 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4096 length 8192 chunkOffset 0 chunkSize 134217728
2020/05/07 10:17:33 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 12288 length 16384 chunkOffset 0 chunkSize 134217728
2020/05/07 10:17:33 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 28672 length 32768 chunkOffset 0 chunkSize 134217728
2020/05/07 10:17:33 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 61440 length 65536 chunkOffset 0 chunkSize 134217728
2020/05/07 10:17:34 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 126976 length 131072 chunkOffset 0 chunkSize 134217728
2020/05/07 10:17:34 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 258048 length 262144 chunkOffset 0 chunkSize 134217728
2020/05/07 10:17:34 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:17:34 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=69632, offset=4394180608
2020/05/07 10:17:34 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.seek from 131072 to 4394180608 (fs.RangeSeeker)
2020/05/07 10:17:34 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.RangeSeek from 520192 to 4394180608 length -1
2020/05/07 10:17:34 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at -1 length 4096 chunkOffset 4394180608 chunkSize 134217728
2020/05/07 10:17:34 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 4394180608 length 134217728
2020/05/07 10:17:35 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4394184704 length 8192 chunkOffset 4394180608 chunkSize 134217728
2020/05/07 10:17:35 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4394192896 length 16384 chunkOffset 4394180608 chunkSize 134217728
2020/05/07 10:17:35 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4394209280 length 32768 chunkOffset 4394180608 chunkSize 134217728
2020/05/07 10:17:35 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4394242048 length 65536 chunkOffset 4394180608 chunkSize 134217728
2020/05/07 10:17:35 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=66858, err=
2020/05/07 10:17:35 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Flush:
2020/05/07 10:17:35 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Flush: err=
2020/05/07 10:17:35 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Release:
2020/05/07 10:17:35 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.Release closing
2020/05/07 10:17:35 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Release: err=
2020/05/07 10:28:04 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:28:04 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:28:04 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:28:04 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:28:19 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:28:19 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:28:19 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:28:19 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:28:32 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:28:32 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:28:32 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:28:32 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:28:48 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:28:48 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:28:48 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:28:48 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:30:03 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:30:03 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:30:03 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:30:03 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:30:03 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Open: flags=OpenReadOnly
2020/05/07 10:30:03 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Open: flags=O_RDONLY
2020/05/07 10:30:03 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Open: fd=SOME_File_WHICH_WILL_BE_UNAMED (r), err=
2020/05/07 10:30:03 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Open: fh=&{SOME_File_WHICH_WILL_BE_UNAMED (r)}, err=
2020/05/07 10:30:03 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=65536, offset=0
2020/05/07 10:30:03 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 0 length 134217728
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 0 length 4096 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4096 length 8192 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 12288 length 16384 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 28672 length 32768 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 61440 length 65536 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 126976 length 131072 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:05 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=65536, err=
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:30:05 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=4096, offset=4394246144
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 258048 length 262144 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.seek from 65536 to 4394246144 (fs.RangeSeeker)
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.RangeSeek from 520192 to 4394246144 length -1
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at -1 length 4096 chunkOffset 4394246144 chunkSize 134217728
2020/05/07 10:30:05 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 4394246144 length 134217728
2020/05/07 10:30:06 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=1322, err=
2020/05/07 10:30:06 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=65536
2020/05/07 10:30:06 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.seek from 4394247466 to 65536 (fs.RangeSeeker)
2020/05/07 10:30:06 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.RangeSeek from 4394247466 to 65536 length -1
2020/05/07 10:30:06 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at -1 length 4096 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:06 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 65536 length 134217728
2020/05/07 10:30:07 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 69632 length 8192 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:07 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 77824 length 16384 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:07 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 94208 length 32768 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:07 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 126976 length 65536 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:07 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 192512 length 131072 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:07 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 323584 length 262144 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:07 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:07 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=196608
2020/05/07 10:30:07 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:30:07 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 585728 length 524288 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:08 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:08 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Flush:
2020/05/07 10:30:08 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Flush: err=
2020/05/07 10:30:08 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Release:
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.Release closing
2020/05/07 10:30:08 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: Lookup: name="Amazing.Stories.2020.S01E04.1080p.WEB.H264-XLF.mkv"
2020/05/07 10:30:08 DEBUG : TV-1080p/Amazing Stories (2020)/Season 1/: >Lookup: node=SOME_File_WHICH_WILL_BE_UNAMED, err=
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Open: flags=OpenReadOnly
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Open: flags=O_RDONLY
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Open: fd=SOME_File_WHICH_WILL_BE_UNAMED (r), err=
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Open: fh=&{SOME_File_WHICH_WILL_BE_UNAMED (r)}, err=
2020/05/07 10:30:08 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=65536, offset=0
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 0 length 134217728
2020/05/07 10:30:08 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Release: err=
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 0 length 4096 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4096 length 8192 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 12288 length 16384 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 28672 length 32768 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 61440 length 65536 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 126976 length 131072 chunkOffset 0 chunkSize 134217728
2020/05/07 10:30:08 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=65536, err=
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:30:08 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:30:08 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=4096, offset=4394246144
2020/05/07 10:30:09 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.seek from 65536 to 4394246144 (fs.RangeSeeker)
2020/05/07 10:30:09 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.RangeSeek from 258048 to 4394246144 length -1
2020/05/07 10:30:09 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at -1 length 4096 chunkOffset 4394246144 chunkSize 134217728
2020/05/07 10:30:09 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 4394246144 length 134217728
2020/05/07 10:30:09 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=1322, err=
2020/05/07 10:30:09 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=65536
2020/05/07 10:30:09 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.seek from 4394247466 to 65536 (fs.RangeSeeker)
2020/05/07 10:30:09 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.RangeSeek from 4394247466 to 65536 length -1
2020/05/07 10:30:09 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at -1 length 4096 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:09 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 65536 length 134217728
2020/05/07 10:30:10 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 69632 length 8192 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:10 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 77824 length 16384 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:10 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 94208 length 32768 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:10 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 126976 length 65536 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:11 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 192512 length 131072 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:11 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 323584 length 262144 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:11 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:11 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=196608
2020/05/07 10:30:11 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:30:11 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:30:11 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 585728 length 524288 chunkOffset 65536 chunkSize 134217728
2020/05/07 10:30:11 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:11 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=36864, offset=4394205184
2020/05/07 10:30:11 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.seek from 327680 to 4394205184 (fs.RangeSeeker)
2020/05/07 10:30:11 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.RangeSeek from 1110016 to 4394205184 length -1
2020/05/07 10:30:11 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at -1 length 4096 chunkOffset 4394205184 chunkSize 134217728
2020/05/07 10:30:11 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 4394205184 length 134217728
2020/05/07 10:30:12 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4394209280 length 8192 chunkOffset 4394205184 chunkSize 134217728
2020/05/07 10:30:12 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4394217472 length 16384 chunkOffset 4394205184 chunkSize 134217728
2020/05/07 10:30:12 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 4394233856 length 32768 chunkOffset 4394205184 chunkSize 134217728
2020/05/07 10:30:12 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=36864, err=
2020/05/07 10:30:12 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: Attr:
2020/05/07 10:30:12 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: >Attr: a=valid=1s ino=0 size=4394247466 mode=-rw-rw-r--, err=
2020/05/07 10:30:12 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=4096, offset=4394242048
2020/05/07 10:30:12 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=4096, err=
2020/05/07 10:30:12 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=36864, offset=2198368256
2020/05/07 10:30:12 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.seek from 4394246144 to 2198368256 (fs.RangeSeeker)
2020/05/07 10:30:12 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.RangeSeek from 4394247466 to 2198368256 length -1
2020/05/07 10:30:12 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at -1 length 4096 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:12 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 2198368256 length 134217728
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2198372352 length 8192 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2198380544 length 16384 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2198396928 length 32768 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2198429696 length 65536 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=36864, err=
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=2198405120
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2198495232 length 131072 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2198626304 length 262144 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=2198536192
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2198888448 length 524288 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=2198667264
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=2198798336
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2199412736 length 1048576 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:13 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=36864, offset=2251968512
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2200461312 length 1048576 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2201509888 length 1048576 chunkOffset 2198368256 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ReadFileHandle.seek from 2198929408 to 2251968512 (fs.RangeSeeker)
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.RangeSeek from 2202558464 to 2251968512 length -1
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at -1 length 4096 chunkOffset 2251968512 chunkSize 134217728
2020/05/07 10:30:13 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.openRange at 2251968512 length 134217728
2020/05/07 10:30:15 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2251972608 length 8192 chunkOffset 2251968512 chunkSize 134217728
2020/05/07 10:30:15 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2251980800 length 16384 chunkOffset 2251968512 chunkSize 134217728
2020/05/07 10:30:15 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2251997184 length 32768 chunkOffset 2251968512 chunkSize 134217728
2020/05/07 10:30:15 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2252029952 length 65536 chunkOffset 2251968512 chunkSize 134217728
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=36864, err=
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=2252005376
2020/05/07 10:30:15 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2252095488 length 131072 chunkOffset 2251968512 chunkSize 134217728
2020/05/07 10:30:15 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2252226560 length 262144 chunkOffset 2251968512 chunkSize 134217728
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=2252136448
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=2252267520
2020/05/07 10:30:15 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2252488704 length 524288 chunkOffset 2251968512 chunkSize 134217728
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=2252398592
2020/05/07 10:30:15 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2253012992 length 1048576 chunkOffset 2251968512 chunkSize 134217728
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: Read: len=131072, offset=2252529664
2020/05/07 10:30:15 DEBUG : &{SOME_File_WHICH_WILL_BE_UNAMED (r)}: >Read: read=131072, err=
2020/05/07 10:30:15 DEBUG : SOME_File_WHICH_WILL_BE_UNAMED: ChunkedReader.Read at 2254061568 length 1048576 chunkOffset 2251968512 chunkSize 134217728

piratesedge · May 7, 2020, 12:53am

So I am running as rclone as root user, all files and folder permissions are set to the correct permissions. I'm really unsure of what i'm doing wrong, I thought it had to do something with my service file.

Animosity022 · May 7, 2020, 10:34am

How did you configure the cache backend ? It should be type = cache

Mine points back to my GD:

[cache]
type = cache
remote = GD:
chunk_size = 32M
info_age = 8760h
chunk_total_size = 50G
db_path = /opt/rclone
chunk_path = /opt/rclone
chunk_clean_interval = 5m
workers = 4

as an example.

With my crypt remote on top of it.

[cachecrypt]
type = crypt
remote = cache:crypt
filename_encryption = standard
password = password
password2 = password
directory_name_encryption = true

piratesedge · May 8, 2020, 2:54am

You're right I've just taken a look at my backup copy of my rclone.conf on a previous server I setup.

[gcache]
type = cache
remote = gdrive:NAS

I have this listed in my conf too.

This must be the issue, I will report back once fixed.

Thank you so much for this information. I was planning to bash my head against a wall.

system · May 13, 2020, 1:04am

This topic was automatically closed 3 days after the last reply. New replies are no longer allowed.