Mount failed on Windows - drive just doesn't appear


#1

Hello,

I started using rclone on Windows and so far I was very happy with it.
Easily I could integrate my google drive and also mount worked pretty well.

Since some days I cannot mount my drives anymore, the drive just doesn’t appear.
So I tried to debug and I don’t really see any error:

C:\Users\usr\rclone>rclone -vv mount gdrive:/ X: --vfs-cache-mode writes --debug-fuse
2018/12/03 10:09:24 DEBUG : rclone: Version "v1.44" starting with parameters ["rclone" "-vv" "mount" "gdrive:/" "X:" "--vfs-cache-mode" "writes" "--debug-fuse"]
2018/12/03 10:09:24 DEBUG : Using config file from "C:\\Users\\usr\\.config\\rclone\\rclone.conf"
2018/12/03 10:09:24 DEBUG : Google drive root '': Mounting on "X:"
2018/12/03 10:09:24 DEBUG : vfs cache root is "C:\\Users\\usr\\AppData\\Local\\rclone\\vfs\\gdrive"
2018/12/03 10:09:24 DEBUG : Adding path "vfs/forget" to remote control registry
2018/12/03 10:09:24 DEBUG : Adding path "vfs/refresh" to remote control registry
2018/12/03 10:09:24 DEBUG : Adding path "vfs/poll-interval" to remote control registry
2018/12/03 10:09:24 DEBUG : Google drive root '': Mounting with options: ["-o" "fsname=gdrive:" "-o" "subtype=rclone" "-o" "max_readahead=131072" "-o" "attr_timeout=1" "-o" "atomic_o_trunc" "-o" "debug" "-o" "uid=-1" "-o" "gid=-1" "--FileSystemName=rclone"]
2018/12/03 10:09:24 DEBUG : Google drive root '': Init:
2018/12/03 10:09:24 DEBUG : Google drive root '': >Init:
2018/12/03 10:09:24 DEBUG : /: Statfs:
2018/12/03 10:09:24 DEBUG : /: >Statfs: stat={Bsize:4096 Frsize:4096 Blocks:274877906944 Bfree:274796150367 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Favail:0 Fsid:0 Flag:0 Namemax:255}, errc=0
2018/12/03 10:09:24 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2018/12/03 10:09:24 DEBUG : /: >Getattr: errc=0
2018/12/03 10:09:24 DEBUG : /: Readlink:
2018/12/03 10:09:24 DEBUG : /: >Readlink: linkPath="", errc=-40
The service rclone has been started.
rclone[TID=5530]: FFFF9789C6585580: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=0000000000000590[PID=29a8], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
2018/12/03 10:09:24 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2018/12/03 10:09:24 DEBUG : /: >Getattr: errc=0
2018/12/03 10:09:24 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2018/12/03 10:09:24 DEBUG : /: >Getattr: errc=0
2018/12/03 10:09:24 DEBUG : /: Opendir:
2018/12/03 10:09:24 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2018/12/03 10:09:24 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2018/12/03 10:09:24 DEBUG : /: >Opendir: errc=0, fh=0x0
rclone[TID=5530]: FFFF9789C6585580: <<Create IoStatus=0[1] UserContext=0000000000000000:00000000000D6F20, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:0, FileSize=0:0, CreationTime=2018-12-03T09:09:24.251Z, LastAccessTime=2018-12-03T09:09:24.251Z, LastWriteTime=2018-12-03T09:09:24.251Z, ChangeTime=2018-12-03T09:09:24.251Z, IndexNumber=0:1}
rclone[TID=5530]: FFFF9789D62487B0: >>QueryVolumeInformation
rclone[TID=0754]: FFFF9789C7D16780: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=200000, FileAttributes=0, Security=NULL, AllocationSize=0:0, AccessToken=00000000000005AC[PID=29a8], DesiredAccess=80, GrantedAccess=0, ShareAccess=7
2018/12/03 10:09:24 DEBUG : /: Statfs:
2018/12/03 10:09:24 DEBUG : /: >Statfs: stat={Bsize:4096 Frsize:4096 Blocks:274877906944 Bfree:274796150367 Bavail:274877906944 Files:1000000000 Ffree:1000000000 Favail:0 Fsid:0 Flag:0 Namemax:255}, errc=0
rclone[TID=5530]: FFFF9789D62487B0: <<QueryVolumeInformation IoStatus=0[0] VolumeInfo={TotalSize=40000:0, FreeSize=3ffb2:7e5f000, VolumeLabel=""}
2018/12/03 10:09:24 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
rclone[TID=5530]: FFFF9789C8E31CC0: >>Close 0000000000000000:00000000000D6F20
2018/12/03 10:09:24 DEBUG : /: >Getattr: errc=0
2018/12/03 10:09:24 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2018/12/03 10:09:24 DEBUG : /: >Getattr: errc=0
2018/12/03 10:09:24 DEBUG : /: Opendir:
2018/12/03 10:09:24 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2018/12/03 10:09:24 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2018/12/03 10:09:24 DEBUG : /: >Opendir: errc=0, fh=0x1
rclone[TID=0754]: FFFF9789C7D16780: <<Create IoStatus=0[1] UserContext=0000000000000000:00000000000D7250, GrantedAccess=80, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:0, FileSize=0:0, CreationTime=2018-12-03T09:09:24.251Z, LastAccessTime=2018-12-03T09:09:24.251Z, LastWriteTime=2018-12-03T09:09:24.251Z, ChangeTime=2018-12-03T09:09:24.251Z, IndexNumber=0:1}
2018/12/03 10:09:24 DEBUG : /: Releasedir: fh=0x0
rclone[TID=0754]: FFFF9789D6961010: >>Close 0000000000000000:00000000000D7250
rclone[TID=4c94]: FFFF9789C6585580: >>Create [UT---C] "\", FILE_OPEN, CreateOptions=21, FileAttributes=10, Security=NULL, AllocationSize=0:0, AccessToken=00000000000005B0[PID=29a8], DesiredAccess=100000, GrantedAccess=0, ShareAccess=3
2018/12/03 10:09:24 DEBUG : /: >Releasedir: errc=0
rclone[TID=5530]: FFFF9789C8E31CC0: <<Close IoStatus=0[0]
2018/12/03 10:09:24 DEBUG : /: Releasedir: fh=0x1
2018/12/03 10:09:24 DEBUG : /: >Releasedir: errc=0
rclone[TID=0754]: FFFF9789D6961010: <<Close IoStatus=0[0]
2018/12/03 10:09:24 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2018/12/03 10:09:24 DEBUG : /: >Getattr: errc=0
2018/12/03 10:09:24 DEBUG : /: Getattr: fh=0xFFFFFFFFFFFFFFFF
2018/12/03 10:09:24 DEBUG : /: >Getattr: errc=0
2018/12/03 10:09:24 DEBUG : /: Opendir:
2018/12/03 10:09:24 DEBUG : /: OpenFile: flags=O_RDONLY, perm=-rwxrwxrwx
2018/12/03 10:09:24 DEBUG : /: >OpenFile: fd=/ (r), err=<nil>
2018/12/03 10:09:24 DEBUG : /: >Opendir: errc=0, fh=0x0
rclone[TID=4c94]: FFFF9789C6585580: <<Create IoStatus=0[1] UserContext=0000000000000000:00000000000D7250, GrantedAccess=100000, FileInfo={FileAttributes=10, ReparseTag=0, AllocationSize=0:0, FileSize=0:0, CreationTime=2018-12-03T09:09:24.251Z, LastAccessTime=2018-12-03T09:09:24.251Z, LastWriteTime=2018-12-03T09:09:24.251Z, ChangeTime=2018-12-03T09:09:24.251Z, IndexNumber=0:1}
rclone[TID=4c94]: FFFF9789D68DF230: >>Close 0000000000000000:00000000000D7250
2018/12/03 10:09:24 DEBUG : /: Releasedir: fh=0x0
2018/12/03 10:09:24 DEBUG : /: >Releasedir: errc=0
rclone[TID=4c94]: FFFF9789D68DF230: <<Close IoStatus=0[0]
2018/12/03 10:10:24 DEBUG : Google drive root '': Checking for changes on remote
2018/12/03 10:11:24 DEBUG : Google drive root '': Checking for changes on remote
2018/12/03 10:12:24 DEBUG : Google drive root '': Checking for changes on remote
2018/12/03 10:13:24 DEBUG : Google drive root '': Checking for changes on remote

Has someone any idea what this could be? I have absolutely no idea where to search.
I also tried different drive letters that are all not taken, but no idea why it doesn’t work.

Thank you and best regards


#2

From your log it appears that the drive is mounted.

I don’t know why it doesn’t appear though…

Did you try a reboot? Maybe the kernel has got confused somehow?


#3

Reboot of windows? Yes, I did a reboot, but still it doesn’t show up.


#4

Have you got the latest version of WinFSP? Maybe you could try re-installing that?


#5

Also tried that one, but still doesn’t change.
But I realize it’s something with my computer, maybe another program has an impact. I will try to close everything and then check again.

I copied rclone with the same config to a VM, works like a charm


#6

That is a good experiment.

I’d be interested to hear how you get on.


#7

I tried a lot and now I figured out what went wrong, it was the classical PEBCAK. I should have read better the instructions:

Note that drives created as Administrator are not visible by other accounts (including the account that was elevated as Administrator). So if you start a Windows drive from an Administrative Command Prompt and then try to access the same drive from Explorer (which does not run as Administrator), you will not be able to see the new drive.

So I opened the cmd as Admin and the explorer as a normal user. When I opened the cmd as normal user the drive appeared.
Problem solved :wink: