search

keybase / client

Keybase Go Library, Client, Service, OS X, iOS, Android, Electron
BSD 3-Clause "New" or "Revised" License
8.91k stars 1.23k forks source link

Got message "We were unable to load KBFS (Fuse kext). at application startup #13254

Open miguelaferreira opened 6 years ago

miguelaferreira commented 6 years ago

Whenever I open keybase.app I get a popup with a message about not being able to lead KBFS. After this I installed OSX FUSE extensions but still get that same popup

my log id: 0aece1a2c5c651356f6abf1c

strib commented 6 years ago

@miguelaferreira Have you seen our troubleshooting tips for situations like this? https://github.com/keybase/client/wiki/Troubleshooting

Note that the osxfuse extensions aren't relevant -- Keybase ships with its own version of those drivers and won't use the system versions for compatibility reasons.

If the troubleshooting above doesn't work, you should post the output of keybase --debug install -c fuse here and we can take a closer look.

miguelaferreira commented 6 years ago

@strib I went through the wiki link you sent and I don't run VMware nor VirtualBox and I'm not going to unload SMB or the Intel HAXM because I could use those. However, from the link it seems that I should have enough slots.

Here's the output of the command you asked for:

2018-08-13T15:22:27.147619+02:00 ▶ [DEBU keybase globals.go:451] 001 Created Identify2Cache, max age: 5m0s
2018-08-13T15:22:27.147960+02:00 ▶ [DEBU keybase globals.go:454] 002 Created LinkCache, max size: 4000, clean dur: 1m0s
2018-08-13T15:22:27.147986+02:00 ▶ [DEBU keybase globals.go:456] 003 Created CardCache, max age: 5m0s
2018-08-13T15:22:27.148001+02:00 ▶ [DEBU keybase globals.go:474] 004 made a new full self cache
2018-08-13T15:22:27.148038+02:00 ▶ [DEBU keybase globals.go:476] 005 made a new cached UPAK loader (timeout=10m0s)
2018-08-13T15:22:27.152508+02:00 ▶ [DEBU keybase ui.go:604] 006 Setting GPG_TTY to /dev/ttys010
2018-08-13T15:22:27.153647+02:00 ▶ [DEBU keybase secret_store.go:152] 007 NewSecretStoreLocked: using os-specific SecretStore
2018-08-13T15:22:27.153992+02:00 ▶ [DEBU keybase globals.go:387] 008 Keybase CLI 2.5.0-20180807174114+6435f04df6
2018-08-13T15:22:27.154008+02:00 ▶ [DEBU keybase globals.go:387] 009 - Built with go1.10.3
2018-08-13T15:22:27.154016+02:00 ▶ [DEBU keybase globals.go:387] 00a - Visit https://keybase.io for more details
2018-08-13T15:22:27.155175+02:00 ▶ [DEBU keybase main.go:212] 00b + configureProcesses
2018-08-13T15:22:27.155208+02:00 ▶ [DEBU keybase main.go:323] 00c + configureLogging
2018-08-13T15:22:27.155227+02:00 ▶ [DEBU keybase main.go:332] 00d Disabling log forwarding
2018-08-13T15:22:27.155240+02:00 ▶ [DEBU keybase main.go:325] 00e - configureLogging
2018-08-13T15:22:27.155253+02:00 ▶ [DEBU keybase main.go:214] 00f - configureProcesses -> <nil>
2018-08-13T15:22:27.157489+02:00 ▶ [DEBU keybase install_darwin.go:444] 010 Installing components: [fuse]
2018-08-13T15:22:27.158008+02:00 ▶ [INFO keybase app.go:106] 011 Installing KBFuse
2018-08-13T15:22:29.331172+02:00 ▶ [DEBU keybase app.go:77] 012 Output ([--debug --run-mode=prod --app-path=/Applications/Keybase.app --timeout=10 --install-fuse]): 08.13.2018 15:22:27.453 Installer:55[DEBG] Version: 1.1.61
08.13.2018 15:22:27.487 KBInstaller:27[DEBG] Installables: (
    "Privileged Helper",
    Fuse
)
08.13.2018 15:22:27.487 KBInstaller:32[DEBG] Install: Privileged Helper
08.13.2018 15:22:27.488 KBHelperTool:73[INFO] Connecting to keybase.Helper (privileged=1)
08.13.2018 15:22:27.488 KBHelperTool:73[INFO] Connected
08.13.2018 15:22:27.538 KBHelperTool:115[DEBG] Helper version: {
    build = 3;
    version = "1.0.31";
}
08.13.2018 15:22:27.560 KBHelperTool:115[DEBG] Helper version: {
    build = 3;
    version = "1.0.31";
}
08.13.2018 15:22:27.565 KBInstaller:32[DEBG] Install: Fuse
08.13.2018 15:22:27.565 KBTask:114[DEBG] Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d --log-format=file fuse status --bundle-version=3.6.3
08.13.2018 15:22:27.767 KBTask:55[DEBG] Task EOF
08.13.2018 15:22:27.768 KBTask:70[DEBG] Completed
08.13.2018 15:22:27.768 KBTask:72[DEBG] Task (err): 2018-08-13T15:22:27.590856+02:00 ▶ [DEBU keybase globals.go:451] 001 Created Identify2Cache, max age: 5m0s
2018-08-13T15:22:27.590919+02:00 ▶ [DEBU keybase globals.go:454] 002 Created LinkCache, max size: 4000, clean dur: 1m0s
2018-08-13T15:22:27.590942+02:00 ▶ [DEBU keybase globals.go:456] 003 Created CardCache, max age: 5m0s
2018-08-13T15:22:27.590954+02:00 ▶ [DEBU keybase globals.go:474] 004 made a new full self cache
2018-08-13T15:22:27.590999+02:00 ▶ [DEBU keybase globals.go:476] 005 made a new cached UPAK loader (timeout=10m0s)
2018-08-13T15:22:27.594798+02:00 ▶ [DEBU keybase ui.go:606] 006 Can't set GPG_TTY; discover failed
2018-08-13T15:22:27.594856+02:00 ▶ [DEBU keybase secret_store.go:152] 007 NewSecretStoreLocked: using os-specific SecretStore
2018-08-13T15:22:27.594887+02:00 ▶ [DEBU keybase globals.go:387] 008 Keybase CLI 2.5.0-20180807174114+6435f04df6
2018-08-13T15:22:27.594899+02:00 ▶ [DEBU keybase globals.go:387] 009 - Built with go1.10.3
2018-08-13T15:22:27.594906+02:00 ▶ [DEBU keybase globals.go:387] 00a - Visit https://keybase.io for more details
2018-08-13T15:22:27.595554+02:00 ▶ [DEBU keybase main.go:212] 00b + configureProcesses
2018-08-13T15:22:27.595609+02:00 ▶ [DEBU keybase main.go:323] 00c + configureLogging
2018-08-13T15:22:27.595820+02:00 ▶ [DEBU keybase main.go:332] 00d Disabling log forwarding
2018-08-13T15:22:27.595851+02:00 ▶ [DEBU keybase main.go:325] 00e - configureLogging
2018-08-13T15:22:27.595875+02:00 ▶ [DEBU keybase main.go:214] 00f - configureProcesses -> <nil>
2018-08-13T15:22:27.596496+02:00 ▶ [DEBU keybase fuse_status_darwin.go:46] 010 No kext info available (kext not loaded)
2018-08-13T15:22:27.596635+02:00 ▶ [DEBU keybase fuse_status_darwin.go:136] 011 Loading plist: /Library/Filesystems/kbfuse.fs/Contents/Info.plist
2018-08-13T15:22:27.610879+02:00 ▶ [DEBU keybase out_of_date.go:14] 012 + PrintOutOfDateWarnings
2018-08-13T15:22:27.612109+02:00 ▶ [DEBU keybase socket.go:65] 013 + GetSocket
2018-08-13T15:22:27.612704+02:00 ▶ [DEBU keybase rpc.go:25] 014 - GetSocket -> ok
2018-08-13T15:22:27.612767+02:00 ▶ [DEBU keybase socket.go:75] 015 | empty socket wrapper; need a new one
2018-08-13T15:22:27.613343+02:00 ▶ [DEBU keybase socket_nix.go:100] 016 + SocketInfo#dialSocket(unix:/Users/miguel/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock)
2018-08-13T15:22:27.613383+02:00 ▶ [DEBU keybase socket_nix.go:124] 017 | net.Dial(unix:/Users/miguel/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock)
2018-08-13T15:22:27.615156+02:00 ▶ [DEBU keybase socket_nix.go:125] 018 - SocketInfo#dialSocket(unix:/Users/miguel/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock) -> ok
2018-08-13T15:22:27.615187+02:00 ▶ [DEBU keybase socket.go:90] 019 | DialSocket -> ok
2018-08-13T15:22:27.657465+02:00 ▶ [DEBU keybase out_of_date.go:28] 01a Got OutOfDateInfo: keybase1.OutOfDateInfo{UpgradeTo:"", UpgradeURI:"", CustomMessage:"", CriticalClockSkew:0}
2018-08-13T15:22:27.657525+02:00 ▶ [DEBU keybase out_of_date.go:44] 01b - PrintOutOfDateWarnings
2018-08-13T15:22:27.762130+02:00 ▶ [DEBU keybase globals.go:589] 01c GlobalContext#Shutdown(0xc4203c2700)
2018-08-13T15:22:27.762812+02:00 ▶ [DEBU keybase globals.go:633] 01d exiting shutdown code=0; err=<nil>

