search

keybase / keybase-issues

A single repo for managing publicly recognized issues with the keybase client, installer, and website.
902 stars 37 forks source link

“keybase.kbfs isn't running” on macOS 10.12.2 #2721

Closed njm2112 closed 7 years ago

njm2112 commented 7 years ago

As of about two days ago, Keybase.app throws this error upon automatic launch at machine startup.

image

Detailed log info posted as separate comment.

Problem is recurring with every new restart.

I'm able to manually launch Keybase.app but immediately encounter similar error:

image

That being said, the error doesn't cause me to have to force quit Keybase.app and after closing out of the error, seems to be working fine (?).

njm2112 commented 7 years ago

Here’s info from log:

Installer: 1.1.45
Privileged Helper: Installed, Bundle Version: 1.0.29, Version: 1.0.29
Updater: Installed, PID: 712
Service: Installed, Version: 1.0.18-20161209203922+7173f3e, PID: 772
Fuse: Installed, Version: 3.5.2, Kext ID: com.github.kbfuse.filesystems.kbfuse, Kext Loaded: No, Path: /Library/Filesystems/kbfuse.fs
Mount directory: Installed
KBFS: Re-Install, Error: keybase.kbfs isn’t running (expecting pid=833), Error, PID: 833
CLI: Installed

Log:
Version: 1.1.45
Installables: (
    “Privileged Helper”,
    Updater,
    Service,
    Fuse,
    “Mount directory”,
    KBFS,
    CLI
)
Install: Privileged Helper
Connecting to keybase.Helper (privileged=1)
Connected
Helper version: {
    build = 2;
    version = “1.0.29”;
}
Helper version: {
    build = 2;
    version = “1.0.29”;
}
Install: Updater
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d —log-format=file install —format=json —components=updater —timeout=90s
Task EOF
Completed
Task (err): 2016-12-15T08:18:59.298119 ▶ [DEBU keybase globals.go:310] 001 Created Identify2Cache, max age: 5m0s
2016-12-15T08:18:59.300314 ▶ [DEBU keybase globals.go:313] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2016-12-15T08:18:59.300657 ▶ [DEBU keybase globals.go:315] 003 Created CardCache, max age: 5m0s
2016-12-15T08:18:59.303880 ▶ [DEBU keybase ui.go:561] 004 Can’t set GPG_TTY; discover failed
2016-12-15T08:18:59.304046 ▶ [DEBU keybase globals.go:293] 005 Keybase CLI 1.0.18-20161209203922+7173f3e
2016-12-15T08:18:59.304736 ▶ [DEBU keybase globals.go:293] 006 - Built with go1.7.4
2016-12-15T08:18:59.304752 ▶ [DEBU keybase globals.go:293] 007 - Visit https://keybase.io for more details
2016-12-15T08:18:59.304768 ▶ [DEBU keybase main.go:147] 008 + configureProcesses
2016-12-15T08:18:59.304823 ▶ [DEBU keybase main.go:245] 009 + configureLogging
2016-12-15T08:18:59.304851 ▶ [DEBU keybase main.go:254] 00a Disabling log forwarding
2016-12-15T08:18:59.304867 ▶ [DEBU keybase main.go:247] 00b - configureLogging
2016-12-15T08:18:59.304878 ▶ [DEBU keybase main.go:149] 00c - configureProcesses -> <nil>
2016-12-15T08:18:59.305568 ▶ [DEBU keybase install_osx.go:405] 00d Installing components: [updater]
2016-12-15T08:18:59.306440 ▶ [DEBU keybase install_osx.go:911] 00e Using updater path: /Applications/Keybase.app/Contents/SharedSupport/bin/updater
2016-12-15T08:18:59.306759 ▶ [INFO keybase launchd.go:284] 00f Removing /Users/njm/Library/LaunchAgents/keybase.updater.plist
2016-12-15T08:18:59.316332 ▶ [DEBU keybase launchd.go:141] 010 Output (launchctl remove): 
2016-12-15T08:18:59.316459 ▶ [INFO keybase launchd.go:217] 011 Waiting for keybase.updater to exit…
2016-12-15T08:18:59.531494 ▶ [INFO keybase launchd.go:160] 012 Stopped keybase.updater
2016-12-15T08:18:59.531608 ▶ [DEBU keybase install_osx.go:921] 013 Installing updater service (keybase.updater, timeout=1m30s)
2016-12-15T08:18:59.532005 ▶ [INFO keybase launchd.go:268] 014 Saving /Users/njm/Library/LaunchAgents/keybase.updater.plist
2016-12-15T08:18:59.533208 ▶ [DEBU keybase util.go:178] 015 + Writing to /Users/njm/Library/LaunchAgents/keybase.updater.plist
2016-12-15T08:18:59.538833 ▶ [DEBU keybase util.go:180] 016 | Temporary file generated: /Users/njm/Library/LaunchAgents/keybase.updater.plist.AS3KOHQBFR24E3BUXFFJDEBJIAWRCBXC
2016-12-15T08:18:59.550760 ▶ [DEBU keybase util.go:199] 017 - Wrote to /Users/njm/Library/LaunchAgents/keybase.updater.plist -> ok
2016-12-15T08:18:59.550922 ▶ [INFO keybase launchd.go:95] 018 Starting keybase.updater
2016-12-15T08:18:59.559930 ▶ [DEBU keybase launchd.go:98] 019 Output (launchctl load): 
2016-12-15T08:18:59.559999 ▶ [INFO keybase launchd.go:176] 01a Waiting for keybase.updater to be loaded…
2016-12-15T08:19:10.106560 ▶ [DEBU keybase launchd.go:111] 01b Service status: &launchd.ServiceStatus{label:”keybase.updater”, pid:”712”, lastExitStatus:””}
2016-12-15T08:19:10.622576 ▶ [DEBU keybase out_of_date.go:13] 01c + PrintOutOfDateWarnings
2016-12-15T08:19:10.623163 ▶ [DEBU keybase util.go:391] 01d + GetSocket
2016-12-15T08:19:10.623253 ▶ [DEBU keybase util.go:392] 01e - GetSocket -> ok
2016-12-15T08:19:10.623964 ▶ [DEBU keybase socket.go:73] 01f | empty socket wrapper; need a new one
2016-12-15T08:19:10.624465 ▶ [DEBU keybase socket_nix.go:96] 020 Dialing unix:/Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:10.625741 ▶ [DEBU keybase socket_nix.go:96] 021 Dialing unix:/Users/njm/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:10.626020 ▶ [DEBU keybase socket.go:88] 022 | DialSocket -> ERROR: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:10.626057 ▶ [DEBU keybase out_of_date.go:18] 023 Ignoring error in printOutOfDateWarnings: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:10.626128 ▶ [DEBU keybase out_of_date.go:19] 024 - PrintOutOfDateWarnings
2016-12-15T08:19:10.727192 ▶ [DEBU keybase globals.go:345] 025 Calling shutdown first time through
2016-12-15T08:19:10.727352 ▶ [DEBU keybase login_state.go:1046] 026 + Account “LoginState - Shutdown”
2016-12-15T08:19:10.727688 ▶ [DEBU keybase login_state.go:959] 027 - LoginState: Leaving request loop
2016-12-15T08:19:10.727723 ▶ [DEBU keybase login_state.go:1048] 028 - Account “LoginState - Shutdown”
2016-12-15T08:19:10.727759 ▶ [DEBU keybase globals.go:402] 029 exiting shutdown code=0; err=<nil>

Task (out): {
  “componentResults”: [
    {
      “name”: “updater”,
      “status”: {
        “code”: 0,
        “name”: “OK”,
        “desc”: “OK”,
        “fields”: null
      }
    }
  ],
  “status”: {
    “code”: 0,
    “name”: “OK”,
    “desc”: “OK”,
    “fields”: null
  },
  “fatal”: false
}

Checking launchd status for updater
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase —log-format=file launchd status —format=json updater
Task EOF
Completed
Task (err): 
Task (out): {
  “version”: “”,
  “label”: “keybase.updater”,
  “pid”: “712”,
  “lastExitStatus”: “”,
  “bundleVersion”: “”,
  “installStatus”: 4,
  “installAction”: 1,
  “status”: {
    “code”: 0,
    “name”: “OK”,
    “desc”: “OK”,
    “fields”: null
  }
}

