keybase / keybase/keybase-issues

can't login with new go client from reset account

Open
#1,895 17 comments 0 reactions 0 assignees View on GitHub
Dominant language
No language data
Stars
899
Forks
40
PR merge metrics
No merged PRs in 30d

Description

got the latest Keybase from chris.

I first reset my account from the website, since I'd lost my private key that I managed myself.

Here's my attempt to login from the terminal:

```
keybase login
How would you like to sign this install of Keybase?

(1) Use an existing device
(2) Use a paper key
(3) Use my Keybase passphrase
Choose a signing option: 3
Your keybase username: cr
▶ ERROR Expected a key but didn't find one (error 901)
```

And here it is again with `-d` :

```
▶ ERROR Expected a key but didn't find one (error 901)
Christians-MacBook-Pro:~ christianrudder$ keybase -d login
10:55:48.943347 ▶ [DEBU keybase json.go:40] 001 + loading config file: /Users/christianrudder/Library/Application Support/Keybase/config.json
10:55:48.943932 ▶ [DEBU keybase json.go:68] 002 - successfully loaded config file
10:55:48.944007 ▶ [DEBU keybase config.go:114] 003 Config: mapping server ->
10:55:48.944144 ▶ [DEBU keybase config.go:114] 004 Config: mapping socket_file ->
10:55:48.944158 ▶ [DEBU keybase config.go:114] 005 Config: mapping home ->
10:55:48.944250 ▶ [DEBU keybase config.go:114] 006 Config: mapping timers ->
10:55:48.944260 ▶ [DEBU keybase globals.go:189] 007 Keybase CLI 1.0.1-0
10:55:48.944267 ▶ [DEBU keybase globals.go:189] 008 - Built with go1.5.1
10:55:48.944273 ▶ [DEBU keybase globals.go:189] 009 - Visit https://keybase.io for more details
10:55:48.944282 ▶ [DEBU keybase main.go:100] 00a + configureProcesses
10:55:48.944295 ▶ [DEBU keybase main.go:157] 00b | After forks; newProc=false
10:55:48.944303 ▶ [DEBU keybase main.go:178] 00c + configureLogging
10:55:48.944312 ▶ [DEBU keybase config.go:114] 00d Config: mapping local_rpc_debug ->
10:55:48.944335 ▶ [DEBU keybase socket_nix.go:23] 00e Dialing unix:/Users/christianrudder/Library/Caches/Keybase/keybased.sock
10:55:48.944464 ▶ [DEBU keybase config.go:114] 00f Config: mapping local_rpc_debug ->
10:55:48.944565 ▶ [DEBU keybase main.go:214] 010 Setting remote log level: 1
10:55:48.944972 ▶ [DEBU keybase main.go:180] 011 - configureLogging
10:55:48.945032 ▶ [DEBU keybase versionfix.go:21] 012 + FixVersionClash
10:55:48.945602 ▶ [DEBU keybase log.go:30] 013 Config: mapping server ->
10:55:48.946039 ▶ [DEBU keybase log.go:30] 014 Config: mapping socket_file ->
10:55:48.946386 ▶ [DEBU keybase log.go:30] 015 Config: mapping home ->
10:55:48.946810 ▶ [DEBU keybase log.go:30] 016 Config: mapping config_file ->
10:55:48.947121 ▶ [DEBU keybase log.go:30] 017 Config: mapping home ->
10:55:48.947314 ▶ [DEBU keybase versionfix.go:33] 018 | Contacted service; got version: 1.0.1-0
10:55:48.947341 ▶ [DEBU keybase versionfix.go:55] 019 | version check 1.0.1-0 v 1.0.1-0
10:55:48.947360 ▶ [DEBU keybase versionfix.go:57] 01a | versions check out
10:55:48.947368 ▶ [DEBU keybase versionfix.go:23] 01b - FixVersionClash ->
10:55:48.947374 ▶ [DEBU keybase main.go:102] 01c - configureProcesses ->
10:55:48.947642 ▶ [DEBU keybase log.go:30] 01d + RunEngine(Login)
10:55:48.947915 ▶ [DEBU keybase log.go:30] 01e + RunEngine(LoginCurrentDevice)
10:55:48.948098 ▶ [DEBU keybase log.go:30] 01f + Account "LoggedInProvisionedLoad"
10:55:48.948289 ▶ [DEBU keybase log.go:30] 020 + Loading session
10:55:48.948509 ▶ [DEBU keybase log.go:30] 021 Config: mapping session_file ->
10:55:48.948713 ▶ [DEBU keybase log.go:30] 022 Config: mapping home ->
10:55:48.948951 ▶ [DEBU keybase log.go:30] 023 Config: mapping session_file ->
10:55:48.949162 ▶ [DEBU keybase log.go:30] 024 Config: mapping home ->
10:55:48.949386 ▶ [DEBU keybase log.go:30] 025 + loading session file: /Users/christianrudder/Library/Caches/Keybase/session.json
10:55:48.949596 ▶ [DEBU keybase log.go:30] 026 No session file found; tried /Users/christianrudder/Library/Caches/Keybase/session.json
10:55:48.949786 ▶ [DEBU keybase log.go:30] 027 - Loaded session
10:55:48.949957 ▶ [DEBU keybase log.go:30] 028 - Account "LoggedInProvisionedLoad"
10:55:48.950129 ▶ [DEBU keybase log.go:30] 029 no device in user config
10:55:48.950313 ▶ [DEBU keybase log.go:30] 02a - RunEngine(LoginCurrentDevice) -> ERROR: No device provisioned locally for this user
10:55:48.950527 ▶ [DEBU keybase log.go:30] 02b LoginCurrentDevice error: No device provisioned locally for this user (continuing with device provisioning...)
10:55:48.950733 ▶ [DEBU keybase log.go:30] 02c + RunEngine(LoginProvision)
10:55:48.950978 ▶ [DEBU keybase log.go:30] 02d gpg not an option: get index error: exec: "gpg": executable file not found in $PATH
10:55:48.951178 ▶ [DEBU keybase log.go:30] 02e found gpg with private keys?: false
How would you like to sign this install of Keybase?

(1) Use an existing device
(2) Use a paper key
(3) Use my Keybase passphrase
Choose a signing option: 3
Your keybase username: cr
10:55:53.907824 ▶ [DEBU keybase log.go:30] 02f LoginProvision: loading user cr
10:55:53.908266 ▶ [DEBU keybase log.go:30] 030 LoadUser: {UID: Name:cr PublicKeyOptional:true NoCacheResult:false Self:false ForceReload:false AllKeys:false LoginContext: Contextified:{g:0xc82008d080}}
10:55:53.912010 ▶ [DEBU keybase log.go:30] 031 + LoadUser(uid=, name=cr)
10:55:53.912400 ▶ [DEBU keybase log.go:30] 032 + Resolving username cr
10:55:53.912785 ▶ [DEBU keybase log.go:30] 033 + Account "G - GetMyUID - GetUID"
10:55:53.913115 ▶ [DEBU keybase log.go:30] 034 - Account "G - GetMyUID - GetUID"
10:55:53.913419 ▶ [DEBU keybase log.go:30] 035 | resolved to a8ba96c71753eaa915dc4a80329f3100
10:55:53.913759 ▶ [DEBU keybase log.go:30] 036 + loadUserFromLocalStorage(a8ba96c71753eaa915dc4a80329f3100)
10:55:53.914039 ▶ [DEBU keybase log.go:30] 037 + LevelDb.open
10:55:53.914353 ▶ [DEBU keybase log.go:30] 038 - LevelDb.open -> ok
10:55:53.914752 ▶ [DEBU keybase log.go:30] 039 | Loaded successfully
10:55:53.915047 ▶ [DEBU keybase log.go:30] 03a + ParseKeyFamily
10:55:53.916592 ▶ [DEBU keybase log.go:30] 03b - ParseKeyFamily -> ok
10:55:53.916874 ▶ [DEBU keybase log.go:30] 03c | Loaded username cr (uid=a8ba96c71753eaa915dc4a80329f3100)
10:55:53.917155 ▶ [DEBU keybase log.go:30] 03d - loadUserFromLocalStorage(cr,a8ba96c71753eaa915dc4a80329f3100)
10:55:53.917437 ▶ [DEBU keybase log.go:30] 03e + MerkleClient.LookupUser(map[uid:a8ba96c71753eaa915dc4a80329f3100])
10:55:53.917581 ▶ [DEBU keybase log.go:30] 03f + MerkleClient.LoadRoot()
10:55:53.917918 ▶ [DEBU keybase log.go:30] 040 + LevelDb.open
10:55:53.918179 ▶ [DEBU keybase log.go:30] 041 - LevelDb.open -> ok
10:55:53.918450 ▶ [DEBU keybase log.go:30] 042 + LevelDb.open
10:55:53.918695 ▶ [DEBU keybase log.go:30] 043 - LevelDb.open -> ok
10:55:53.919092 ▶ [DEBU keybase log.go:30] 044 - MerkleClient.LoadRoot() -> &{{0xc82008d080} 327655 [3 225 70 205 175 129 54 104 10 213 102 145 42 50 52 12 236 140 148 146] 0xc8203fb020 {"body":{"key":{"fingerprint":"03e146cdaf8136680ad566912a32340cec8c9492","key_id":"2A32340CEC8C9492"},"legacy_uid_root":"d3450288d233fe9125d0b6f8196dbc93447bca2630300898781a637a44752032","prev":"a190b787604c368f8680e021dd6dcaa2aad403be2a71759a4fb661d2ea93c9068fd43518cb448fa16bd5c4f64f795258c9a12c57a422ac696485686c0103e32c","root":"320e125cc01963dc945c4b8849a5a71a9d91627eaaa9ac28a75a8df232e15acf25ef3f49cca577e20e37ae79bf7e647ec40d047772c26e1b6892d0d5c97f4cd5","seqno":327655,"txid":"8c1c276a7e8a66979d4769ccb9340715","type":"merkle_root","version":1},"ctime":1448898889,"tag":"signature"} 0xc8203fb200 [50 14 18 92 192 25 99 220 148 92 75 136 73 165 167 26 157 145 98 126 170 169 172 40 167 90 141 242 50 225 90 207 37 239 63 73 204 165 119 226 14 55 174 121 191 126 100 126 196 13 4 119 114 194 110 27 104 146 208 213 201 127 76 213] [211 69 2 136 210 51 254 145 37 208 182 248 25 109 188 147 68 123 202 38 48 48 8 152 120 26 99 122 68 117 32 50] 1448898889}
10:55:53.919356 ▶ [DEBU keybase log.go:30] 045 | LookupPath
10:55:53.919598 ▶ [DEBU keybase log.go:30] 046 + API GET request to https://keybase.io/_/api/1.0/merkle/path.json?poll=10&uid=a8ba96c71753eaa915dc4a80329f3100
10:55:53.958196 ▶ [DEBU keybase log.go:30] 047 | Result is: 200 OK
10:55:53.974205 ▶ [DEBU keybase log.go:30] 048 - successful API call
10:55:53.974656 ▶ [DEBU keybase log.go:30] 049 | VerifyRoot
10:55:53.975116 ▶ [DEBU keybase log.go:30] 04a | Merkle root: got back 327655, >= cached 327655
10:55:53.975436 ▶ [DEBU keybase log.go:30] 04b | VerifyUser
10:55:53.977787 ▶ [DEBU keybase log.go:30] 04c + ParsingMerkleUserLeaf
10:55:53.978094 ▶ [DEBU keybase log.go:30] 04d - ParsingMerkleUserLeaf -> ok
10:55:53.978250 ▶ [DEBU keybase log.go:30] 04e | VerifyUsername
10:55:53.978779 ▶ [DEBU keybase log.go:30] 04f | Failed to map Username cr -> UID a8ba96c71753eaa915dc4a80329f3100 via direct hash
10:55:53.979959 ▶ [DEBU keybase log.go:30] 050 | Username cr mapped to a8ba96c71753eaa915dc4a80329f3100 via Merkle lookup
10:55:53.980228 ▶ [DEBU keybase log.go:30] 051 - MerkleClient.LookupUser(map[uid:a8ba96c71753eaa915dc4a80329f3100 poll:10]) -> OK
10:55:53.980476 ▶ [DEBU keybase log.go:30] 052 | Local basics version is up-to-date @ version 7
10:55:53.980702 ▶ [DEBU keybase log.go:30] 053 | Freshness: basics=true; for a8ba96c71753eaa915dc4a80329f3100
10:55:53.980901 ▶ [DEBU keybase log.go:30] 054 + SigChainLoader.Load(a8ba96c71753eaa915dc4a80329f3100)
10:55:53.981138 ▶ [DEBU keybase log.go:30] 055 | SigChainLoader.Load(a8ba96c71753eaa915dc4a80329f3100) GetFingerprint
10:55:53.981362 ▶ [DEBU keybase log.go:30] 056 | SigChainLoader.Load(a8ba96c71753eaa915dc4a80329f3100) AccessPreload
10:55:53.981583 ▶ [DEBU keybase log.go:30] 057 | Preload failed
10:55:53.981823 ▶ [DEBU keybase log.go:30] 058 | SigChainLoader.Load(a8ba96c71753eaa915dc4a80329f3100) LoadLinksFromStorage
10:55:53.982032 ▶ [DEBU keybase log.go:30] 059 + SigChainLoader.LoadFromStorage(a8ba96c71753eaa915dc4a80329f3100)
10:55:53.982259 ▶ [DEBU keybase log.go:30] 05a + LevelDb.open
10:55:53.982509 ▶ [DEBU keybase log.go:30] 05b - LevelDb.open -> ok
10:55:53.982756 ▶ [DEBU keybase log.go:30] 05c | loading link; curr=59af0c9d674410a012d716555f81de52c9f729d270a34ec06c1e86ed04973f7a
10:55:53.982960 ▶ [DEBU keybase log.go:30] 05d + LevelDb.open
10:55:53.983173 ▶ [DEBU keybase log.go:30] 05e - LevelDb.open -> ok
10:55:53.983488 ▶ [DEBU keybase log.go:30] 05f | Link is marked as 'sig_verified'
10:55:53.983745 ▶ [DEBU keybase log.go:30] 060 | Unpacked Link 59af0c9d674410a012d716555f81de52c9f729d270a34ec06c1e86ed04973f7a
10:55:53.983946 ▶ [DEBU keybase log.go:30] 061 | Setting loadKID=010148d55e0c44249f8970b65471d50c7e77772d8857a2b94e431bbc9b0c22888e910a
10:55:53.984171 ▶ [DEBU keybase log.go:30] 062 - SigChainLoader.LoadFromStorage(a8ba96c71753eaa915dc4a80329f3100) -> ok
10:55:53.984375 ▶ [DEBU keybase log.go:30] 063 | SigChainLoader.Load(a8ba96c71753eaa915dc4a80329f3100) MakeSigChain
10:55:53.984548 ▶ [DEBU keybase log.go:30] 064 | SigChainLoader.Load(a8ba96c71753eaa915dc4a80329f3100) VerifyChain
10:55:53.984749 ▶ [DEBU keybase log.go:30] 065 + SigChain::VerifyChain()
10:55:53.985141 ▶ [DEBU keybase log.go:30] 066 - SigChain::VerifyChain() -> ok
10:55:53.985352 ▶ [DEBU keybase log.go:30] 067 | SigChainLoader.Load(a8ba96c71753eaa915dc4a80329f3100) CheckFreshness
10:55:53.985572 ▶ [DEBU keybase log.go:30] 068 + CheckFreshness
10:55:53.985784 ▶ [DEBU keybase log.go:30] 069 | Server triple: &{1 59af0c9d674410a012d716555f81de52c9f729d270a34ec06c1e86ed04973f7a 14bcaead8b0d356ae8c22555bfb5a03c89288ad0903e7c2fffae5915a254b0810f}
10:55:53.986009 ▶ [DEBU keybase log.go:30] 06a | Client triple: &{1 59af0c9d674410a012d716555f81de52c9f729d270a34ec06c1e86ed04973f7a }
10:55:53.986190 ▶ [DEBU keybase log.go:30] 06b | Future triple=nil
10:55:53.986405 ▶ [DEBU keybase log.go:30] 06c | Local chain version is up-to-date @ version 1
10:55:53.986608 ▶ [DEBU keybase log.go:30] 06d - CheckFreshness (a8ba96c71753eaa915dc4a80329f3100) -> (false,ok)
10:55:53.986707 ▶ [DEBU keybase log.go:30] 06e - SigChainLoader.Load(a8ba96c71753eaa915dc4a80329f3100) -> (true, ok)
10:55:53.987077 ▶ [DEBU keybase log.go:30] 06f + LoadSigHints(a8ba96c71753eaa915dc4a80329f3100)
10:55:53.987364 ▶ [DEBU keybase log.go:30] 070 + LevelDb.open
10:55:53.987607 ▶ [DEBU keybase log.go:30] 071 - LevelDb.open -> ok
10:55:53.987850 ▶ [DEBU keybase log.go:30] 072 | SigHints loaded @v1
10:55:53.988074 ▶ [DEBU keybase log.go:30] 073 - LoadSigHints(a8ba96c71753eaa915dc4a80329f3100)
10:55:53.988279 ▶ [DEBU keybase log.go:30] 074 + Refresh SigHints() for uid=a8ba96c71753eaa915dc4a80329f3100
10:55:53.988494 ▶ [DEBU keybase log.go:30] 075 + API GET request to https://keybase.io/_/api/1.0/sig/hints.json?low=1&uid=a8ba96c71753eaa915dc4a80329f3100
10:55:54.037193 ▶ [DEBU keybase log.go:30] 076 | Result is: 200 OK
10:55:54.037870 ▶ [DEBU keybase log.go:30] 077 - successful API call
10:55:54.038430 ▶ [DEBU keybase log.go:30] 078 | No changes; version 1 was up-to-date
10:55:54.038972 ▶ [DEBU keybase log.go:30] 079 - Refresh SigHints() for uid=a8ba96c71753eaa915dc4a80329f3100
10:55:54.039561 ▶ [DEBU keybase log.go:30] 07a + Store user cr
10:55:54.040088 ▶ [DEBU keybase log.go:30] 07b + SigHints.Store() for uid=a8ba96c71753eaa915dc4a80329f3100
10:55:54.040640 ▶ [DEBU keybase log.go:30] 07c | SigHints.Store() skipped; wasn't dirty
10:55:54.041245 ▶ [DEBU keybase log.go:30] 07d - SigHints.Store() for uid=a8ba96c71753eaa915dc4a80329f3100 -> ok
10:55:54.041696 ▶ [DEBU keybase log.go:30] 07e - Store for cr skipped; user wasn't dirty
10:55:54.042079 ▶ [DEBU keybase log.go:30] 07f an error occurred during provisioning, logging out
10:55:54.042424 ▶ [DEBU keybase log.go:30] 080 + Login "logout"
10:55:54.042761 ▶ [DEBU keybase log.go:30] 081 + Loading session
10:55:54.042975 ▶ [DEBU keybase log.go:30] 082 - Skipped; already loaded
10:55:54.043468 ▶ [DEBU keybase log.go:30] 083 clearing cached secret keys
10:55:54.043854 ▶ [DEBU keybase log.go:30] 084 - Login "logout"
10:55:54.044226 ▶ [DEBU keybase log.go:30] 085 + Account "LoginState - Shutdown"
10:55:54.044601 ▶ [DEBU keybase log.go:30] 086 - Account "LoginState - Shutdown"
10:55:54.044950 ▶ [DEBU keybase log.go:30] 087 - RunEngine(LoginProvision) -> ERROR: Expected a key but didn't find one
10:55:54.045131 ▶ [DEBU keybase log.go:30] 088 - RunEngine(Login) -> ERROR: Expected a key but didn't find one
10:55:54.045512 ▶ [DEBU keybase globals.go:235] 089 Calling shutdown first time through
10:55:54.045547 ▶ [DEBU keybase login_state.go:873] 08a + Account "LoginState - Shutdown"
10:55:54.045576 ▶ [DEBU keybase login_state.go:875] 08b - Account "LoginState - Shutdown"
10:55:54.045589 ▶ [DEBU keybase globals.go:275] 08c exiting shutdown code=0; err=
10:55:54.045608 ▶ [ERRO keybase main.go:47] 08d Expected a key but didn't find one (error 901)
Christians-MacBook-Pro:~ christianrudder$
```

Contributor guide

No contributing guide indexed for this repository

Research direction

Start by reproducing the reset-account flow with `keybase login` and the passphrase option. Trace `LoginCurrentDevice` and `LoginProvision` in the debug output, then inspect the named Go files `main.go`, `log.go`, `config.go`, and `versionfix.go` around the error 901 path. Done means the login completes without reporting “Expected a key but didn't find one.”

Written by the indexing model from the issue text.

Assessment

Tech stack
go
Domain
authentication, cli
Issue type
Bug
Difficulty
4/5
Estimated time
3-5 days
Activity status
Stale
Clarity
Needs clarification
Newbie friendliness
20/100

Get new issues in your inbox

A short digest of beginner-friendly GitHub issues.