08.13.2018 15:22:27.768 KBTask:73[DEBG] Task (out): {
  "version": "3.6.3",
  "bundleVersion": "3.6.3",
  "kextID": "com.github.kbfuse.filesystems.kbfuse",
  "path": "/Library/Filesystems/kbfuse.fs",
  "kextStarted": false,
  "installStatus": 4,
  "installAction": 1,
  "mountInfos": [],
  "status": {
    "code": 0,
    "name": "OK",
    "desc": "OK",
    "fields": null
  }
}

08.13.2018 15:22:27.791 KBFuseComponent:243[DEBG] Load permissions: 4755
08.13.2018 15:22:27.791 KBFuseComponent:173[INFO] Fuse install is OK
08.13.2018 15:22:27.791 KBTask:114[DEBG] Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d --log-format=file fuse status --bundle-version=3.6.3
08.13.2018 15:22:27.947 KBTask:55[DEBG] Task EOF
08.13.2018 15:22:27.947 KBTask:70[DEBG] Completed
08.13.2018 15:22:27.948 KBTask:72[DEBG] Task (err): 2018-08-13T15:22:27.820355+02:00 ▶ [DEBU keybase globals.go:451] 001 Created Identify2Cache, max age: 5m0s
2018-08-13T15:22:27.820424+02:00 ▶ [DEBU keybase globals.go:454] 002 Created LinkCache, max size: 4000, clean dur: 1m0s
2018-08-13T15:22:27.820452+02:00 ▶ [DEBU keybase globals.go:456] 003 Created CardCache, max age: 5m0s
2018-08-13T15:22:27.820469+02:00 ▶ [DEBU keybase globals.go:474] 004 made a new full self cache
2018-08-13T15:22:27.820514+02:00 ▶ [DEBU keybase globals.go:476] 005 made a new cached UPAK loader (timeout=10m0s)
2018-08-13T15:22:27.825302+02:00 ▶ [DEBU keybase ui.go:606] 006 Can't set GPG_TTY; discover failed
2018-08-13T15:22:27.825361+02:00 ▶ [DEBU keybase secret_store.go:152] 007 NewSecretStoreLocked: using os-specific SecretStore
2018-08-13T15:22:27.825403+02:00 ▶ [DEBU keybase globals.go:387] 008 Keybase CLI 2.5.0-20180807174114+6435f04df6
2018-08-13T15:22:27.825418+02:00 ▶ [DEBU keybase globals.go:387] 009 - Built with go1.10.3
2018-08-13T15:22:27.825426+02:00 ▶ [DEBU keybase globals.go:387] 00a - Visit https://keybase.io for more details
2018-08-13T15:22:27.825963+02:00 ▶ [DEBU keybase main.go:212] 00b + configureProcesses
2018-08-13T15:22:27.826026+02:00 ▶ [DEBU keybase main.go:323] 00c + configureLogging
2018-08-13T15:22:27.826057+02:00 ▶ [DEBU keybase main.go:332] 00d Disabling log forwarding
2018-08-13T15:22:27.826073+02:00 ▶ [DEBU keybase main.go:325] 00e - configureLogging
2018-08-13T15:22:27.826116+02:00 ▶ [DEBU keybase main.go:214] 00f - configureProcesses -> <nil>
2018-08-13T15:22:27.826504+02:00 ▶ [DEBU keybase fuse_status_darwin.go:46] 010 No kext info available (kext not loaded)
2018-08-13T15:22:27.826560+02:00 ▶ [DEBU keybase fuse_status_darwin.go:136] 011 Loading plist: /Library/Filesystems/kbfuse.fs/Contents/Info.plist
2018-08-13T15:22:27.831305+02:00 ▶ [DEBU keybase out_of_date.go:14] 012 + PrintOutOfDateWarnings
2018-08-13T15:22:27.831359+02:00 ▶ [DEBU keybase socket.go:65] 013 + GetSocket
2018-08-13T15:22:27.831394+02:00 ▶ [DEBU keybase rpc.go:25] 014 - GetSocket -> ok
2018-08-13T15:22:27.831414+02:00 ▶ [DEBU keybase socket.go:75] 015 | empty socket wrapper; need a new one
2018-08-13T15:22:27.831434+02:00 ▶ [DEBU keybase socket_nix.go:100] 016 + SocketInfo#dialSocket(unix:/Users/miguel/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock)
2018-08-13T15:22:27.831461+02:00 ▶ [DEBU keybase socket_nix.go:124] 017 | net.Dial(unix:/Users/miguel/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock)
2018-08-13T15:22:27.831710+02:00 ▶ [DEBU keybase socket_nix.go:125] 018 - SocketInfo#dialSocket(unix:/Users/miguel/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock) -> ok
2018-08-13T15:22:27.831732+02:00 ▶ [DEBU keybase socket.go:90] 019 | DialSocket -> ok
2018-08-13T15:22:27.839376+02:00 ▶ [DEBU keybase out_of_date.go:28] 01a Got OutOfDateInfo: keybase1.OutOfDateInfo{UpgradeTo:"", UpgradeURI:"", CustomMessage:"", CriticalClockSkew:0}
2018-08-13T15:22:27.839450+02:00 ▶ [DEBU keybase out_of_date.go:44] 01b - PrintOutOfDateWarnings
2018-08-13T15:22:27.941563+02:00 ▶ [DEBU keybase globals.go:589] 01c GlobalContext#Shutdown(0xc4203ba700)
2018-08-13T15:22:27.941691+02:00 ▶ [DEBU keybase globals.go:633] 01d exiting shutdown code=0; err=<nil>