Install: Service
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d —log-format=file install —format=json —components=service —timeout=90s
Task EOF
Completed
Task (err): 2016-12-15T08:19:11.305955 ▶ [DEBU keybase globals.go:310] 001 Created Identify2Cache, max age: 5m0s
2016-12-15T08:19:11.306041 ▶ [DEBU keybase globals.go:313] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2016-12-15T08:19:11.306111 ▶ [DEBU keybase globals.go:315] 003 Created CardCache, max age: 5m0s
2016-12-15T08:19:11.308144 ▶ [DEBU keybase ui.go:561] 004 Can’t set GPG_TTY; discover failed
2016-12-15T08:19:11.308201 ▶ [DEBU keybase globals.go:293] 005 Keybase CLI 1.0.18-20161209203922+7173f3e
2016-12-15T08:19:11.308221 ▶ [DEBU keybase globals.go:293] 006 - Built with go1.7.4
2016-12-15T08:19:11.308234 ▶ [DEBU keybase globals.go:293] 007 - Visit https://keybase.io for more details
2016-12-15T08:19:11.308249 ▶ [DEBU keybase main.go:147] 008 + configureProcesses
2016-12-15T08:19:11.308274 ▶ [DEBU keybase main.go:245] 009 + configureLogging
2016-12-15T08:19:11.308291 ▶ [DEBU keybase main.go:254] 00a Disabling log forwarding
2016-12-15T08:19:11.308305 ▶ [DEBU keybase main.go:247] 00b - configureLogging
2016-12-15T08:19:11.308321 ▶ [DEBU keybase main.go:149] 00c - configureProcesses -> <nil>
2016-12-15T08:19:11.308478 ▶ [DEBU keybase install_osx.go:405] 00d Installing components: [service]
2016-12-15T08:19:11.308623 ▶ [DEBU keybase install_osx.go:496] 00e Using binPath: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase
2016-12-15T08:19:11.308848 ▶ [INFO keybase launchd.go:284] 00f Removing /Users/njm/Library/LaunchAgents/keybase.service.plist
2016-12-15T08:19:11.318598 ▶ [DEBU keybase launchd.go:141] 010 Output (launchctl remove): 
2016-12-15T08:19:11.318665 ▶ [INFO keybase launchd.go:217] 011 Waiting for keybase.service to exit…
2016-12-15T08:19:11.532598 ▶ [INFO keybase launchd.go:160] 012 Stopped keybase.service
2016-12-15T08:19:11.542832 ▶ [DEBU keybase launchd.go:141] 013 Output (launchctl remove): 
2016-12-15T08:19:11.542897 ▶ [DEBU keybase launchd.go:146] 014 Nothing to stop (homebrew.mxcl.keybase)
2016-12-15T08:19:11.542919 ▶ [DEBU keybase install_osx.go:505] 015 Installing Keybase service (keybase.service, timeout=1m30s)
2016-12-15T08:19:11.543095 ▶ [INFO keybase launchd.go:268] 016 Saving /Users/njm/Library/LaunchAgents/keybase.service.plist
2016-12-15T08:19:11.543134 ▶ [DEBU keybase util.go:178] 017 + Writing to /Users/njm/Library/LaunchAgents/keybase.service.plist
2016-12-15T08:19:11.543896 ▶ [DEBU keybase util.go:180] 018 | Temporary file generated: /Users/njm/Library/LaunchAgents/keybase.service.plist.TUEXQGQBUQV6XNDCKWVTT2GQ2QD7ON7J
2016-12-15T08:19:11.544137 ▶ [DEBU keybase util.go:199] 019 - Wrote to /Users/njm/Library/LaunchAgents/keybase.service.plist -> ok
2016-12-15T08:19:11.544241 ▶ [INFO keybase launchd.go:95] 01a Starting keybase.service
2016-12-15T08:19:11.555746 ▶ [DEBU keybase launchd.go:98] 01b Output (launchctl load): 
2016-12-15T08:19:11.555838 ▶ [INFO keybase launchd.go:176] 01c Waiting for keybase.service to be loaded…
2016-12-15T08:19:12.070477 ▶ [DEBU keybase launchd.go:111] 01d Service status: &launchd.ServiceStatus{label:”keybase.service”, pid:”772”, lastExitStatus:””}
2016-12-15T08:19:12.585095 ▶ [DEBU keybase service_info.go:92] 01e Looking for service info file (timeout=1m30s)
2016-12-15T08:19:13.785931 ▶ [DEBU keybase service_info.go:104] 01f Found service info: libkb.ServiceInfo{Version:”1.0.18-20161209203922+7173f3e”, Label:”keybase.service”, Pid:772}
2016-12-15T08:19:13.786256 ▶ [DEBU keybase out_of_date.go:13] 020 + PrintOutOfDateWarnings
2016-12-15T08:19:13.786291 ▶ [DEBU keybase util.go:391] 021 + GetSocket
2016-12-15T08:19:13.786316 ▶ [DEBU keybase util.go:392] 022 - GetSocket -> ok
2016-12-15T08:19:13.786340 ▶ [DEBU keybase socket.go:73] 023 | empty socket wrapper; need a new one
2016-12-15T08:19:13.786368 ▶ [DEBU keybase socket_nix.go:96] 024 Dialing unix:/Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:13.786880 ▶ [DEBU keybase socket_nix.go:96] 025 Dialing unix:/Users/njm/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:13.787051 ▶ [DEBU keybase socket.go:88] 026 | DialSocket -> ERROR: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:13.787084 ▶ [DEBU keybase out_of_date.go:18] 027 Ignoring error in printOutOfDateWarnings: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:13.787111 ▶ [DEBU keybase out_of_date.go:19] 028 - PrintOutOfDateWarnings
2016-12-15T08:19:13.888154 ▶ [DEBU keybase globals.go:345] 029 Calling shutdown first time through
2016-12-15T08:19:13.888226 ▶ [DEBU keybase login_state.go:1046] 02a + Account “LoginState - Shutdown”
2016-12-15T08:19:13.888294 ▶ [DEBU keybase login_state.go:959] 02b - LoginState: Leaving request loop
2016-12-15T08:19:13.888316 ▶ [DEBU keybase login_state.go:1048] 02c - Account “LoginState - Shutdown”
2016-12-15T08:19:13.888382 ▶ [DEBU keybase globals.go:402] 02d exiting shutdown code=0; err=<nil>

Task (out): {
  “componentResults”: [
    {
      “name”: “service”,
      “status”: {
        “code”: 0,
        “name”: “OK”,
        “desc”: “OK”,
        “fields”: null
      }
    }
  ],
  “status”: {
    “code”: 0,
    “name”: “OK”,
    “desc”: “OK”,
    “fields”: null
  },
  “fatal”: false
}

Checking launchd status for service
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase —log-format=file launchd status —format=json service
Task EOF
Completed
Task (err): 
Task (out): {
  “version”: “1.0.18-20161209203922+7173f3e”,
  “label”: “keybase.service”,
  “pid”: “772”,
  “lastExitStatus”: “”,
  “bundleVersion”: “1.0.18-20161209203922+7173f3e”,
  “installStatus”: 4,
  “installAction”: 1,
  “status”: {
    “code”: 0,
    “name”: “OK”,
    “desc”: “OK”,
    “fields”: null
  }
}

Install: Fuse
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d —log-format=file fuse status —bundle-version=3.5.2
Task EOF
Completed
Task (err): 2016-12-15T08:19:14.031695 ▶ [DEBU keybase globals.go:310] 001 Created Identify2Cache, max age: 5m0s
2016-12-15T08:19:14.031788 ▶ [DEBU keybase globals.go:313] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2016-12-15T08:19:14.031821 ▶ [DEBU keybase globals.go:315] 003 Created CardCache, max age: 5m0s
2016-12-15T08:19:14.050845 ▶ [DEBU keybase ui.go:561] 004 Can’t set GPG_TTY; discover failed
2016-12-15T08:19:14.055475 ▶ [DEBU keybase globals.go:293] 005 Keybase CLI 1.0.18-20161209203922+7173f3e
2016-12-15T08:19:14.055561 ▶ [DEBU keybase globals.go:293] 006 - Built with go1.7.4
2016-12-15T08:19:14.055580 ▶ [DEBU keybase globals.go:293] 007 - Visit https://keybase.io for more details
2016-12-15T08:19:14.055598 ▶ [DEBU keybase main.go:147] 008 + configureProcesses
2016-12-15T08:19:14.055639 ▶ [DEBU keybase main.go:245] 009 + configureLogging
2016-12-15T08:19:14.055684 ▶ [DEBU keybase main.go:254] 00a Disabling log forwarding
2016-12-15T08:19:14.055729 ▶ [DEBU keybase main.go:247] 00b - configureLogging
2016-12-15T08:19:14.055752 ▶ [DEBU keybase main.go:149] 00c - configureProcesses -> <nil>
2016-12-15T08:19:14.056577 ▶ [DEBU keybase fuse_status_osx.go:46] 00d No kext info available (kext not loaded)
2016-12-15T08:19:14.056712 ▶ [DEBU keybase fuse_status_osx.go:146] 00e Loading plist: /Library/Filesystems/kbfuse.fs/Contents/Info.plist
2016-12-15T08:19:14.067090 ▶ [DEBU keybase out_of_date.go:13] 00f + PrintOutOfDateWarnings
2016-12-15T08:19:14.067139 ▶ [DEBU keybase util.go:391] 010 + GetSocket
2016-12-15T08:19:14.067168 ▶ [DEBU keybase util.go:392] 011 - GetSocket -> ok
2016-12-15T08:19:14.067184 ▶ [DEBU keybase socket.go:73] 012 | empty socket wrapper; need a new one
2016-12-15T08:19:14.067209 ▶ [DEBU keybase socket_nix.go:96] 013 Dialing unix:/Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:14.067491 ▶ [DEBU keybase socket_nix.go:96] 014 Dialing unix:/Users/njm/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:14.067610 ▶ [DEBU keybase socket.go:88] 015 | DialSocket -> ERROR: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:14.067737 ▶ [DEBU keybase out_of_date.go:18] 016 Ignoring error in printOutOfDateWarnings: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:14.067767 ▶ [DEBU keybase out_of_date.go:19] 017 - PrintOutOfDateWarnings
2016-12-15T08:19:14.172544 ▶ [DEBU keybase globals.go:345] 018 Calling shutdown first time through
2016-12-15T08:19:14.172615 ▶ [DEBU keybase login_state.go:1046] 019 + Account “LoginState - Shutdown”
2016-12-15T08:19:14.172677 ▶ [DEBU keybase login_state.go:959] 01a - LoginState: Leaving request loop
2016-12-15T08:19:14.172702 ▶ [DEBU keybase login_state.go:1048] 01b - Account “LoginState - Shutdown”
2016-12-15T08:19:14.172733 ▶ [DEBU keybase globals.go:402] 01c exiting shutdown code=0; err=<nil>

Task (out): {
  “version”: “3.5.2”,
  “bundleVersion”: “3.5.2”,
  “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
  }
}

Load permissions: 4755
Fuse install is OK
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d —log-format=file fuse status —bundle-version=3.5.2
Task EOF
Completed
Task (err): 2016-12-15T08:19:14.265827 ▶ [DEBU keybase globals.go:310] 001 Created Identify2Cache, max age: 5m0s
2016-12-15T08:19:14.265949 ▶ [DEBU keybase globals.go:313] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2016-12-15T08:19:14.265983 ▶ [DEBU keybase globals.go:315] 003 Created CardCache, max age: 5m0s
2016-12-15T08:19:14.268071 ▶ [DEBU keybase ui.go:561] 004 Can’t set GPG_TTY; discover failed
2016-12-15T08:19:14.268124 ▶ [DEBU keybase globals.go:293] 005 Keybase CLI 1.0.18-20161209203922+7173f3e
2016-12-15T08:19:14.268145 ▶ [DEBU keybase globals.go:293] 006 - Built with go1.7.4
2016-12-15T08:19:14.268159 ▶ [DEBU keybase globals.go:293] 007 - Visit https://keybase.io for more details
2016-12-15T08:19:14.268178 ▶ [DEBU keybase main.go:147] 008 + configureProcesses
2016-12-15T08:19:14.268202 ▶ [DEBU keybase main.go:245] 009 + configureLogging
2016-12-15T08:19:14.268220 ▶ [DEBU keybase main.go:254] 00a Disabling log forwarding
2016-12-15T08:19:14.268236 ▶ [DEBU keybase main.go:247] 00b - configureLogging
2016-12-15T08:19:14.268258 ▶ [DEBU keybase main.go:149] 00c - configureProcesses -> <nil>
2016-12-15T08:19:14.268764 ▶ [DEBU keybase fuse_status_osx.go:46] 00d No kext info available (kext not loaded)
2016-12-15T08:19:14.268839 ▶ [DEBU keybase fuse_status_osx.go:146] 00e Loading plist: /Library/Filesystems/kbfuse.fs/Contents/Info.plist
2016-12-15T08:19:14.278491 ▶ [DEBU keybase out_of_date.go:13] 00f + PrintOutOfDateWarnings
2016-12-15T08:19:14.278543 ▶ [DEBU keybase util.go:391] 010 + GetSocket
2016-12-15T08:19:14.278574 ▶ [DEBU keybase util.go:392] 011 - GetSocket -> ok
2016-12-15T08:19:14.278605 ▶ [DEBU keybase socket.go:73] 012 | empty socket wrapper; need a new one
2016-12-15T08:19:14.278634 ▶ [DEBU keybase socket_nix.go:96] 013 Dialing unix:/Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:14.278933 ▶ [DEBU keybase socket_nix.go:96] 014 Dialing unix:/Users/njm/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:14.279067 ▶ [DEBU keybase socket.go:88] 015 | DialSocket -> ERROR: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:14.279097 ▶ [DEBU keybase out_of_date.go:18] 016 Ignoring error in printOutOfDateWarnings: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:14.279131 ▶ [DEBU keybase out_of_date.go:19] 017 - PrintOutOfDateWarnings
2016-12-15T08:19:14.379567 ▶ [DEBU keybase globals.go:345] 018 Calling shutdown first time through
2016-12-15T08:19:14.379641 ▶ [DEBU keybase login_state.go:1046] 019 + Account “LoginState - Shutdown”
2016-12-15T08:19:14.379703 ▶ [DEBU keybase login_state.go:959] 01a - LoginState: Leaving request loop
2016-12-15T08:19:14.379743 ▶ [DEBU keybase login_state.go:1048] 01b - Account “LoginState - Shutdown”
2016-12-15T08:19:14.379817 ▶ [DEBU keybase globals.go:402] 01c exiting shutdown code=0; err=<nil>

