VFS Causing Rate Limit

Help and Support
1

What is the problem you are having with rclone?

I am having an issue with rclone that I cannot solve. It seems like the VFS cache is causing google 403 errors when I mount my drive. I have been using the same mount command for over 2 years with almost no issues.

rclone mount 631_crypt: ~/rclone --dir-cache-time 2m --vfs-cache-max-age 1h --vfs-read-chunk-size 64M --vfs-read-chunk-size-limit 2G --drive-chunk-size 64M --buffer-size 16M --timeout 1h --vfs-cache-mode full -vv

It seems to fix itself when I delete the cache directories on my local drive. I have tried setting poll interval to 10m and it still polls every minute. It doesnt seem to respect the max-age as I have tried to change it to a few different lower values and tried changing the dir-cache-time as well. I have tried using a new client ID and secret from google, new toke even changing drives. No luck.

This is my latest mount attempt: rclone mount 631_crypt: ~/rclone --dir-cache-time 1m --vfs-cache-max-age 1m --vfs-read-chunk-size 64M --vfs-read-chunk-size-limit off --drive-chunk-size 64M --buffer-size 32M --timeout 1h --vfs-cache-mode full --vfs-cache-poll-interval 10m -vv

Run the command 'rclone version' and share the full output of the command.

rclone v1.61.1

  • os/version: ubuntu 18.04 (64 bit)
  • os/kernel: 5.4.0-105-generic (x86_64)
  • os/type: linux
  • os/arch: amd64
  • go/version: go1.19.4
  • go/linking: static
  • go/tags: none

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)

rclone mount 631_crypt: ~/rclone --dir-cache-time 2m --vfs-cache-max-age 1h --vfs-read-chunk-size 64M --vfs-read-chunk-size-limit 2G --drive-chunk-size 64M --buffer-size 16M --timeout 1h --vfs-cache-mode full -vv

The rclone config contents with secrets removed.

[631_crypt]
type = crypt
remote = 631_nov1:data
filename_encryption = standard
directory_name_encryption = true

A log from the command with the -vv flag

