Rclone get update form Sharepoint very slowly

Mgo · September 16, 2021, 11:39am

What is the problem you are having with rclone?

It takes about 10 minutes for changes made on Sharepoint Online to arrive in the server folder. If I edit a file on the server it happens almost instantly on Sharepoint

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

v1.56.0

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

Onedrive Business

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


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

[Service]
Type=notify
ExecStart=/usr/bin/rclone mount Wkheops: /home/ubuntu/Onedrive \
--vfs-cache-mode writes \
--log-level INFO \
--log-file /home/ubuntu/rclone/rclone.log
--poll-interval 5s \


ExecStop=/bin/fusermount -uz /home/ubuntu/Onedrive
Restart=on-success
RestartSec=5
User=ubuntu
Group=ubuntu

[Install]
WantedBy=multi-user.target

#### The rclone config contents with secrets removed.  

[Wkheops]
type = onedrive
token = 
drive_id = 
drive_type = documentLibrary

A log from the command with the `-vv` flag


2021/09/16 11:57:58 DEBUG : rclone: Version "v1.56.0" starting with parameters ["/usr/bin/rclone" "mount" "Wkheops:" "/home/ubuntu/Onedrive" "--vfs-cache-mode" "writes" "--log-level" "DEBUG" "--log-file" "/home/ubuntu/rclone/rclone.log"]
2021/09/16 11:57:58 DEBUG : Creating backend with remote "Wkheops:"
2021/09/16 11:57:58 DEBUG : Using config file from "/home/ubuntu/.config/rclone/rclone.conf"
2021/09/16 11:57:59 INFO  : One drive root '': poll-interval is not supported by this remote
2021/09/16 11:57:59 DEBUG : vfs cache: root is "/home/ubuntu/.cache/rclone/vfs/Wkheops"
2021/09/16 11:57:59 DEBUG : vfs cache: metadata root is "/home/ubuntu/.cache/rclone/vfs/Wkheops"
2021/09/16 11:57:59 DEBUG : Creating backend with remote "/home/ubuntu/.cache/rclone/vfs/Wkheops"
2021/09/16 11:57:59 DEBUG : One drive root '': Mounting on "/home/ubuntu/Onedrive"
2021/09/16 11:57:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 11:57:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 11:57:59 DEBUG : : Root:
2021/09/16 11:57:59 DEBUG : : >Root: node=/, err=<nil>
2021/09/16 11:58:01 DEBUG : /: Attr:
2021/09/16 11:58:01 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
2021/09/16 11:58:01 DEBUG : /: Lookup: name="Wkheops"
2021/09/16 11:58:01 DEBUG : /: >Lookup: node=Wkheops/, err=<nil>
2021/09/16 11:58:01 DEBUG : Wkheops/: Attr:
2021/09/16 11:58:01 DEBUG : Wkheops/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
2021/09/16 11:58:03 DEBUG : /: Attr:
2021/09/16 11:58:03 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
2021/09/16 11:58:03 DEBUG : /: ReadDirAll:
2021/09/16 11:58:03 DEBUG : /: >ReadDirAll: item=3, err=<nil>
2021/09/16 11:58:03 DEBUG : /: Lookup: name="Wkheops"
2021/09/16 11:58:03 DEBUG : /: >Lookup: node=Wkheops/, err=<nil>
2021/09/16 11:58:03 DEBUG : Wkheops/: Attr:
2021/09/16 11:58:03 DEBUG : Wkheops/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
2021/09/16 11:58:03 DEBUG : /: Attr:
2021/09/16 11:58:03 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
2021/09/16 11:58:09 DEBUG : Wkheops/: Lookup: name="test_a_édité.txt"
2021/09/16 11:58:09 DEBUG : Wkheops/: >Lookup: node=Wkheops/test_a_édité.txt, err=<nil>
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: Attr:
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: >Attr: a=valid=1s ino=0 size=39 mode=-rw-r--r--, err=<nil>
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: Open: flags=OpenReadOnly
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: Open: flags=O_RDONLY
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: newRWFileHandle:
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: >newRWFileHandle: err=<nil>
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: >Open: fd=Wkheops/test_a_édité.txt (rw), err=<nil>
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: >Open: fh=&{Wkheops/test_a_édité.txt (rw)}, err=<nil>
2021/09/16 11:58:09 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Read: len=4096, offset=0
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt(0xc00038df40): _readAt: size=4096, off=0
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt(0xc00038df40): openPending:
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: vfs cache: checking remote fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b" against cached fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b"
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: vfs cache: truncate to size=39
2021/09/16 11:58:09 DEBUG : Wkheops: Added virtual directory entry vAddFile: "test_a_édité.txt"
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt(0xc00038df40): >openPending: err=<nil>
2021/09/16 11:58:09 DEBUG : vfs cache: looking for range={Pos:0 Size:39} in [{Pos:0 Size:39}] - present true
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt(0xc00038df40): >_readAt: n=39, err=EOF
2021/09/16 11:58:09 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Read: read=39, err=<nil>
2021/09/16 11:58:09 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Flush:
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt(0xc00038df40): RWFileHandle.Flush
2021/09/16 11:58:09 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Flush: err=<nil>
2021/09/16 11:58:09 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Release:
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt(0xc00038df40): RWFileHandle.Release
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt(0xc00038df40): close:
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt: vfs cache: setting modification time to 2021-09-16 09:55:48 +0000 UTC
2021/09/16 11:58:09 DEBUG : Wkheops/test_a_édité.txt(0xc00038df40): >close: err=<nil>
2021/09/16 11:58:09 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Release: err=<nil>
2021/09/16 11:58:40 DEBUG : Wkheops/: Lookup: name="test_a_édité.txt"
2021/09/16 11:58:40 DEBUG : Wkheops/: >Lookup: node=Wkheops/test_a_édité.txt, err=<nil>
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: Attr:
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: >Attr: a=valid=1s ino=0 size=39 mode=-rw-r--r--, err=<nil>
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: Open: flags=OpenReadOnly
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: Open: flags=O_RDONLY
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: newRWFileHandle:
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: >newRWFileHandle: err=<nil>
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: >Open: fd=Wkheops/test_a_édité.txt (rw), err=<nil>
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: >Open: fh=&{Wkheops/test_a_édité.txt (rw)}, err=<nil>
2021/09/16 11:58:40 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Read: len=4096, offset=0
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt(0xc0000b4a00): _readAt: size=4096, off=0
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt(0xc0000b4a00): openPending:
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: vfs cache: checking remote fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b" against cached fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b"
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: vfs cache: truncate to size=39
2021/09/16 11:58:40 DEBUG : Wkheops: Added virtual directory entry vAddFile: "test_a_édité.txt"
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt(0xc0000b4a00): >openPending: err=<nil>
2021/09/16 11:58:40 DEBUG : vfs cache: looking for range={Pos:0 Size:39} in [{Pos:0 Size:39}] - present true
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt(0xc0000b4a00): >_readAt: n=39, err=EOF
2021/09/16 11:58:40 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Read: read=39, err=<nil>
2021/09/16 11:58:40 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Flush:
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt(0xc0000b4a00): RWFileHandle.Flush
2021/09/16 11:58:40 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Flush: err=<nil>
2021/09/16 11:58:40 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Release:
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt(0xc0000b4a00): RWFileHandle.Release
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt(0xc0000b4a00): close:
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt: vfs cache: setting modification time to 2021-09-16 09:55:48 +0000 UTC
2021/09/16 11:58:40 DEBUG : Wkheops/test_a_édité.txt(0xc0000b4a00): >close: err=<nil>
2021/09/16 11:58:40 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Release: err=<nil>
2021/09/16 11:58:41 DEBUG : Wkheops/: Lookup: name="test_a_édité.txt"
2021/09/16 11:58:41 DEBUG : Wkheops/: >Lookup: node=Wkheops/test_a_édité.txt, err=<nil>
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: Attr:
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: >Attr: a=valid=1s ino=0 size=39 mode=-rw-r--r--, err=<nil>
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: Open: flags=OpenReadOnly
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: Open: flags=O_RDONLY
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: newRWFileHandle:
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: >newRWFileHandle: err=<nil>
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: >Open: fd=Wkheops/test_a_édité.txt (rw), err=<nil>
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: >Open: fh=&{Wkheops/test_a_édité.txt (rw)}, err=<nil>
2021/09/16 11:58:41 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Read: len=4096, offset=0
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt(0xc0001dd300): _readAt: size=4096, off=0
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt(0xc0001dd300): openPending:
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: vfs cache: checking remote fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b" against cached fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b"
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: vfs cache: truncate to size=39
2021/09/16 11:58:41 DEBUG : Wkheops: Added virtual directory entry vAddFile: "test_a_édité.txt"
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt(0xc0001dd300): >openPending: err=<nil>
2021/09/16 11:58:41 DEBUG : vfs cache: looking for range={Pos:0 Size:39} in [{Pos:0 Size:39}] - present true
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt(0xc0001dd300): >_readAt: n=39, err=EOF
2021/09/16 11:58:41 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Read: read=39, err=<nil>
2021/09/16 11:58:41 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Flush:
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt(0xc0001dd300): RWFileHandle.Flush
2021/09/16 11:58:41 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Flush: err=<nil>
2021/09/16 11:58:41 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Release:
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt(0xc0001dd300): RWFileHandle.Release
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt(0xc0001dd300): close:
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt: vfs cache: setting modification time to 2021-09-16 09:55:48 +0000 UTC
2021/09/16 11:58:41 DEBUG : Wkheops/test_a_édité.txt(0xc0001dd300): >close: err=<nil>
2021/09/16 11:58:41 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Release: err=<nil>
2021/09/16 11:58:45 DEBUG : /: Lookup: name="Wkheops"
2021/09/16 11:58:45 DEBUG : /: >Lookup: node=Wkheops/, err=<nil>
2021/09/16 11:58:45 DEBUG : Wkheops/: Attr:
2021/09/16 11:58:45 DEBUG : Wkheops/: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
2021/09/16 11:58:45 DEBUG : /: Attr:
2021/09/16 11:58:45 DEBUG : /: >Attr: attr=valid=1s ino=0 size=0 mode=drwxr-xr-x, err=<nil>
2021/09/16 11:58:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 11:58:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 11:59:29 DEBUG : Wkheops/: Lookup: name="test_a_édité.txt"
2021/09/16 11:59:29 DEBUG : Wkheops/: >Lookup: node=Wkheops/test_a_édité.txt, err=<nil>
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: Attr:
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: >Attr: a=valid=1s ino=0 size=39 mode=-rw-r--r--, err=<nil>
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: Open: flags=OpenReadOnly
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: Open: flags=O_RDONLY
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: newRWFileHandle:
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: >newRWFileHandle: err=<nil>
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: >Open: fd=Wkheops/test_a_édité.txt (rw), err=<nil>
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: >Open: fh=&{Wkheops/test_a_édité.txt (rw)}, err=<nil>
2021/09/16 11:59:29 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Read: len=4096, offset=0
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt(0xc0000b5000): _readAt: size=4096, off=0
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt(0xc0000b5000): openPending:
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: vfs cache: checking remote fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b" against cached fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b"
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: vfs cache: truncate to size=39
2021/09/16 11:59:29 DEBUG : Wkheops: Added virtual directory entry vAddFile: "test_a_édité.txt"
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt(0xc0000b5000): >openPending: err=<nil>
2021/09/16 11:59:29 DEBUG : vfs cache: looking for range={Pos:0 Size:39} in [{Pos:0 Size:39}] - present true
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt(0xc0000b5000): >_readAt: n=39, err=EOF
2021/09/16 11:59:29 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Read: read=39, err=<nil>
2021/09/16 11:59:29 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Flush:
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt(0xc0000b5000): RWFileHandle.Flush
2021/09/16 11:59:29 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Flush: err=<nil>
2021/09/16 11:59:29 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Release:
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt(0xc0000b5000): RWFileHandle.Release
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt(0xc0000b5000): close:
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt: vfs cache: setting modification time to 2021-09-16 09:55:48 +0000 UTC
2021/09/16 11:59:29 DEBUG : Wkheops/test_a_édité.txt(0xc0000b5000): >close: err=<nil>
2021/09/16 11:59:29 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Release: err=<nil>
2021/09/16 11:59:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 11:59:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:00:04 DEBUG : Wkheops/: Lookup: name="test_a_édité.txt"
2021/09/16 12:00:04 DEBUG : Wkheops/: >Lookup: node=Wkheops/test_a_édité.txt, err=<nil>
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: Attr:
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: >Attr: a=valid=1s ino=0 size=39 mode=-rw-r--r--, err=<nil>
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: Open: flags=OpenReadOnly
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: Open: flags=O_RDONLY
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: newRWFileHandle:
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: >newRWFileHandle: err=<nil>
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: >Open: fd=Wkheops/test_a_édité.txt (rw), err=<nil>
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: >Open: fh=&{Wkheops/test_a_édité.txt (rw)}, err=<nil>
2021/09/16 12:00:04 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Read: len=4096, offset=0
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt(0xc00050e600): _readAt: size=4096, off=0
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt(0xc00050e600): openPending:
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: vfs cache: checking remote fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b" against cached fingerprint "39,2021-09-16 09:55:48 +0000 UTC,f2eee2746287430c2a859d04aaf733c35cc7324b"
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: vfs cache: truncate to size=39
2021/09/16 12:00:04 DEBUG : Wkheops: Added virtual directory entry vAddFile: "test_a_édité.txt"
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt(0xc00050e600): >openPending: err=<nil>
2021/09/16 12:00:04 DEBUG : vfs cache: looking for range={Pos:0 Size:39} in [{Pos:0 Size:39}] - present true
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt(0xc00050e600): >_readAt: n=39, err=EOF
2021/09/16 12:00:04 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Read: read=39, err=<nil>
2021/09/16 12:00:04 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Flush:
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt(0xc00050e600): RWFileHandle.Flush
2021/09/16 12:00:04 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Flush: err=<nil>
2021/09/16 12:00:04 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: Release:
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt(0xc00050e600): RWFileHandle.Release
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt(0xc00050e600): close:
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt: vfs cache: setting modification time to 2021-09-16 09:55:48 +0000 UTC
2021/09/16 12:00:04 DEBUG : Wkheops/test_a_édité.txt(0xc00050e600): >close: err=<nil>
2021/09/16 12:00:04 DEBUG : &{Wkheops/test_a_édité.txt (rw)}: >Release: err=<nil>
2021/09/16 12:00:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:00:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:01:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:01:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:02:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:02:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:03:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:03:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:04:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:04:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:05:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:05:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:06:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:06:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:07:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:07:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:08:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:08:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:09:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:09:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:10:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:10:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:11:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:11:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:12:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:12:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:13:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:13:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:14:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:14:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:15:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:15:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:16:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:16:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:17:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:17:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:18:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:18:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:19:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:19:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:20:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:20:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:21:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:21:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:22:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:22:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:23:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:23:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:24:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:24:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:25:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:25:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:26:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:26:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:27:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:27:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:28:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:28:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:29:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:29:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)
2021/09/16 12:30:59 DEBUG : vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item Wkheops/test_a_édité.txt not removed, freed 0 bytes
2021/09/16 12:30:59 INFO  : vfs cache: cleaned: objects 1 (was 1) in use 0, to upload 0, uploading 0, total size 39 (was 39)