Task (out): {
  “version”: “3.5.2”,
  “bundleVersion”: “3.5.2”,
  “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
  }
}

Install: Mount directory
Mount directory=/keybase, attributes={
    NSFileCreationDate = “2016-12-14 02:32:39 +0000”;
    NSFileExtensionHidden = 0;
    NSFileGroupOwnerAccountID = 20;
    NSFileGroupOwnerAccountName = staff;
    NSFileModificationDate = “2016-12-14 02:32:39 +0000”;
    NSFileOwnerAccountID = 501;
    NSFileOwnerAccountName = njm;
    NSFilePosixPermissions = 384;
    NSFileReferenceCount = 2;
    NSFileSize = 68;
    NSFileSystemFileNumber = 227535930;
    NSFileSystemNumber = 16777220;
    NSFileType = NSFileTypeDirectory;
}
File list favorite enabled (file:///Users/njm/Library/Application%20Support/Keybase/Keybase/)
File list favorites changed: No
Mount directory=/keybase, attributes={
    NSFileCreationDate = “2016-12-14 02:32:39 +0000”;
    NSFileExtensionHidden = 0;
    NSFileGroupOwnerAccountID = 20;
    NSFileGroupOwnerAccountName = staff;
    NSFileModificationDate = “2016-12-14 02:32:39 +0000”;
    NSFileOwnerAccountID = 501;
    NSFileOwnerAccountName = njm;
    NSFilePosixPermissions = 384;
    NSFileReferenceCount = 2;
    NSFileSize = 68;
    NSFileSystemFileNumber = 227535930;
    NSFileSystemNumber = 16777220;
    NSFileType = NSFileTypeDirectory;
}
Install: KBFS
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d —log-format=file install —format=json —components=kbfs —timeout=90s
Task EOF
Completed
Task (err): 2016-12-15T08:19:14.570265 ▶ [DEBU keybase globals.go:310] 001 Created Identify2Cache, max age: 5m0s
2016-12-15T08:19:14.570355 ▶ [DEBU keybase globals.go:313] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2016-12-15T08:19:14.570398 ▶ [DEBU keybase globals.go:315] 003 Created CardCache, max age: 5m0s
2016-12-15T08:19:14.572890 ▶ [DEBU keybase ui.go:561] 004 Can’t set GPG_TTY; discover failed
2016-12-15T08:19:14.572966 ▶ [DEBU keybase globals.go:293] 005 Keybase CLI 1.0.18-20161209203922+7173f3e
2016-12-15T08:19:14.572985 ▶ [DEBU keybase globals.go:293] 006 - Built with go1.7.4
2016-12-15T08:19:14.572999 ▶ [DEBU keybase globals.go:293] 007 - Visit https://keybase.io for more details
2016-12-15T08:19:14.573014 ▶ [DEBU keybase main.go:147] 008 + configureProcesses
2016-12-15T08:19:14.573035 ▶ [DEBU keybase main.go:245] 009 + configureLogging
2016-12-15T08:19:14.573056 ▶ [DEBU keybase main.go:254] 00a Disabling log forwarding
2016-12-15T08:19:14.573071 ▶ [DEBU keybase main.go:247] 00b - configureLogging
2016-12-15T08:19:14.573092 ▶ [DEBU keybase main.go:149] 00c - configureProcesses -> <nil>
2016-12-15T08:19:14.574085 ▶ [DEBU keybase install_osx.go:405] 00d Installing components: [kbfs]
2016-12-15T08:19:14.574364 ▶ [INFO keybase launchd.go:284] 00e Removing /Users/njm/Library/LaunchAgents/keybase.kbfs.plist
2016-12-15T08:19:14.591999 ▶ [DEBU keybase launchd.go:141] 00f Output (launchctl remove): 
2016-12-15T08:19:14.592137 ▶ [INFO keybase launchd.go:217] 010 Waiting for keybase.kbfs to exit…
2016-12-15T08:19:14.807201 ▶ [INFO keybase launchd.go:160] 011 Stopped keybase.kbfs
2016-12-15T08:19:14.817317 ▶ [DEBU keybase launchd.go:141] 012 Output (launchctl remove): 
2016-12-15T08:19:14.817379 ▶ [DEBU keybase launchd.go:146] 013 Nothing to stop (homebrew.mxcl.kbfs)
2016-12-15T08:19:14.817403 ▶ [DEBU keybase install_osx.go:528] 014 Installing KBFS (keybase.kbfs, timeout=1m30s)
2016-12-15T08:19:14.817593 ▶ [INFO keybase launchd.go:268] 015 Saving /Users/njm/Library/LaunchAgents/keybase.kbfs.plist
2016-12-15T08:19:14.817639 ▶ [DEBU keybase util.go:178] 016 + Writing to /Users/njm/Library/LaunchAgents/keybase.kbfs.plist
2016-12-15T08:19:14.818430 ▶ [DEBU keybase util.go:180] 017 | Temporary file generated: /Users/njm/Library/LaunchAgents/keybase.kbfs.plist.NH2U7FTNT743DTNTOMI2CTIBLJCLSIJW
2016-12-15T08:19:14.818695 ▶ [DEBU keybase util.go:199] 018 - Wrote to /Users/njm/Library/LaunchAgents/keybase.kbfs.plist -> ok
2016-12-15T08:19:14.818765 ▶ [INFO keybase launchd.go:95] 019 Starting keybase.kbfs
2016-12-15T08:19:14.830465 ▶ [DEBU keybase launchd.go:98] 01a Output (launchctl load): 
2016-12-15T08:19:14.830617 ▶ [INFO keybase launchd.go:176] 01b Waiting for keybase.kbfs to be loaded…
2016-12-15T08:19:15.344897 ▶ [DEBU keybase launchd.go:111] 01c Service status: &launchd.ServiceStatus{label:”keybase.kbfs”, pid:”833”, lastExitStatus:””}
2016-12-15T08:19:15.858496 ▶ [DEBU keybase out_of_date.go:13] 01d + PrintOutOfDateWarnings
2016-12-15T08:19:15.858550 ▶ [DEBU keybase util.go:391] 01e + GetSocket
2016-12-15T08:19:15.858571 ▶ [DEBU keybase util.go:392] 01f - GetSocket -> ok
2016-12-15T08:19:15.858586 ▶ [DEBU keybase socket.go:73] 020 | empty socket wrapper; need a new one
2016-12-15T08:19:15.858610 ▶ [DEBU keybase socket_nix.go:96] 021 Dialing unix:/Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:15.858898 ▶ [DEBU keybase socket_nix.go:96] 022 Dialing unix:/Users/njm/Library/Caches/Keybase/keybased.sock
2016-12-15T08:19:15.859025 ▶ [DEBU keybase socket.go:88] 023 | DialSocket -> ERROR: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:15.859059 ▶ [DEBU keybase out_of_date.go:18] 024 Ignoring error in printOutOfDateWarnings: There were multiple errors: dial unix /Users/njm/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/njm/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2016-12-15T08:19:15.859093 ▶ [DEBU keybase out_of_date.go:19] 025 - PrintOutOfDateWarnings
2016-12-15T08:19:15.961445 ▶ [DEBU keybase globals.go:345] 026 Calling shutdown first time through
2016-12-15T08:19:15.961529 ▶ [DEBU keybase login_state.go:1046] 027 + Account “LoginState - Shutdown”
2016-12-15T08:19:15.961590 ▶ [DEBU keybase login_state.go:959] 028 - LoginState: Leaving request loop
2016-12-15T08:19:15.961609 ▶ [DEBU keybase login_state.go:1048] 029 - Account “LoginState - Shutdown”
2016-12-15T08:19:15.961659 ▶ [DEBU keybase globals.go:402] 02a exiting shutdown code=0; err=<nil>

Task (out): {
  “componentResults”: [
    {
      “name”: “kbfs”,
      “status”: {
        “code”: 0,
        “name”: “OK”,
        “desc”: “OK”,
        “fields”: null
      }
    }
  ],
  “status”: {
    “code”: 0,
    “name”: “OK”,
    “desc”: “OK”,
    “fields”: null
  },
  “fatal”: false
}

Checking launchd status for kbfs
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase —log-format=file launchd status —format=json kbfs
Task EOF
Completed
Task (err): 
Task (out): {
  “version”: “”,
  “label”: “keybase.kbfs”,
  “pid”: “833”,
  “lastExitStatus”: “”,
  “bundleVersion”: “”,
  “installStatus”: 1,
  “installAction”: 3,
  “status”: {
    “code”: 1803,
    “name”: “ERROR”,
    “desc”: “keybase.kbfs isn’t running (expecting pid=833)”,
    “fields”: null
  }
}

Install: CLI
/Applications/Keybase.app/Contents/SharedSupport/bin/keybase resolved to /Applications/Keybase.app/Contents/SharedSupport/bin/keybase
Install complete
Privileged Helper: Installed, Bundle Version: 1.0.29
Version: 1.0.29
Updater: Installed, PID: 712
Service: Installed, Version: 1.0.18-20161209203922+7173f3e
PID: 772
Fuse: Installed, Version: 3.5.2
Kext ID: com.github.kbfuse.filesystems.kbfuse
Kext Loaded: No
Path: /Library/Filesystems/kbfuse.fs
Mount directory: Installed
KBFS: Error: keybase.kbfs isn’t running (expecting pid=833)
Error, PID: 833
CLI: Installed
skpy commented 7 years ago

I've been getting the same for some time now.

Installer: 1.1.46
Privileged Helper: Installed, Bundle Version: 1.0.29, Version: 1.0.29
Updater: Installed, PID: 55957
Service: Installed, Version: 1.0.18-20170130165558+0fcd377, PID: 55966
Fuse: Installed, Version: 3.5.2, Kext ID: com.github.kbfuse.filesystems.kbfuse, Kext Loaded: No, Path: /Library/Filesystems/kbfuse.fs
Mount directory: Installed
KBFS: Re-Install, Error: keybase.kbfs is not running, Error, Exit Status: 2
CLI: Installed

Log:
Version: 1.1.46
Installables: (
    "Privileged Helper",
    Updater,
    Service,
    Fuse,
    "Mount directory",
    KBFS,
    CLI
)
Install: Privileged Helper
Connecting to keybase.Helper (privileged=1)
Connected
Helper version: {
    version = "1.0.29";
}
Helper version: {
    version = "1.0.29";
}
Install: Updater
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d --log-format=file install --format=json --components=updater --timeout=30s
Task EOF
Completed
Task (err): 2017-02-01T18:37:51.863560 ▶ [DEBU keybase globals.go:352] 001 Created Identify2Cache, max age: 5m0s
2017-02-01T18:37:51.865124 ▶ [DEBU keybase globals.go:355] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2017-02-01T18:37:51.865188 ▶ [DEBU keybase globals.go:357] 003 Created CardCache, max age: 5m0s
2017-02-01T18:37:51.865199 ▶ [DEBU keybase globals.go:359] 004 made a new full self cache
2017-02-01T18:37:51.865210 ▶ [DEBU keybase globals.go:361] 005 made a new cached UPAK loader (timeout=10m0s)
2017-02-01T18:37:51.869207 ▶ [DEBU keybase ui.go:571] 006 Can't set GPG_TTY; discover failed
2017-02-01T18:37:51.869277 ▶ [DEBU keybase globals.go:335] 007 Keybase CLI 1.0.18-20170130165558+0fcd377
2017-02-01T18:37:51.869783 ▶ [DEBU keybase globals.go:335] 008 - Built with go1.7.5
2017-02-01T18:37:51.869792 ▶ [DEBU keybase globals.go:335] 009 - Visit https://keybase.io for more details
2017-02-01T18:37:51.869800 ▶ [DEBU keybase main.go:147] 00a + configureProcesses
2017-02-01T18:37:51.869830 ▶ [DEBU keybase main.go:245] 00b + configureLogging
2017-02-01T18:37:51.869837 ▶ [DEBU keybase main.go:254] 00c Disabling log forwarding
2017-02-01T18:37:51.869843 ▶ [DEBU keybase main.go:247] 00d - configureLogging
2017-02-01T18:37:51.869848 ▶ [DEBU keybase main.go:149] 00e - configureProcesses -> <nil>
2017-02-01T18:37:51.870516 ▶ [DEBU keybase install_osx.go:405] 00f Installing components: [updater]
2017-02-01T18:37:51.871170 ▶ [DEBU keybase install_osx.go:945] 010 Using updater path: /Applications/Keybase.app/Contents/SharedSupport/bin/updater
2017-02-01T18:37:51.876394 ▶ [DEBU keybase launchd.go:141] 011 Output (launchctl remove): 
2017-02-01T18:37:51.876977 ▶ [DEBU keybase launchd.go:146] 012 Nothing to stop (keybase.updater)
2017-02-01T18:37:51.876992 ▶ [DEBU keybase install_osx.go:955] 013 Installing updater service (keybase.updater, timeout=30s)
2017-02-01T18:37:51.877419 ▶ [INFO keybase launchd.go:270] 014 Saving /Users/smerrill/Library/LaunchAgents/keybase.updater.plist
2017-02-01T18:37:51.877991 ▶ [DEBU keybase util.go:181] 015 + Writing to /Users/smerrill/Library/LaunchAgents/keybase.updater.plist
2017-02-01T18:37:51.878956 ▶ [DEBU keybase util.go:183] 016 | Temporary file generated: /Users/smerrill/Library/LaunchAgents/keybase.updater.plist.2SC7YJD3CDKHSN4U6QDJZCOFTCIMVRMN
2017-02-01T18:37:51.879168 ▶ [DEBU keybase util.go:202] 017 - Wrote to /Users/smerrill/Library/LaunchAgents/keybase.updater.plist -> ok
2017-02-01T18:37:51.879214 ▶ [INFO keybase launchd.go:95] 018 Starting keybase.updater
2017-02-01T18:37:51.884139 ▶ [DEBU keybase launchd.go:98] 019 Output (launchctl load): 
2017-02-01T18:37:51.884183 ▶ [INFO keybase launchd.go:176] 01a Waiting for keybase.updater to be loaded...
2017-02-01T18:37:52.397075 ▶ [DEBU keybase launchd.go:111] 01b Service status: &launchd.ServiceStatus{label:"keybase.updater", pid:"55957", lastExitStatus:""}
2017-02-01T18:37:52.909572 ▶ [DEBU keybase out_of_date.go:14] 01c + PrintOutOfDateWarnings
2017-02-01T18:37:52.910236 ▶ [DEBU keybase util.go:402] 01d + GetSocket
2017-02-01T18:37:52.910352 ▶ [DEBU keybase util.go:403] 01e - GetSocket -> ok
2017-02-01T18:37:52.910369 ▶ [DEBU keybase socket.go:73] 01f | empty socket wrapper; need a new one
2017-02-01T18:37:52.911039 ▶ [DEBU keybase socket_nix.go:96] 020 Dialing unix:/Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2017-02-01T18:37:52.912112 ▶ [DEBU keybase socket_nix.go:96] 021 Dialing unix:/Users/smerrill/Library/Caches/Keybase/keybased.sock
2017-02-01T18:37:52.912552 ▶ [DEBU keybase socket.go:88] 022 | DialSocket -> ERROR: There were multiple errors: dial unix /Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/smerrill/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2017-02-01T18:37:52.912575 ▶ [DEBU keybase out_of_date.go:19] 023 Ignoring error in printOutOfDateWarnings: There were multiple errors: dial unix /Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/smerrill/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2017-02-01T18:37:52.912633 ▶ [DEBU keybase out_of_date.go:20] 024 - PrintOutOfDateWarnings
2017-02-01T18:37:53.015708 ▶ [DEBU keybase globals.go:423] 025 Calling shutdown first time through
2017-02-01T18:37:53.015846 ▶ [DEBU keybase login_state.go:1045] 026 + Account "LoginState - Shutdown"
2017-02-01T18:37:53.015900 ▶ [DEBU keybase login_state.go:958] 027 - LoginState: Leaving request loop
2017-02-01T18:37:53.015916 ▶ [DEBU keybase login_state.go:1047] 028 - Account "LoginState - Shutdown"
2017-02-01T18:37:53.015955 ▶ [DEBU keybase globals.go:480] 029 exiting shutdown code=0; err=<nil>

Task (out): {
  "componentResults": [
    {
      "name": "updater",
      "status": {
        "code": 0,
        "name": "OK",
        "desc": "OK",
        "fields": null
      }
    }
  ],
  "status": {
    "code": 0,
    "name": "OK",
    "desc": "OK",
    "fields": null
  },
  "fatal": false
}

Checking launchd status for updater
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase --log-format=file launchd status --format=json updater
Task EOF
Completed
Task (err): 
Task (out): {
  "version": "",
  "label": "keybase.updater",
  "pid": "55957",
  "lastExitStatus": "",
  "bundleVersion": "",
  "installStatus": 4,
  "installAction": 1,
  "status": {
    "code": 0,
    "name": "OK",
    "desc": "OK",
    "fields": null
  }
}

Install: Service
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d --log-format=file install --format=json --components=service --timeout=30s
Task EOF
Completed
Task (err): 2017-02-01T18:37:53.571172 ▶ [DEBU keybase globals.go:352] 001 Created Identify2Cache, max age: 5m0s
2017-02-01T18:37:53.571235 ▶ [DEBU keybase globals.go:355] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2017-02-01T18:37:53.571256 ▶ [DEBU keybase globals.go:357] 003 Created CardCache, max age: 5m0s
2017-02-01T18:37:53.571275 ▶ [DEBU keybase globals.go:359] 004 made a new full self cache
2017-02-01T18:37:53.571284 ▶ [DEBU keybase globals.go:361] 005 made a new cached UPAK loader (timeout=10m0s)
2017-02-01T18:37:53.573753 ▶ [DEBU keybase ui.go:571] 006 Can't set GPG_TTY; discover failed
2017-02-01T18:37:53.573778 ▶ [DEBU keybase globals.go:335] 007 Keybase CLI 1.0.18-20170130165558+0fcd377
2017-02-01T18:37:53.573787 ▶ [DEBU keybase globals.go:335] 008 - Built with go1.7.5
2017-02-01T18:37:53.573808 ▶ [DEBU keybase globals.go:335] 009 - Visit https://keybase.io for more details
2017-02-01T18:37:53.573815 ▶ [DEBU keybase main.go:147] 00a + configureProcesses
2017-02-01T18:37:53.573824 ▶ [DEBU keybase main.go:245] 00b + configureLogging
2017-02-01T18:37:53.573834 ▶ [DEBU keybase main.go:254] 00c Disabling log forwarding
2017-02-01T18:37:53.573840 ▶ [DEBU keybase main.go:247] 00d - configureLogging
2017-02-01T18:37:53.573846 ▶ [DEBU keybase main.go:149] 00e - configureProcesses -> <nil>
2017-02-01T18:37:53.573937 ▶ [DEBU keybase install_osx.go:405] 00f Installing components: [service]
2017-02-01T18:37:53.574007 ▶ [DEBU keybase install_osx.go:512] 010 Using binPath: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase
2017-02-01T18:37:53.577986 ▶ [DEBU keybase launchd.go:141] 011 Output (launchctl remove): 
2017-02-01T18:37:53.578025 ▶ [DEBU keybase launchd.go:146] 012 Nothing to stop (keybase.service)
2017-02-01T18:37:53.581397 ▶ [DEBU keybase launchd.go:141] 013 Output (launchctl remove): 
2017-02-01T18:37:53.581432 ▶ [DEBU keybase launchd.go:146] 014 Nothing to stop (homebrew.mxcl.keybase)
2017-02-01T18:37:53.581441 ▶ [DEBU keybase install_osx.go:521] 015 Installing service (keybase.service, timeout=30s)
2017-02-01T18:37:53.581539 ▶ [INFO keybase launchd.go:270] 016 Saving /Users/smerrill/Library/LaunchAgents/keybase.service.plist
2017-02-01T18:37:53.581560 ▶ [DEBU keybase util.go:181] 017 + Writing to /Users/smerrill/Library/LaunchAgents/keybase.service.plist
2017-02-01T18:37:53.581946 ▶ [DEBU keybase util.go:183] 018 | Temporary file generated: /Users/smerrill/Library/LaunchAgents/keybase.service.plist.3WHHUK2KC5YCDCGSJFQ4S4WPMWRG3PDI
2017-02-01T18:37:53.582119 ▶ [DEBU keybase util.go:202] 019 - Wrote to /Users/smerrill/Library/LaunchAgents/keybase.service.plist -> ok
2017-02-01T18:37:53.582149 ▶ [INFO keybase launchd.go:95] 01a Starting keybase.service
2017-02-01T18:37:53.586448 ▶ [DEBU keybase launchd.go:98] 01b Output (launchctl load): 
2017-02-01T18:37:53.586489 ▶ [INFO keybase launchd.go:176] 01c Waiting for keybase.service to be loaded...
2017-02-01T18:37:54.095305 ▶ [DEBU keybase launchd.go:111] 01d Service status: &launchd.ServiceStatus{label:"keybase.service", pid:"55966", lastExitStatus:""}
2017-02-01T18:37:54.609036 ▶ [DEBU keybase service_info.go:92] 01e Looking for service info file (timeout=30s)
2017-02-01T18:37:55.010609 ▶ [DEBU keybase service_info.go:104] 01f Found service info: libkb.ServiceInfo{Version:"1.0.18-20170130165558+0fcd377", Label:"keybase.service", Pid:55966}
2017-02-01T18:37:55.010793 ▶ [DEBU keybase out_of_date.go:14] 020 + PrintOutOfDateWarnings
2017-02-01T18:37:55.010818 ▶ [DEBU keybase util.go:402] 021 + GetSocket
2017-02-01T18:37:55.010836 ▶ [DEBU keybase util.go:403] 022 - GetSocket -> ok
2017-02-01T18:37:55.010847 ▶ [DEBU keybase socket.go:73] 023 | empty socket wrapper; need a new one
2017-02-01T18:37:55.010865 ▶ [DEBU keybase socket_nix.go:96] 024 Dialing unix:/Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2017-02-01T18:37:55.010977 ▶ [DEBU keybase socket.go:88] 025 | DialSocket -> ok
2017-02-01T18:37:55.091639 ▶ [DEBU keybase out_of_date.go:28] 026 Got OutOfDateInfo: keybase1.OutOfDateInfo{UpgradeTo:"", UpgradeURI:"", CustomMessage:"", CriticalClockSkew:0}
2017-02-01T18:37:55.091687 ▶ [DEBU keybase out_of_date.go:44] 027 - PrintOutOfDateWarnings
2017-02-01T18:37:55.192387 ▶ [DEBU keybase globals.go:423] 028 Calling shutdown first time through
2017-02-01T18:37:55.192572 ▶ [DEBU keybase login_state.go:1045] 029 + Account "LoginState - Shutdown"
2017-02-01T18:37:55.192648 ▶ [DEBU keybase login_state.go:958] 02a - LoginState: Leaving request loop
2017-02-01T18:37:55.192672 ▶ [DEBU keybase login_state.go:1047] 02b - Account "LoginState - Shutdown"
2017-02-01T18:37:55.192732 ▶ [DEBU keybase globals.go:480] 02c exiting shutdown code=0; err=<nil>

Task (out): {
  "componentResults": [
    {
      "name": "service",
      "status": {
        "code": 0,
        "name": "OK",
        "desc": "OK",
        "fields": null
      }
    }
  ],
  "status": {
    "code": 0,
    "name": "OK",
    "desc": "OK",
    "fields": null
  },
  "fatal": false
}

Checking launchd status for service
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase --log-format=file launchd status --format=json service
Task EOF
Completed
Task (err): 
Task (out): {
  "version": "1.0.18-20170130165558+0fcd377",
  "label": "keybase.service",
  "pid": "55966",
  "lastExitStatus": "",
  "bundleVersion": "1.0.18-20170130165558+0fcd377",
  "installStatus": 4,
  "installAction": 1,
  "status": {
    "code": 0,
    "name": "OK",
    "desc": "OK",
    "fields": null
  }
}

Install: Fuse
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d --log-format=file fuse status --bundle-version=3.5.2
Task EOF
Completed
Task (err): 2017-02-01T18:37:56.176114 ▶ [DEBU keybase globals.go:352] 001 Created Identify2Cache, max age: 5m0s
2017-02-01T18:37:56.176177 ▶ [DEBU keybase globals.go:355] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2017-02-01T18:37:56.176199 ▶ [DEBU keybase globals.go:357] 003 Created CardCache, max age: 5m0s
2017-02-01T18:37:56.176213 ▶ [DEBU keybase globals.go:359] 004 made a new full self cache
2017-02-01T18:37:56.176221 ▶ [DEBU keybase globals.go:361] 005 made a new cached UPAK loader (timeout=10m0s)
2017-02-01T18:37:56.178735 ▶ [DEBU keybase ui.go:571] 006 Can't set GPG_TTY; discover failed
2017-02-01T18:37:56.178776 ▶ [DEBU keybase globals.go:335] 007 Keybase CLI 1.0.18-20170130165558+0fcd377
2017-02-01T18:37:56.178789 ▶ [DEBU keybase globals.go:335] 008 - Built with go1.7.5
2017-02-01T18:37:56.178799 ▶ [DEBU keybase globals.go:335] 009 - Visit https://keybase.io for more details
2017-02-01T18:37:56.178806 ▶ [DEBU keybase main.go:147] 00a + configureProcesses
2017-02-01T18:37:56.178817 ▶ [DEBU keybase main.go:245] 00b + configureLogging
2017-02-01T18:37:56.178825 ▶ [DEBU keybase main.go:254] 00c Disabling log forwarding
2017-02-01T18:37:56.178832 ▶ [DEBU keybase main.go:247] 00d - configureLogging
2017-02-01T18:37:56.178863 ▶ [DEBU keybase main.go:149] 00e - configureProcesses -> <nil>
2017-02-01T18:37:56.179265 ▶ [DEBU keybase fuse_status_osx.go:46] 00f No kext info available (kext not loaded)
2017-02-01T18:37:56.179371 ▶ [DEBU keybase fuse_status_osx.go:146] 010 Loading plist: /Library/Filesystems/kbfuse.fs/Contents/Info.plist
2017-02-01T18:37:56.184048 ▶ [DEBU keybase out_of_date.go:14] 011 + PrintOutOfDateWarnings
2017-02-01T18:37:56.184087 ▶ [DEBU keybase util.go:402] 012 + GetSocket
2017-02-01T18:37:56.184106 ▶ [DEBU keybase util.go:403] 013 - GetSocket -> ok
2017-02-01T18:37:56.184116 ▶ [DEBU keybase socket.go:73] 014 | empty socket wrapper; need a new one
2017-02-01T18:37:56.184129 ▶ [DEBU keybase socket_nix.go:96] 015 Dialing unix:/Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2017-02-01T18:37:56.184208 ▶ [DEBU keybase socket.go:88] 016 | DialSocket -> ok
2017-02-01T18:37:56.184927 ▶ [DEBU keybase out_of_date.go:28] 017 Got OutOfDateInfo: keybase1.OutOfDateInfo{UpgradeTo:"", UpgradeURI:"", CustomMessage:"", CriticalClockSkew:0}
2017-02-01T18:37:56.184949 ▶ [DEBU keybase out_of_date.go:44] 018 - PrintOutOfDateWarnings
2017-02-01T18:37:56.286885 ▶ [DEBU keybase globals.go:423] 019 Calling shutdown first time through
2017-02-01T18:37:56.287016 ▶ [DEBU keybase login_state.go:1045] 01a + Account "LoginState - Shutdown"
2017-02-01T18:37:56.287073 ▶ [DEBU keybase login_state.go:958] 01b - LoginState: Leaving request loop
2017-02-01T18:37:56.287093 ▶ [DEBU keybase login_state.go:1047] 01c - Account "LoginState - Shutdown"
2017-02-01T18:37:56.287121 ▶ [DEBU keybase globals.go:480] 01d exiting shutdown code=0; err=<nil>

Task (out): {
  "version": "3.5.2",
  "bundleVersion": "3.5.2",
  "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
  }
}