08.13.2018 15:22:27.948 KBTask:73[DEBG] Task (out): {
  "version": "3.6.3",
  "bundleVersion": "3.6.3",
  "kextID": "com.github.kbfuse.filesystems.kbfuse",
  "path": "/Library/Filesystems/kbfuse.fs",
  "kextStarted": false,
  "installStatus": 4,
  "installAction": 1,
  "mountInfos": [],
  "status": {
    "code": 0,
    "name": "OK",
    "desc": "OK",
    "fields": null
  }
}

08.13.2018 15:22:29.327 KBInstaller:45[INFO] Install complete
08.13.2018 15:22:29.327 KBInstaller:49[INFO] Privileged Helper: Installed, Bundle Version: 1.0.31
Version: 1.0.31
08.13.2018 15:22:29.327 KBInstaller:49[INFO] Fuse: Install Error: KextManager failed to load with status: -603946985
Installed, Version: 3.6.3
Kext ID: com.github.kbfuse.filesystems.kbfuse
Kext Loaded: No
Path: /Library/Filesystems/kbfuse.fs
08.13.2018 15:22:29.327 Installer:95[INFO] Exit(4)
2018-08-13T15:22:29.331931+02:00 ▶ [ERRO keybase install_darwin.go:538] 013 Error installing KBFuse: exit status 4

I do see the error mentioned in the wiki page

Fuse: Install Error: KextManager failed to load with status: -603946985

Could it be some other application? Maybe there is a command to list the usage of these slots.

miguelaferreira commented 6 years ago

@strib I'm still faced with the same issue, is there something I could try and do myself to figure this out?

strib commented 6 years ago

The way the applications map to slots is very mysterious to me, I'm afraid I'm not an expert. In my recollection, two programs such as Intel and HAXM are definitely enough to take all the slots. I'm sorry, I have no clue how to check slot usage.

macOS Mojave might increase the number of slots, but I'm not 100% sure about that.

miguelaferreira commented 6 years ago

Alright, thanks for getting back to me anyway.