Thank you in advance for your help

Marine

Animosity022 · September 16, 2021, 11:57am

That's expected it isn't a polling remote so you have to wait for the directory cache timeout to expire and it will show up after that.

--dir-cache-time duration   Time to cache directory entries for. (default 5m0s)

You could set this smaller but the impact is slower to list out things as it has to get the directory tree again.

Mgo · September 16, 2021, 12:10pm

I tried to put the --dir-cache-time to 1m. It's always very slow to have the update

Animosity022 · September 16, 2021, 12:13pm

What does very slowly mean?

It's not a polling remote so you really can't do much here other than wait as I stated.

Mgo · September 16, 2021, 12:24pm

It's taking 5 minutes for getting the update of the file

Animosity022 · September 16, 2021, 12:24pm

That's exactly the default dir-cache-time.

If you set it to one minute, you'd have to share a full debug log with the change.

asdffdsa · September 16, 2021, 12:39pm

hello and welcome to the forum,

as per the debug log, One drive root '': poll-interval is not supported by this remote

if you rclone copy a file, is there a 10 minute delay?

Animosity022 · September 16, 2021, 1:49pm

That's exactly what I posted a few lines up.

asdffdsa · September 16, 2021, 1:53pm

ok, not sure what happened.
when i came to this page, i did not see any of your posts and i thought i was a the first poster...

Rclone get update form Sharepoint very slowly

What is the problem you are having with rclone?

What is your rclone version (output from rclone version)

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

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

A log from the command with the -vv flag

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

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

A log from the command with the `-vv` flag