Load permissions: 4755
Fuse install is OK
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d --log-format=file fuse status --bundle-version=3.5.2
Task EOF
Completed
Task (err): 2017-02-01T18:37:56.323248 ▶ [DEBU keybase globals.go:352] 001 Created Identify2Cache, max age: 5m0s
2017-02-01T18:37:56.323305 ▶ [DEBU keybase globals.go:355] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2017-02-01T18:37:56.323326 ▶ [DEBU keybase globals.go:357] 003 Created CardCache, max age: 5m0s
2017-02-01T18:37:56.323341 ▶ [DEBU keybase globals.go:359] 004 made a new full self cache
2017-02-01T18:37:56.323348 ▶ [DEBU keybase globals.go:361] 005 made a new cached UPAK loader (timeout=10m0s)
2017-02-01T18:37:56.326078 ▶ [DEBU keybase ui.go:571] 006 Can't set GPG_TTY; discover failed
2017-02-01T18:37:56.326125 ▶ [DEBU keybase globals.go:335] 007 Keybase CLI 1.0.18-20170130165558+0fcd377
2017-02-01T18:37:56.326136 ▶ [DEBU keybase globals.go:335] 008 - Built with go1.7.5
2017-02-01T18:37:56.326141 ▶ [DEBU keybase globals.go:335] 009 - Visit https://keybase.io for more details
2017-02-01T18:37:56.326153 ▶ [DEBU keybase main.go:147] 00a + configureProcesses
2017-02-01T18:37:56.326164 ▶ [DEBU keybase main.go:245] 00b + configureLogging
2017-02-01T18:37:56.326171 ▶ [DEBU keybase main.go:254] 00c Disabling log forwarding
2017-02-01T18:37:56.326178 ▶ [DEBU keybase main.go:247] 00d - configureLogging
2017-02-01T18:37:56.326183 ▶ [DEBU keybase main.go:149] 00e - configureProcesses -> <nil>
2017-02-01T18:37:56.326490 ▶ [DEBU keybase fuse_status_osx.go:46] 00f No kext info available (kext not loaded)
2017-02-01T18:37:56.326529 ▶ [DEBU keybase fuse_status_osx.go:146] 010 Loading plist: /Library/Filesystems/kbfuse.fs/Contents/Info.plist
2017-02-01T18:37:56.331309 ▶ [DEBU keybase out_of_date.go:14] 011 + PrintOutOfDateWarnings
2017-02-01T18:37:56.331353 ▶ [DEBU keybase util.go:402] 012 + GetSocket
2017-02-01T18:37:56.331377 ▶ [DEBU keybase util.go:403] 013 - GetSocket -> ok
2017-02-01T18:37:56.331389 ▶ [DEBU keybase socket.go:73] 014 | empty socket wrapper; need a new one
2017-02-01T18:37:56.331406 ▶ [DEBU keybase socket_nix.go:96] 015 Dialing unix:/Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2017-02-01T18:37:56.331498 ▶ [DEBU keybase socket.go:88] 016 | DialSocket -> ok
2017-02-01T18:37:56.332399 ▶ [DEBU keybase out_of_date.go:28] 017 Got OutOfDateInfo: keybase1.OutOfDateInfo{UpgradeTo:"", UpgradeURI:"", CustomMessage:"", CriticalClockSkew:0}
2017-02-01T18:37:56.332440 ▶ [DEBU keybase out_of_date.go:44] 018 - PrintOutOfDateWarnings
2017-02-01T18:37:56.437672 ▶ [DEBU keybase globals.go:423] 019 Calling shutdown first time through
2017-02-01T18:37:56.437816 ▶ [DEBU keybase login_state.go:1045] 01a + Account "LoginState - Shutdown"
2017-02-01T18:37:56.437924 ▶ [DEBU keybase login_state.go:958] 01b - LoginState: Leaving request loop
2017-02-01T18:37:56.437943 ▶ [DEBU keybase login_state.go:1047] 01c - Account "LoginState - Shutdown"
2017-02-01T18:37:56.438023 ▶ [DEBU keybase globals.go:480] 01d exiting shutdown code=0; err=<nil>