2023/01/29 15:26:32 DEBUG : rclone: Version "v1.61.1" starting with parameters ["rclone" "mount" "631_crypt:" "/home7/mrl1991/rclone" "--dir-cache-time" "1m" "--vfs-cache-max-age" "1m" "--vfs-read-chunk-size" "64M" "--vfs-read-chunk-size-limit" "off" "--drive-chunk-size" "64M" "--buffer-size" "32M" "--timeout" "1h" "--vfs-cache-mode" "full" "--vfs-cache-poll-interval" "10m" "-vv"]
2023/01/29 15:26:32 DEBUG : Creating backend with remote "631_crypt:"
2023/01/29 15:26:32 DEBUG : Using config file from "/home7/mrl1991/.config/rclone/rclone.conf"
2023/01/29 15:26:32 DEBUG : Creating backend with remote "631_nov1:data"
2023/01/29 15:26:32 DEBUG : 631_nov1: detected overridden config - adding "{84KcY}" suffix to name
2023/01/29 15:26:32 DEBUG : fs cache: renaming cache item "631_nov1:data" to be canonical "631_nov1{84KcY}:data"
2023/01/29 15:26:33 DEBUG : vfs cache: root is "/home7/mrl1991/.cache/rclone"
2023/01/29 15:26:33 DEBUG : vfs cache: data root is "/home7/mrl1991/.cache/rclone/vfs/631_crypt"
2023/01/29 15:26:33 DEBUG : vfs cache: metadata root is "/home7/mrl1991/.cache/rclone/vfsMeta/631_crypt"
2023/01/29 15:26:33 DEBUG : Creating backend with remote "/home7/mrl1991/.cache/rclone/vfs/631_crypt/"
2023/01/29 15:26:33 DEBUG : fs cache: renaming cache item "/home7/mrl1991/.cache/rclone/vfs/631_crypt/" to be canonical "/home7/mrl1991/.cache/rclone/vfs/631_crypt"
2023/01/29 15:26:33 DEBUG : Creating backend with remote "/home7/mrl1991/.cache/rclone/vfsMeta/631_crypt/"
2023/01/29 15:26:33 DEBUG : fs cache: renaming cache item "/home7/mrl1991/.cache/rclone/vfsMeta/631_crypt/" to be canonical "/home7/mrl1991/.cache/rclone/vfsMeta/631_crypt"
2023/01/29 15:26:34 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv: vfs cache: truncate to size=7883285722 (not needed as size correct)
2023/01/29 15:26:34 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv: vfs cache: setting modification time to 2023-01-27 10:39:50 -0500 EST
2023/01/29 15:26:34 INFO  : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv: vfs cache: queuing for upload in 5s
2023/01/29 15:26:35 DEBUG : Formula1.2021.Round13.Netherlands.FP1.SkySportsF1HD.1080p.H264.English-egortech.mkv: Skipping undecryptable file name: illegal base32 data at input byte 8
2023/01/29 15:26:36 DEBUG : media/tv/Poker Face (2023)/Season 1: Added virtual directory entry vAddFile: "Poker Face (2023) - S01E01 - Dead Man's Hand.mkv"
2023/01/29 15:26:36 DEBUG : Encrypted drive '631_crypt:': Mounting on "/home7/mrl1991/rclone"
2023/01/29 15:26:36 DEBUG : vfs cache RemoveNotInUse (maxAge=60000000000, emptyOnly=false): item media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv not removed, freed 0 bytes
2023/01/29 15:26:36 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 7.342Gi (was 7.342Gi)
2023/01/29 15:26:36 DEBUG : : Root: 
2023/01/29 15:26:36 DEBUG : : >Root: node=/, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Lookup: name=".Trash"
2023/01/29 15:26:36 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/01/29 15:26:36 DEBUG : /: Lookup: name=".Trash-1081"
2023/01/29 15:26:36 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/01/29 15:26:36 DEBUG : /: Lookup: name=".xdg-volume-info"
2023/01/29 15:26:36 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/01/29 15:26:36 DEBUG : /: Lookup: name="autorun.inf"
2023/01/29 15:26:36 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Attr: 
2023/01/29 15:26:36 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/01/29 15:26:36 DEBUG : /: ReadDirAll: 
2023/01/29 15:26:36 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/01/29 15:26:36 DEBUG : /: Lookup: name="autorun.inf"
2023/01/29 15:26:36 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/01/29 15:26:39 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv: vfs cache: starting upload
2023/01/29 15:26:40 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/01/29 15:26:40 DEBUG : pacer: Rate limited, increasing sleep to 1.303699156s
2023/01/29 15:26:40 DEBUG : pacer: low level retry 2/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/01/29 15:26:40 DEBUG : pacer: Rate limited, increasing sleep to 2.570892244s
2023/01/29 15:26:42 DEBUG : pacer: low level retry 3/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/01/29 15:26:42 DEBUG : pacer: Rate limited, increasing sleep to 4.136328072s
2

hi max, my favorite number is 1 and my second favorite number is 631

what is the planned use for the mount?

can you post the redacted version of rclone config show 631_nov1:
when you remove id/token/secret/etc, replace the text with redacted, do not delete any lines.

--dir-cache-time 2m - that is an extremely short value. a commonly used is 5000h

why so many flags and non-default values?
you must have a very unique use-case.

3

Hey thanks for getting back. THe mount is for plex. I just used those custom values from a mount example I got years ago for plex. If you have a better example I'd love to try it.

I can try without the flags but I am not sure why now my mount that had been working for so long will just fail. I have just been fiddling with them over the course of troubleshooting. Here's the config w/ redacted.

[631_nov1]

type = drive
client_id = REDACTED
client_secret = REDACTED
scope = drive
token = REDACTED
team_drive = REDACTED
root_folder_id =

4

not sure, but i think there is enough info now for others to comment.

5

I have not tried the mount since last week. I just tried using a simple command: rclone mount 631_crypt: ~/rclone --vfs-cache-mode full -vv

Still getting rate limited and its been far past the max age default.

Any advice?