Task (out): {
  "version": "3.5.2",
  "bundleVersion": "3.5.2",
  "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
  }
}

Install: Mount directory
Mount directory doesn't exist: /keybase
Creating mount directory: {
    directory = "/keybase";
    excludeFromBackup = 1;
    gid = 1988998316;
    permissions = 384;
    uid = 24220575;
}
Mount directory=/keybase, attributes={
    NSFileCreationDate = "2017-02-01 23:37:56 +0000";
    NSFileExtensionHidden = 0;
    NSFileGroupOwnerAccountID = 1988998316;
    NSFileModificationDate = "2017-02-01 23:37:56 +0000";
    NSFileOwnerAccountID = 24220575;
    NSFileOwnerAccountName = smerrill;
    NSFilePosixPermissions = 384;
    NSFileReferenceCount = 2;
    NSFileSize = 68;
    NSFileSystemFileNumber = 69998696;
    NSFileSystemNumber = 16777220;
    NSFileType = NSFileTypeDirectory;
}
File list favorite enabled (file:///Users/smerrill/Library/Application%20Support/Keybase/Keybase/)
File list favorites changed: Yes
Mount directory=/keybase, attributes={
    NSFileCreationDate = "2017-02-01 23:37:56 +0000";
    NSFileExtensionHidden = 0;
    NSFileGroupOwnerAccountID = 1988998316;
    NSFileModificationDate = "2017-02-01 23:37:56 +0000";
    NSFileOwnerAccountID = 24220575;
    NSFileOwnerAccountName = smerrill;
    NSFilePosixPermissions = 384;
    NSFileReferenceCount = 2;
    NSFileSize = 68;
    NSFileSystemFileNumber = 69998696;
    NSFileSystemNumber = 16777220;
    NSFileType = NSFileTypeDirectory;
}
Install: KBFS
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d --log-format=file install --format=json --components=kbfs --timeout=30s
Task EOF
Completed
Task (err): 2017-02-01T18:37:56.484567 ▶ [DEBU keybase globals.go:352] 001 Created Identify2Cache, max age: 5m0s
2017-02-01T18:37:56.484629 ▶ [DEBU keybase globals.go:355] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2017-02-01T18:37:56.484648 ▶ [DEBU keybase globals.go:357] 003 Created CardCache, max age: 5m0s
2017-02-01T18:37:56.484664 ▶ [DEBU keybase globals.go:359] 004 made a new full self cache
2017-02-01T18:37:56.484679 ▶ [DEBU keybase globals.go:361] 005 made a new cached UPAK loader (timeout=10m0s)
2017-02-01T18:37:56.487164 ▶ [DEBU keybase ui.go:571] 006 Can't set GPG_TTY; discover failed
2017-02-01T18:37:56.487197 ▶ [DEBU keybase globals.go:335] 007 Keybase CLI 1.0.18-20170130165558+0fcd377
2017-02-01T18:37:56.487220 ▶ [DEBU keybase globals.go:335] 008 - Built with go1.7.5
2017-02-01T18:37:56.487227 ▶ [DEBU keybase globals.go:335] 009 - Visit https://keybase.io for more details
2017-02-01T18:37:56.487234 ▶ [DEBU keybase main.go:147] 00a + configureProcesses
2017-02-01T18:37:56.487245 ▶ [DEBU keybase main.go:245] 00b + configureLogging
2017-02-01T18:37:56.487253 ▶ [DEBU keybase main.go:254] 00c Disabling log forwarding
2017-02-01T18:37:56.487259 ▶ [DEBU keybase main.go:247] 00d - configureLogging
2017-02-01T18:37:56.487264 ▶ [DEBU keybase main.go:149] 00e - configureProcesses -> <nil>
2017-02-01T18:37:56.487338 ▶ [DEBU keybase install_osx.go:405] 00f Installing components: [kbfs]
2017-02-01T18:37:56.491301 ▶ [DEBU keybase launchd.go:141] 010 Output (launchctl remove): 
2017-02-01T18:37:56.491339 ▶ [DEBU keybase launchd.go:146] 011 Nothing to stop (keybase.kbfs)
2017-02-01T18:37:56.494993 ▶ [DEBU keybase launchd.go:141] 012 Output (launchctl remove): 
2017-02-01T18:37:56.495036 ▶ [DEBU keybase launchd.go:146] 013 Nothing to stop (homebrew.mxcl.kbfs)
2017-02-01T18:37:56.495045 ▶ [DEBU keybase install_osx.go:544] 014 Installing KBFS (keybase.kbfs, timeout=30s)
2017-02-01T18:37:56.495171 ▶ [INFO keybase launchd.go:270] 015 Saving /Users/smerrill/Library/LaunchAgents/keybase.kbfs.plist
2017-02-01T18:37:56.495194 ▶ [DEBU keybase util.go:181] 016 + Writing to /Users/smerrill/Library/LaunchAgents/keybase.kbfs.plist
2017-02-01T18:37:56.495598 ▶ [DEBU keybase util.go:183] 017 | Temporary file generated: /Users/smerrill/Library/LaunchAgents/keybase.kbfs.plist.XMYGQWURP6NYFCF3TYRP3HRZMDREAJQ7
2017-02-01T18:37:56.495729 ▶ [DEBU keybase util.go:202] 018 - Wrote to /Users/smerrill/Library/LaunchAgents/keybase.kbfs.plist -> ok
2017-02-01T18:37:56.495759 ▶ [INFO keybase launchd.go:95] 019 Starting keybase.kbfs
2017-02-01T18:37:56.500058 ▶ [DEBU keybase launchd.go:98] 01a Output (launchctl load): 
2017-02-01T18:37:56.500102 ▶ [INFO keybase launchd.go:176] 01b Waiting for keybase.kbfs to be loaded...
2017-02-01T18:37:57.010332 ▶ [DEBU keybase launchd.go:111] 01c Service status: &launchd.ServiceStatus{label:"keybase.kbfs", pid:"55979", lastExitStatus:""}
2017-02-01T18:37:57.517265 ▶ [DEBU keybase out_of_date.go:14] 01d + PrintOutOfDateWarnings
2017-02-01T18:37:57.517302 ▶ [DEBU keybase util.go:402] 01e + GetSocket
2017-02-01T18:37:57.517316 ▶ [DEBU keybase util.go:403] 01f - GetSocket -> ok
2017-02-01T18:37:57.517326 ▶ [DEBU keybase socket.go:73] 020 | empty socket wrapper; need a new one
2017-02-01T18:37:57.517340 ▶ [DEBU keybase socket_nix.go:96] 021 Dialing unix:/Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2017-02-01T18:37:57.517454 ▶ [DEBU keybase socket.go:88] 022 | DialSocket -> ok
2017-02-01T18:37:57.518105 ▶ [DEBU keybase out_of_date.go:28] 023 Got OutOfDateInfo: keybase1.OutOfDateInfo{UpgradeTo:"", UpgradeURI:"", CustomMessage:"", CriticalClockSkew:0}
2017-02-01T18:37:57.518133 ▶ [DEBU keybase out_of_date.go:44] 024 - PrintOutOfDateWarnings
2017-02-01T18:37:57.618587 ▶ [DEBU keybase globals.go:423] 025 Calling shutdown first time through
2017-02-01T18:37:57.618663 ▶ [DEBU keybase login_state.go:1045] 026 + Account "LoginState - Shutdown"
2017-02-01T18:37:57.618722 ▶ [DEBU keybase login_state.go:958] 027 - LoginState: Leaving request loop
2017-02-01T18:37:57.618753 ▶ [DEBU keybase login_state.go:1047] 028 - Account "LoginState - Shutdown"
2017-02-01T18:37:57.618860 ▶ [DEBU keybase globals.go:480] 029 exiting shutdown code=0; err=<nil>

Task (out): {
  "componentResults": [
    {
      "name": "kbfs",
      "status": {
        "code": 0,
        "name": "OK",
        "desc": "OK",
        "fields": null
      }
    }
  ],
  "status": {
    "code": 0,
    "name": "OK",
    "desc": "OK",
    "fields": null
  },
  "fatal": false
}

Checking launchd status for kbfs
Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase --log-format=file launchd status --format=json kbfs
Task EOF
Completed
Task (err): 
Task (out): {
  "version": "",
  "label": "keybase.kbfs",
  "pid": "",
  "lastExitStatus": "2",
  "bundleVersion": "",
  "installStatus": 1,
  "installAction": 3,
  "status": {
    "code": 1803,
    "name": "ERROR",
    "desc": "keybase.kbfs is not running",
    "fields": null
  }
}

Install: CLI
/usr/local/bin/keybase resolved to /Applications/Keybase.app/Contents/SharedSupport/bin/keybase
Install complete
Privileged Helper: Installed, Bundle Version: 1.0.29
Version: 1.0.29
Updater: Installed, PID: 55957
Service: Installed, Version: 1.0.18-20170130165558+0fcd377
PID: 55966
Fuse: Installed, Version: 3.5.2
Kext ID: com.github.kbfuse.filesystems.kbfuse
Kext Loaded: No
Path: /Library/Filesystems/kbfuse.fs
Mount directory: Installed
KBFS: Error: keybase.kbfs is not running
Error, Exit Status: 2
CLI: Installed
skpy commented 7 years ago

When I try to manually open the keybase folder from the Finder, I get a permission denied error.

screen shot 2017-02-01 at 6 45 37 pm
cjb commented 7 years ago

Thanks, CC @gabriel

skpy commented 7 years ago

I submitted keybase log send, with log ID 0b89e7c244953da449d6be1c

gabriel commented 7 years ago

Oh hmm this is another exit status 255 issue when mounting

gabriel commented 7 years ago

What is the output of:

kextstat | grep kbfuse

What happens if you run:

/Applications/Keybase.app/Contents/Resources/KeybaseInstaller.app/Contents/MacOS/Keybase --install-fuse --timeout=60 --run-mode=prod --app-path=/Applications/Keybase.app

After running this what is output of kextstat | grep kbfuse again?

njm2112 commented 7 years ago

I noticed that I was having trouble with a number of other applications that attempt to auto-mount at machine startup (e.g., Boxcryptor, ExpanDrive). After a lot of digging around, I read something about conflicts with kext caused by Virtual Box. After I removed Virtual Box entirely (including some extra command-line work needed to completely get everything off the system), all of my auto-mount applications, including Keybase.app, seem to be working fine and I don't receive the original error I reported anymore.

skpy commented 7 years ago 
~ smerrill $ kextstat | grep kbfuse
~ smerrill $
~ smerrill $ /Applications/Keybase.app/Contents/Resources/KeybaseInstaller.app/Contents/MacOS/Keybase --install-fuse --timeout=60 --run-mode=prod --app-path=/Applications/Keybase.app

02.02.2017 10:48:23.407 Installer:42[DEBG] Version: 1.1.46
02.02.2017 10:48:23.426 KBInstaller:27[DEBG] Installables: (
    Fuse
)
02.02.2017 10:48:23.426 KBInstaller:32[DEBG] Install: Fuse
02.02.2017 10:48:23.426 KBTask:114[DEBG] Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d --log-format=file fuse status --bundle-version=3.5.2
02.02.2017 10:48:23.556 KBTask:55[DEBG] Task EOF
02.02.2017 10:48:23.556 KBTask:70[DEBG] Completed
02.02.2017 10:48:23.556 KBTask:72[DEBG] Task (err): 2017-02-02T10:48:23.439498 ▶ [DEBU keybase globals.go:352] 001 Created Identify2Cache, max age: 5m0s
2017-02-02T10:48:23.439565 ▶ [DEBU keybase globals.go:355] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2017-02-02T10:48:23.439583 ▶ [DEBU keybase globals.go:357] 003 Created CardCache, max age: 5m0s
2017-02-02T10:48:23.439594 ▶ [DEBU keybase globals.go:359] 004 made a new full self cache
2017-02-02T10:48:23.439604 ▶ [DEBU keybase globals.go:361] 005 made a new cached UPAK loader (timeout=10m0s)
2017-02-02T10:48:23.442389 ▶ [DEBU keybase ui.go:571] 006 Can't set GPG_TTY; discover failed
2017-02-02T10:48:23.442444 ▶ [DEBU keybase globals.go:335] 007 Keybase CLI 1.0.18-20170131120020+3f505f9
2017-02-02T10:48:23.442455 ▶ [DEBU keybase globals.go:335] 008 - Built with go1.7.5
2017-02-02T10:48:23.442460 ▶ [DEBU keybase globals.go:335] 009 - Visit https://keybase.io for more details
2017-02-02T10:48:23.442468 ▶ [DEBU keybase main.go:147] 00a + configureProcesses
2017-02-02T10:48:23.442492 ▶ [DEBU keybase main.go:245] 00b + configureLogging
2017-02-02T10:48:23.442500 ▶ [DEBU keybase main.go:254] 00c Disabling log forwarding
2017-02-02T10:48:23.442508 ▶ [DEBU keybase main.go:247] 00d - configureLogging
2017-02-02T10:48:23.442518 ▶ [DEBU keybase main.go:149] 00e - configureProcesses -> <nil>
2017-02-02T10:48:23.442849 ▶ [DEBU keybase fuse_status_osx.go:46] 00f No kext info available (kext not loaded)
2017-02-02T10:48:23.442880 ▶ [DEBU keybase fuse_status_osx.go:146] 010 Loading plist: /Library/Filesystems/kbfuse.fs/Contents/Info.plist
2017-02-02T10:48:23.447289 ▶ [DEBU keybase out_of_date.go:14] 011 + PrintOutOfDateWarnings
2017-02-02T10:48:23.447321 ▶ [DEBU keybase util.go:402] 012 + GetSocket
2017-02-02T10:48:23.447338 ▶ [DEBU keybase util.go:403] 013 - GetSocket -> ok
2017-02-02T10:48:23.447351 ▶ [DEBU keybase socket.go:73] 014 | empty socket wrapper; need a new one
2017-02-02T10:48:23.447366 ▶ [DEBU keybase socket_nix.go:96] 015 Dialing unix:/Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2017-02-02T10:48:23.448003 ▶ [DEBU keybase socket_nix.go:96] 016 Dialing unix:/Users/smerrill/Library/Caches/Keybase/keybased.sock
2017-02-02T10:48:23.448385 ▶ [DEBU keybase socket.go:88] 017 | DialSocket -> ERROR: There were multiple errors: dial unix /Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/smerrill/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2017-02-02T10:48:23.448405 ▶ [DEBU keybase out_of_date.go:19] 018 Ignoring error in printOutOfDateWarnings: There were multiple errors: dial unix /Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/smerrill/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2017-02-02T10:48:23.448423 ▶ [DEBU keybase out_of_date.go:20] 019 - PrintOutOfDateWarnings
2017-02-02T10:48:23.552145 ▶ [DEBU keybase globals.go:423] 01a Calling shutdown first time through
2017-02-02T10:48:23.552230 ▶ [DEBU keybase login_state.go:1045] 01b + Account "LoginState - Shutdown"
2017-02-02T10:48:23.552288 ▶ [DEBU keybase login_state.go:958] 01c - LoginState: Leaving request loop
2017-02-02T10:48:23.552326 ▶ [DEBU keybase login_state.go:1047] 01d - Account "LoginState - Shutdown"
2017-02-02T10:48:23.552412 ▶ [DEBU keybase globals.go:480] 01e exiting shutdown code=0; err=<nil>

02.02.2017 10:48:23.556 KBTask:73[DEBG] Task (out): {
  "version": "3.5.2",
  "bundleVersion": "3.5.2",
  "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
  }
}

02.02.2017 10:48:23.587 KBFuseComponent:202[DEBG] Load permissions: 4755
02.02.2017 10:48:23.587 KBFuseComponent:152[INFO] Fuse install is OK
02.02.2017 10:48:23.587 KBTask:114[DEBG] Task: /Applications/Keybase.app/Contents/SharedSupport/bin/keybase -d --log-format=file fuse status --bundle-version=3.5.2
02.02.2017 10:48:23.712 KBTask:55[DEBG] Task EOF
02.02.2017 10:48:23.713 KBTask:70[DEBG] Completed
02.02.2017 10:48:23.713 KBTask:72[DEBG] Task (err): 2017-02-02T10:48:23.600448 ▶ [DEBU keybase globals.go:352] 001 Created Identify2Cache, max age: 5m0s
2017-02-02T10:48:23.600507 ▶ [DEBU keybase globals.go:355] 002 Created LinkCache, max size: 65536, clean dur: 1m0s
2017-02-02T10:48:23.600526 ▶ [DEBU keybase globals.go:357] 003 Created CardCache, max age: 5m0s
2017-02-02T10:48:23.600542 ▶ [DEBU keybase globals.go:359] 004 made a new full self cache
2017-02-02T10:48:23.600554 ▶ [DEBU keybase globals.go:361] 005 made a new cached UPAK loader (timeout=10m0s)
2017-02-02T10:48:23.602909 ▶ [DEBU keybase ui.go:571] 006 Can't set GPG_TTY; discover failed
2017-02-02T10:48:23.602932 ▶ [DEBU keybase globals.go:335] 007 Keybase CLI 1.0.18-20170131120020+3f505f9
2017-02-02T10:48:23.602947 ▶ [DEBU keybase globals.go:335] 008 - Built with go1.7.5
2017-02-02T10:48:23.602953 ▶ [DEBU keybase globals.go:335] 009 - Visit https://keybase.io for more details
2017-02-02T10:48:23.602959 ▶ [DEBU keybase main.go:147] 00a + configureProcesses
2017-02-02T10:48:23.602983 ▶ [DEBU keybase main.go:245] 00b + configureLogging
2017-02-02T10:48:23.603000 ▶ [DEBU keybase main.go:254] 00c Disabling log forwarding
2017-02-02T10:48:23.603005 ▶ [DEBU keybase main.go:247] 00d - configureLogging
2017-02-02T10:48:23.603011 ▶ [DEBU keybase main.go:149] 00e - configureProcesses -> <nil>
2017-02-02T10:48:23.603324 ▶ [DEBU keybase fuse_status_osx.go:46] 00f No kext info available (kext not loaded)
2017-02-02T10:48:23.603349 ▶ [DEBU keybase fuse_status_osx.go:146] 010 Loading plist: /Library/Filesystems/kbfuse.fs/Contents/Info.plist
2017-02-02T10:48:23.606676 ▶ [DEBU keybase out_of_date.go:14] 011 + PrintOutOfDateWarnings
2017-02-02T10:48:23.606714 ▶ [DEBU keybase util.go:402] 012 + GetSocket
2017-02-02T10:48:23.606738 ▶ [DEBU keybase util.go:403] 013 - GetSocket -> ok
2017-02-02T10:48:23.606750 ▶ [DEBU keybase socket.go:73] 014 | empty socket wrapper; need a new one
2017-02-02T10:48:23.606773 ▶ [DEBU keybase socket_nix.go:96] 015 Dialing unix:/Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock
2017-02-02T10:48:23.606959 ▶ [DEBU keybase socket_nix.go:96] 016 Dialing unix:/Users/smerrill/Library/Caches/Keybase/keybased.sock
2017-02-02T10:48:23.607025 ▶ [DEBU keybase socket.go:88] 017 | DialSocket -> ERROR: There were multiple errors: dial unix /Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/smerrill/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2017-02-02T10:48:23.607042 ▶ [DEBU keybase out_of_date.go:19] 018 Ignoring error in printOutOfDateWarnings: There were multiple errors: dial unix /Users/smerrill/Library/Group Containers/keybase/Library/Caches/Keybase/keybased.sock: connect: no such file or directory; dial unix /Users/smerrill/Library/Caches/Keybase/keybased.sock: connect: no such file or directory
2017-02-02T10:48:23.607054 ▶ [DEBU keybase out_of_date.go:20] 019 - PrintOutOfDateWarnings
2017-02-02T10:48:23.709190 ▶ [DEBU keybase globals.go:423] 01a Calling shutdown first time through
2017-02-02T10:48:23.709250 ▶ [DEBU keybase login_state.go:1045] 01b + Account "LoginState - Shutdown"
2017-02-02T10:48:23.709296 ▶ [DEBU keybase login_state.go:958] 01c - LoginState: Leaving request loop
2017-02-02T10:48:23.709313 ▶ [DEBU keybase login_state.go:1047] 01d - Account "LoginState - Shutdown"
2017-02-02T10:48:23.709344 ▶ [DEBU keybase globals.go:480] 01e exiting shutdown code=0; err=<nil>