rclone mount 631_crypt: ~/rclone --vfs-cache-mode full -vv
2023/02/06 12:55:00 DEBUG : rclone: Version "v1.61.1" starting with parameters ["rclone" "mount" "631_crypt:" "/home7/mrl1991/rclone" "--vfs-cache-mode" "full" "-vv"]
2023/02/06 12:55:00 DEBUG : Creating backend with remote "631_crypt:"
2023/02/06 12:55:00 DEBUG : Using config file from "/home7/mrl1991/.config/rclone/rclone.conf"
2023/02/06 12:55:00 DEBUG : Creating backend with remote "631_nov1:data"
2023/02/06 12:55:00 DEBUG : 631_nov1: Loaded invalid token from config file - ignoring
2023/02/06 12:55:00 DEBUG : Saving config "token" in section "631_nov1" of the config file
2023/02/06 12:55:00 DEBUG : 631_nov1: Saved new token in config file
2023/02/06 12:55:01 DEBUG : vfs cache: root is "/home7/mrl1991/.cache/rclone"
2023/02/06 12:55:01 DEBUG : vfs cache: data root is "/home7/mrl1991/.cache/rclone/vfs/631_crypt"
2023/02/06 12:55:01 DEBUG : vfs cache: metadata root is "/home7/mrl1991/.cache/rclone/vfsMeta/631_crypt"
2023/02/06 12:55:01 DEBUG : Creating backend with remote "/home7/mrl1991/.cache/rclone/vfs/631_crypt/"
2023/02/06 12:55:01 DEBUG : fs cache: renaming cache item "/home7/mrl1991/.cache/rclone/vfs/631_crypt/" to be canonical "/home7/mrl1991/.cache/rclone/vfs/631_crypt"
2023/02/06 12:55:01 DEBUG : Creating backend with remote "/home7/mrl1991/.cache/rclone/vfsMeta/631_crypt/"
2023/02/06 12:55:01 DEBUG : fs cache: renaming cache item "/home7/mrl1991/.cache/rclone/vfsMeta/631_crypt/" to be canonical "/home7/mrl1991/.cache/rclone/vfsMeta/631_crypt"
2023/02/06 12:55:03 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv: vfs cache: truncate to size=7883285722 (not needed as size correct)
2023/02/06 12:55:03 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv: vfs cache: setting modification time to 2023-01-27 10:39:50 -0500 EST
2023/02/06 12:55:03 INFO  : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv: vfs cache: queuing for upload in 5s
2023/02/06 12:55:03 DEBUG : Formula1.2021.Round13.Netherlands.FP1.SkySportsF1HD.1080p.H264.English-egortech.mkv: Skipping undecryptable file name: illegal base32 data at input byte 8
2023/02/06 12:55:05 DEBUG : media/tv/Poker Face (2023)/Season 1: Added virtual directory entry vAddFile: "Poker Face (2023) - S01E01 - Dead Man's Hand.mkv"
2023/02/06 12:55:06 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E02 - The Night Shift.mkv: vfs cache: truncate to size=3160297434 (not needed as size correct)
2023/02/06 12:55:06 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E02 - The Night Shift.mkv: vfs cache: setting modification time to 2023-01-27 21:21:18 -0500 EST
2023/02/06 12:55:06 INFO  : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E02 - The Night Shift.mkv: vfs cache: queuing for upload in 5s
2023/02/06 12:55:06 DEBUG : media/tv/Poker Face (2023)/Season 1: Added virtual directory entry vAddFile: "Poker Face (2023) - S01E02 - The Night Shift.mkv"
2023/02/06 12:55:06 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E03 - The Stall.mkv: vfs cache: truncate to size=2752201641 (not needed as size correct)
2023/02/06 12:55:06 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E03 - The Stall.mkv: vfs cache: setting modification time to 2023-01-27 21:21:39 -0500 EST
2023/02/06 12:55:06 INFO  : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E03 - The Stall.mkv: vfs cache: queuing for upload in 5s
2023/02/06 12:55:06 DEBUG : media/tv/Poker Face (2023)/Season 1: Added virtual directory entry vAddFile: "Poker Face (2023) - S01E03 - The Stall.mkv"
2023/02/06 12:55:06 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E04 - Rest in Metal.mkv: vfs cache: truncate to size=2413708226 (not needed as size correct)
2023/02/06 12:55:06 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E04 - Rest in Metal.mkv: vfs cache: setting modification time to 2023-01-27 21:21:43 -0500 EST
2023/02/06 12:55:06 INFO  : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E04 - Rest in Metal.mkv: vfs cache: queuing for upload in 5s
2023/02/06 12:55:06 DEBUG : media/tv/Poker Face (2023)/Season 1: Added virtual directory entry vAddFile: "Poker Face (2023) - S01E04 - Rest in Metal.mkv"
2023/02/06 12:55:06 DEBUG : Encrypted drive '631_crypt:': Mounting on "/home7/mrl1991/rclone"
2023/02/06 12:55:06 DEBUG : : Root: 
2023/02/06 12:55:06 DEBUG : : >Root: node=/, err=<nil>
2023/02/06 12:55:06 DEBUG : /: Lookup: name=".Trash"
2023/02/06 12:55:06 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/02/06 12:55:06 DEBUG : /: Lookup: name=".Trash-1081"
2023/02/06 12:55:06 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/02/06 12:55:06 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv not removed, freed 0 bytes
2023/02/06 12:55:06 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E02 - The Night Shift.mkv not removed, freed 0 bytes
2023/02/06 12:55:06 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E03 - The Stall.mkv not removed, freed 0 bytes
2023/02/06 12:55:06 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E04 - Rest in Metal.mkv not removed, freed 0 bytes
2023/02/06 12:55:06 INFO  : vfs cache: cleaned: objects 4 (was 4) in use 4, to upload 4, uploading 0, total size 15.096Gi (was 15.096Gi)
2023/02/06 12:55:07 DEBUG : /: Lookup: name=".xdg-volume-info"
2023/02/06 12:55:07 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/02/06 12:55:07 DEBUG : /: Lookup: name="autorun.inf"
2023/02/06 12:55:07 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Attr: 
2023/02/06 12:55:07 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:07 DEBUG : /: ReadDirAll: 
2023/02/06 12:55:07 DEBUG : /: >ReadDirAll: item=4, err=<nil>
2023/02/06 12:55:07 DEBUG : /: Lookup: name="autorun.inf"
2023/02/06 12:55:07 DEBUG : /: >Lookup: node=<nil>, err=no such file or directory
2023/02/06 12:55:08 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E01 - Dead Man's Hand.mkv: vfs cache: starting upload
2023/02/06 12:55:09 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/02/06 12:55:09 DEBUG : pacer: Rate limited, increasing sleep to 1.04772795s
2023/02/06 12:55:09 DEBUG : pacer: low level retry 2/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/02/06 12:55:09 DEBUG : pacer: Rate limited, increasing sleep to 2.098789565s
2023/02/06 12:55:10 DEBUG : pacer: low level retry 3/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/02/06 12:55:10 DEBUG : pacer: Rate limited, increasing sleep to 4.355836094s
2023/02/06 12:55:11 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E02 - The Night Shift.mkv: vfs cache: starting upload
2023/02/06 12:55:11 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E03 - The Stall.mkv: vfs cache: starting upload
2023/02/06 12:55:11 DEBUG : media/tv/Poker Face (2023)/Season 1/Poker Face (2023) - S01E04 - Rest in Metal.mkv: vfs cache: starting upload
2023/02/06 12:55:12 DEBUG : pacer: low level retry 4/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/02/06 12:55:12 DEBUG : pacer: Rate limited, increasing sleep to 8.329521111s
2023/02/06 12:55:16 DEBUG : pacer: Reducing sleep to 0s
2023/02/06 12:55:25 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/02/06 12:55:25 DEBUG : pacer: Rate limited, increasing sleep to 1.458993379s
2023/02/06 12:55:25 DEBUG : pacer: Reducing sleep to 0s
2023/02/06 12:55:25 DEBUG : pacer: low level retry 5/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/02/06 12:55:25 DEBUG : pacer: Rate limited, increasing sleep to 1.303391474s
2023/02/06 12:55:25 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/02/06 12:55:25 DEBUG : pacer: Rate limited, increasing sleep to 2.300636528s
2023/02/06 12:55:25 DEBUG : pacer: low level retry 2/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/02/06 12:55:25 DEBUG : pacer: Rate limited, increasing sleep to 4.461169249s
2023/02/06 12:55:26 DEBUG : pacer: low level retry 1/10 (error googleapi: Error 403: User rate limit exceeded., userRateLimitExceeded)
2023/02/06 12:55:26 DEBUG : pacer: Rate limited, increasing sleep to 8.653173329s
2023/02/06 12:55:26 DEBUG : /: Attr: 
2023/02/06 12:55:26 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:26 DEBUG : /: Lookup: name="media"
2023/02/06 12:55:26 DEBUG : /: >Lookup: node=media/, err=<nil>
2023/02/06 12:55:26 DEBUG : media/: Attr: 
2023/02/06 12:55:26 DEBUG : media/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
2023/02/06 12:55:26 DEBUG : media/: Lookup: name="tv"
2023/02/06 12:55:26 DEBUG : media/: >Lookup: node=media/tv/, err=<nil>
2023/02/06 12:55:26 DEBUG : media/tv/: Attr: 
2023/02/06 12:55:26 DEBUG : media/tv/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxrwxr-x, err=<nil>
6

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