02.02.2017 10:48:23.713 KBTask:73[DEBG] Task (out): {
  "version": "3.5.2",
  "bundleVersion": "3.5.2",
  "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
  }
}

02.02.2017 10:48:23.730 KBInstaller:45[INFO] Install complete
02.02.2017 10:48:23.730 KBInstaller:49[INFO] Fuse: Installed, Version: 3.5.2
Kext ID: com.github.kbfuse.filesystems.kbfuse
Kext Loaded: No
Path: /Library/Filesystems/kbfuse.fs
02.02.2017 10:48:23.731 Installer:82[INFO] Exit(0)
~ smerrill $ kextstat | grep kbfuse
~ smerrill $

I do use VirtualBox; currently version 5.1.14 r112924. I can try to remove it, but it's a pretty critical component of my day-to-day professional life.

njm2112 commented 7 years ago

I was very hesitant to remove VirtualBox, as well, but luckily I am able to use CrossOver (or sometimes just Wine directly) as a replacement for VirtualBox. Perhaps another option, though I'm not sure if it will work, is to create a new macOS user profile and install VirtualBox for only that user, then keep Keybase.app running on your primary user profile. If you try it, please let us know whether it addresses the problem or not. Thank you!

gabriel commented 7 years ago

Oh that's interesting, I'll see if I can get it to fail with virtualbox too.

Can you post the output of:

ls -alR /Library/Filesystems/kbfuse.fs

And then run:

/Library/Filesystems/kbfuse.fs/Contents/Resources/load_kbfuse; echo $?

And then check if it's loaded:

kextstat | grep kbfuse
skpy commented 7 years ago 
~ smerrill $ ls -alR /Library/Filesystems/kbfuse.fs
total 0
drwxr-xr-x  3 root  wheel  102 Oct  3 17:32 .
drwxr-xr-x  5 root  wheel  170 Oct  6 17:16 ..
drwxr-xr-x  7 root  wheel  238 Oct  3 17:32 Contents

/Library/Filesystems/kbfuse.fs/Contents:
total 16
drwxr-xr-x  7 root  wheel   238 Oct  3 17:32 .
drwxr-xr-x  3 root  wheel   102 Oct  3 17:32 ..
drwxr-xr-x  5 root  wheel   170 Oct  3 17:32 Extensions
-rwxr-xr-x  1 root  wheel  3280 Oct  3 17:32 Info.plist
drwxr-xr-x  5 root  wheel   170 Oct  3 17:32 Resources
drwxr-xr-x  7 root  wheel   238 Oct  3 17:32 _CodeSignature
-rwxr-xr-x  1 root  wheel   447 Oct  3 17:32 version.plist

/Library/Filesystems/kbfuse.fs/Contents/Extensions:
total 16
drwxr-xr-x  5 root  wheel  170 Oct  3 17:32 .
drwxr-xr-x  7 root  wheel  238 Oct  3 17:32 ..
drwxr-xr-x  3 root  wheel  102 Oct  3 17:32 10.10
lrwxr-xr-x  1 root  wheel    5 Oct  3 17:32 10.11 -> 10.10
lrwxr-xr-x  1 root  wheel    5 Oct  3 17:32 10.12 -> 10.10

/Library/Filesystems/kbfuse.fs/Contents/Extensions/10.10:
total 0
drwxr-xr-x  3 root  wheel  102 Oct  3 17:32 .
drwxr-xr-x  5 root  wheel  170 Oct  3 17:32 ..
drwxr-xr-x  3 root  wheel  102 Oct  3 17:32 kbfuse.kext

/Library/Filesystems/kbfuse.fs/Contents/Extensions/10.10/kbfuse.kext:
total 0
drwxr-xr-x  3 root  wheel  102 Oct  3 17:32 .
drwxr-xr-x  3 root  wheel  102 Oct  3 17:32 ..
drwxr-xr-x  5 root  wheel  170 Oct  3 17:32 Contents

/Library/Filesystems/kbfuse.fs/Contents/Extensions/10.10/kbfuse.kext/Contents:
total 8
drwxr-xr-x  5 root  wheel   170 Oct  3 17:32 .
drwxr-xr-x  3 root  wheel   102 Oct  3 17:32 ..
-rwxr-xr-x  1 root  wheel  1529 Oct  3 17:32 Info.plist
drwxr-xr-x  3 root  wheel   102 Oct  3 17:32 MacOS
drwxr-xr-x  3 root  wheel   102 Oct  3 17:32 _CodeSignature

/Library/Filesystems/kbfuse.fs/Contents/Extensions/10.10/kbfuse.kext/Contents/MacOS:
total 264
drwxr-xr-x  3 root  wheel     102 Oct  3 17:32 .
drwxr-xr-x  5 root  wheel     170 Oct  3 17:32 ..
-rwxr-xr-x  1 root  wheel  133440 Oct  3 17:32 kbfuse

/Library/Filesystems/kbfuse.fs/Contents/Extensions/10.10/kbfuse.kext/Contents/_CodeSignature:
total 8
drwxr-xr-x  3 root  wheel   102 Oct  3 17:32 .
drwxr-xr-x  5 root  wheel   170 Oct  3 17:32 ..
-rwxr-xr-x  1 root  wheel  2200 Oct  3 17:32 CodeResources

/Library/Filesystems/kbfuse.fs/Contents/Resources:
total 224
drwxr-xr-x  5 root  wheel    170 Oct  3 17:32 .
drwxr-xr-x  7 root  wheel    238 Oct  3 17:32 ..
-rwxr-xr-x  1 root  wheel  23137 Oct  3 17:32 License.rtf
-rwsr-xr-x  1 root  wheel  26680 Oct  3 17:32 load_kbfuse
-rwxr-xr-x  1 root  wheel  57776 Oct  3 17:32 mount_kbfuse

/Library/Filesystems/kbfuse.fs/Contents/_CodeSignature:
total 64
drwxr-xr-x  7 root  wheel   238 Oct  3 17:32 .
drwxr-xr-x  7 root  wheel   238 Oct  3 17:32 ..
-rwxr-xr-x  1 root  wheel   180 Oct  3 17:32 CodeDirectory
-rwxr-xr-x  1 root  wheel   196 Oct  3 17:32 CodeRequirements
-rwxr-xr-x  1 root  wheel   228 Oct  3 17:32 CodeRequirements-1
-rwxr-xr-x  1 root  wheel  4342 Oct  3 17:32 CodeResources
-rwxr-xr-x  1 root  wheel  8950 Oct  3 17:32 CodeSignature
~ smerrill $ /Library/Filesystems/kbfuse.fs/Contents/Resources/load_kbfuse; echo $?
255
~ smerrill $ kextstat | grep kbfuse
~ smerrill $
gabriel commented 7 years ago

When you run:

/Library/Filesystems/kbfuse.fs/Contents/Resources/load_kbfuse

Can you check your /var/log/system.log for any error messages (or look via Console.app) that might be coming out there?

skpy commented 7 years ago

tail -f /var/log/system.log showed nothing at all when I ran that command.

gabriel commented 7 years ago

Do you have any other fuse related kext's? 

kextstat | grep fuse
skpy commented 7 years ago

Apparently not:

~ smerrill $ kextstat | grep fuse
~ smerrill $
gabriel commented 7 years ago

Do you have virtualbox installed? Whats the output of kextstat | grep box?

skpy commented 7 years ago

I do still have VirtualBox installed:

~ smerrill $ kextstat | grep box
   26    1 0xffffff7f80efc000 0x1f000    0x1f000    com.apple.security.sandbox (300.0) 8997F7FD-7C04-38F6-86DB-BACDE9137374 <25 22 16 7 6 5 4 3 2 1>
  145    3 0xffffff7f837e0000 0x61000    0x61000    org.virtualbox.kext.VBoxDrv (5.1.14) 08D6FFCF-2BDF-305C-8824-89E69602C30D <7 5 4 3 1>
  152    0 0xffffff7f83855000 0x8000     0x8000     org.virtualbox.kext.VBoxUSB (5.1.14) BF0B350D-C893-37DF-82F0-BE86ABA49D26 <151 145 41 7 5 4 3 1>
  153    0 0xffffff7f8385d000 0x5000     0x5000     org.virtualbox.kext.VBoxNetFlt (5.1.14) 0291FFCC-8E46-3FFD-B674-BFA42D4E67E4 <145 7 5 4 3 1>
  154    0 0xffffff7f83862000 0x6000     0x6000     org.virtualbox.kext.VBoxNetAdp (5.1.14) EFBEE858-A3D7-3C61-86C0-F6AFDCA48229 <145 5 4 1>

As I said above, I rely on VirtualBox for work pretty much every day. If it's a choice between KBFS and VirtualBox, the latter will win.

gabriel commented 7 years ago

Yeah I agree, VirtualBox is super useful. I use it too. Unfornunately, I can't repro this with VirtualBox 5.1.14 (also tried with shared folders).

Do you use Boxcryptor or ExpanDrive? Can you send me your list of kexts, output of kextstat. (If you want to send in private you can email to gabriel at keyba.se.)

skpy commented 7 years ago

Not using Boxcryptor or ExpanDrive. Email sent with output.

gabriel commented 7 years ago

Have you tried it after a reboot? There may be an issue with fuse not loading which requires a reboot: https://github.com/osxfuse/osxfuse/issues/358

donutsonhudson commented 7 years ago

For what it's worth, I've noticed that I'll get that error when I have keybase running on two accounts on the same laptop. Closing keybase on one account will allow me to start keybase without a hitch on the other.

skpy commented 7 years ago

I have rebooted. I am not running any other users on this system.

Keybase used to work just fine. I'd used KBFS successfully late last year. I don't recall when, specifically, the errors started. I assumed at first it was a transient fault and would resolve after a reboot or re-install of Keybase. When several reboots over several weeks did not resolve the problem, I opened this issue.

sapran commented 7 years ago

Having the same problem with fresh install of Keybase on Sierra.

gabriel commented 7 years ago

@donutsonhudson we currently only support running kbfs on a single account (because it's mounting at /keybase), so this is a known restriction..

gabriel commented 7 years ago

@skpy ok, thanks for all the info...

Can you try the following?

Are you able to see you data at ls /tmp/keybase.test? Or do you get an error? (And to double check, you're not running Keybase as multiple users at same time?)

gabriel commented 7 years ago

@sapran There are a few causes of "kbfs isn't running". Can you post the ID from running /Applications/Keybase.app/Contents/SharedSupport/bin/keybase log send?

sapran commented 7 years ago

@gabriel 13bcc59667918a285846751c

gabriel commented 7 years ago

@sapran Looks like it's having trouble loading the fuse kext..

What happens if you run:

/Library/Filesystems/kbfuse.fs/Contents/Resources/load_kbfuse; echo $?

My guess is it will probably error with code 255.

Are you running any anti-virus or vpn software? Do you run virtualbox?

Does it go away after you reboot?

gabriel commented 7 years ago

Ok, I was just able to reproduce this by running VMWare. It seems maybe virtualization is conflicting with fuse somehow.

I'm not able to repro with virtualbox yet, but maybe it depends on running a specific type of VM there?

You might be able to workaround this by starting Keybase before VMWare or VirtualBox until the fuse issue is resolved: https://github.com/osxfuse/osxfuse/issues/358

gabriel commented 7 years ago

Ah, ok here is the explanation:

FUSE needs to register a virtual device for exchanging messages between the kernel and the actual file system implementation running in user space. The number of available device slots is limited by macOS. So if you are using other software like VMware, VirtualBox, TunTap, Intel HAXM, ..., that eat up all free device slots, FUSE will not be able to register its virtual device.

On a brand new install of macOS 10.12 there are six free device slots. VMware and VirtualBox require multiple slots each. TunTap (VPN) requires two slots, as far as I know. Intel HAXM requires at least one slot.

https://github.com/osxfuse/osxfuse/issues/358#issuecomment-279086264

gabriel commented 7 years ago

This is a dupe of https://github.com/keybase/client/issues/5507

Closing, please see discussion there.