ResourceNotFound on Soledad sync
Cannot create new accounts with dev.bitmask.net. The UI reports: "There was an unexpected error with soledad"
The client log
2013-12-27 15:10:24,667 - leap.soledad.client:_bootstrap:L#394 - INFO - Trying to fetch cryptographic secrets from shared recovery database...
2013-12-27 15:10:26,012 - leap.bitmask.services.soledad.soledadbootstrapper:_try_soledad_init:L#284 - ERROR - Error whie initializing soledad (HTTPError) Traceback (most recent call last): File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/apps/leap/bitmask/services/soledad/soledadbootstrapper.py", line 258, in _try_soledad_init auth_token=auth_token) File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 292, in __init__ self._bootstrap() # might raise BootstrapSequenceError() File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 402, in _bootstrap token, timeout = self._shared_db.lock() File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 701, in _shared_db creds=self._creds) File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/shared_db.py", line 113, in open_database db.open(create) File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_database.py", line 67, in open self._check() File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_database.py", line 70, in _check return self._request_json('GET', [])[0] File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 217, in _request_json content_type) File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 209, in _request return self._response() File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 160, in _response raise errors.HTTPError(resp.status, body, headers) HTTPError: HTTPError(500)
2013-12-27 15:10:26,023 - leap:write:L#53 - DEBUG - 2013-12-27 15:10:26-0800 [-] Unhandled error in Deferred:
2013-12-27 15:10:26,027 - leap:write:L#53 - DEBUG - 2013-12-27 15:10:26-0800 [-] Unhandled Error
2013-12-27 15:10:26,029 - leap:write:L#53 - DEBUG - Traceback (most recent call last):
2013-12-27 15:10:26,031 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/threading.py", line 525, in __bootstrap
2013-12-27 15:10:26,034 - leap:write:L#53 - DEBUG - self.__bootstrap_inner()
2013-12-27 15:10:26,036 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/threading.py", line 552, in __bootstrap_inner
2013-12-27 15:10:26,038 - leap:write:L#53 - DEBUG - self.run()
2013-12-27 15:10:26,040 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/threading.py", line 505, in run
2013-12-27 15:10:26,042 - leap:write:L#53 - DEBUG - self.__target(*self.__args, **self.__kwargs)
2013-12-27 15:10:26,043 - leap:write:L#53 - DEBUG - --- ---
2013-12-27 15:10:26,044 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/twisted/python/threadpool.py", line 191, in _worker
2013-12-27 15:10:26,045 - leap:write:L#53 - DEBUG - result = context.call(ctx, function, *args, **kwargs)
2013-12-27 15:10:26,046 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/twisted/python/context.py", line 118, in callWithContext
2013-12-27 15:10:26,047 - leap:write:L#53 - DEBUG - return self.currentContext().callWithContext(ctx, func, *args, **kw)
2013-12-27 15:10:26,049 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/twisted/python/context.py", line 81, in callWithContext
2013-12-27 15:10:26,050 - leap:write:L#53 - DEBUG - return func(*args,**kw)
2013-12-27 15:10:26,051 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/apps/leap/bitmask/services/soledad/soledadbootstrapper.py", line 192, in load_and_sync_soledad
2013-12-27 15:10:26,052 - leap:write:L#53 - DEBUG - server_url, cert_file, token)
2013-12-27 15:10:26,053 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/apps/leap/bitmask/services/soledad/soledadbootstrapper.py", line 258, in _try_soledad_init
2013-12-27 15:10:26,055 - leap:write:L#53 - DEBUG - auth_token=auth_token)
2013-12-27 15:10:26,056 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 292, in __init__
2013-12-27 15:10:26,057 - leap:write:L#53 - DEBUG - self._bootstrap() # might raise BootstrapSequenceError()
2013-12-27 15:10:26,058 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 402, in _bootstrap
2013-12-27 15:10:26,060 - leap:write:L#53 - DEBUG - token, timeout = self._shared_db.lock()
2013-12-27 15:10:26,061 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 701, in _shared_db
2013-12-27 15:10:26,062 - leap:write:L#53 - DEBUG - creds=self._creds)
2013-12-27 15:10:26,064 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/shared_db.py", line 113, in open_database
2013-12-27 15:10:26,065 - leap:write:L#53 - DEBUG - db.open(create)
2013-12-27 15:10:26,066 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_database.py", line 67, in open
2013-12-27 15:10:26,067 - leap:write:L#53 - DEBUG - self._check()
2013-12-27 15:10:26,069 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_database.py", line 70, in _check
2013-12-27 15:10:26,070 - leap:write:L#53 - DEBUG - return self._request_json('GET', [])[0]
2013-12-27 15:10:26,071 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 217, in _request_json
2013-12-27 15:10:26,073 - leap:write:L#53 - DEBUG - content_type)
2013-12-27 15:10:26,074 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 209, in _request
2013-12-27 15:10:26,075 - leap:write:L#53 - DEBUG - return self._response()
2013-12-27 15:10:26,076 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 160, in _response
2013-12-27 15:10:26,077 - leap:write:L#53 - DEBUG - raise errors.HTTPError(resp.status, body, headers)
2013-12-27 15:10:26,079 - leap:write:L#53 - DEBUG - u1db.errors.HTTPError: HTTPError(500)
2013-12-27 15:10:27,022 - leap.bitmask.services.soledad.soledadbootstrapper:_try_soledad_init:L#284 - ERROR - Error whie initializing soledad (HTTPError) Traceback (most recent call last): File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/apps/leap/bitmask/services/soledad/soledadbootstrapper.py", line 258, in _try_soledad_init auth_token=auth_token) File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 292, in __init__ self._bootstrap() # might raise BootstrapSequenceError() File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 402, in _bootstrap token, timeout = self._shared_db.lock() File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/shared_db.py", line 157, in lock body={}) File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 217, in _request_json content_type) File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 209, in _request return self._response() File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 160, in _response raise errors.HTTPError(resp.status, body, headers) HTTPError: HTTPError(500)
2013-12-27 15:10:27,032 - leap:write:L#53 - DEBUG - 2013-12-27 15:10:27-0800 [-] Unhandled error in Deferred:
2013-12-27 15:10:27,036 - leap:write:L#53 - DEBUG - 2013-12-27 15:10:27-0800 [-] Unhandled Error
2013-12-27 15:10:27,039 - leap:write:L#53 - DEBUG - Traceback (most recent call last):
2013-12-27 15:10:27,042 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/threading.py", line 525, in __bootstrap
2013-12-27 15:10:27,045 - leap:write:L#53 - DEBUG - self.__bootstrap_inner()
2013-12-27 15:10:27,048 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/threading.py", line 552, in __bootstrap_inner
2013-12-27 15:10:27,050 - leap:write:L#53 - DEBUG - self.run()
2013-12-27 15:10:27,051 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/threading.py", line 505, in run
2013-12-27 15:10:27,053 - leap:write:L#53 - DEBUG - self.__target(*self.__args, **self.__kwargs)
2013-12-27 15:10:27,054 - leap:write:L#53 - DEBUG - --- ---
2013-12-27 15:10:27,055 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/twisted/python/threadpool.py", line 191, in _worker
2013-12-27 15:10:27,056 - leap:write:L#53 - DEBUG - result = context.call(ctx, function, *args, **kwargs)
2013-12-27 15:10:27,058 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/twisted/python/context.py", line 118, in callWithContext
2013-12-27 15:10:27,059 - leap:write:L#53 - DEBUG - return self.currentContext().callWithContext(ctx, func, *args, **kw)
2013-12-27 15:10:27,060 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/twisted/python/context.py", line 81, in callWithContext
2013-12-27 15:10:27,062 - leap:write:L#53 - DEBUG - return func(*args,**kw)
2013-12-27 15:10:27,063 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/apps/leap/bitmask/services/soledad/soledadbootstrapper.py", line 192, in load_and_sync_soledad
2013-12-27 15:10:27,064 - leap:write:L#53 - DEBUG - server_url, cert_file, token)
2013-12-27 15:10:27,066 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/apps/leap/bitmask/services/soledad/soledadbootstrapper.py", line 258, in _try_soledad_init
2013-12-27 15:10:27,067 - leap:write:L#53 - DEBUG - auth_token=auth_token)
2013-12-27 15:10:27,068 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 292, in __init__
2013-12-27 15:10:27,070 - leap:write:L#53 - DEBUG - self._bootstrap() # might raise BootstrapSequenceError()
2013-12-27 15:10:27,071 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/__init__.py", line 402, in _bootstrap
2013-12-27 15:10:27,072 - leap:write:L#53 - DEBUG - token, timeout = self._shared_db.lock()
2013-12-27 15:10:27,074 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/leap/soledad/client/shared_db.py", line 157, in lock
2013-12-27 15:10:27,075 - leap:write:L#53 - DEBUG - body={})
2013-12-27 15:10:27,076 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 217, in _request_json
2013-12-27 15:10:27,077 - leap:write:L#53 - DEBUG - content_type)
2013-12-27 15:10:27,078 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 209, in _request
2013-12-27 15:10:27,080 - leap:write:L#53 - DEBUG - return self._response()
2013-12-27 15:10:27,081 - leap:write:L#53 - DEBUG - File "/home/elijah/Desktop/Bitmask-linux64-2013-12-20-fbfdc372/lib/u1db/remote/http_client.py", line 160, in _response
2013-12-27 15:10:27,082 - leap:write:L#53 - DEBUG - raise errors.HTTPError(resp.status, body, headers)
2013-12-27 15:10:27,084 - leap:write:L#53 - DEBUG - u1db.errors.HTTPError: HTTPError(500)
The server log:
2013-12-27 23:12:27+0000 [-] 190.190.35.201 - - [27/Dec/2013:23:12:26 +0000] "POST /user-b53da5ccc53fbd4573b4e14b747c0030/sync-from/318aa0a770a64a3d83efe20fce588787 HTTP/1.1" 200 2295 "-" "-"
2013-12-27 23:12:41+0000 [-] WSGI application error
Traceback (most recent call last):
File "/usr/lib/python2.7/dist-packages/twisted/python/threadpool.py", line 195, in _worker
o = self.q.get()
File "/usr/lib/python2.7/dist-packages/twisted/python/context.py", line 118, in callWithContext
return self.currentContext().callWithContext(ctx, func, *args, **kw)
File "/usr/lib/python2.7/dist-packages/twisted/python/context.py", line 83, in callWithContext
self.contexts.pop()
File "/usr/lib/python2.7/dist-packages/twisted/web/wsgi.py", line 340, in run
self.started = True
--- ---
File "/usr/lib/python2.7/dist-packages/twisted/web/wsgi.py", line 315, in run
appIterator = self.application(self.environ, self.startResponse)
File "/usr/lib/python2.7/dist-packages/leap/soledad/server/__init__.py", line 386, in application
return application(environ, start_response)
File "/usr/lib/python2.7/dist-packages/leap/soledad/server/gzip_middleware.py", line 51, in __call__
app_iter = self.app(environ, dummy_start_response)
File "/usr/lib/python2.7/dist-packages/leap/soledad/server/auth.py", line 295, in __call__
return self._app(environ, start_response)
File "/usr/lib/python2.7/dist-packages/leap/soledad/server/__init__.py", line 156, in __call__
return http_app.HTTPApp.__call__(self, environ, start_response)
File "/usr/lib/python2.7/dist-packages/u1db/remote/http_app.py", line 581, in __call__
HTTPInvocationByMethodWithBody(resource, environ, self)()
File "/usr/lib/python2.7/dist-packages/u1db/remote/http_app.py", line 513, in __call__
return meth(args, None)
File "/usr/lib/python2.7/dist-packages/u1db/remote/http_app.py", line 174, in wrapper
return f(self, **args)
File "/usr/lib/python2.7/dist-packages/u1db/remote/http_app.py", line 356, in get
result = self.get_target().get_sync_info(self.source_replica_uid)
File "/usr/lib/python2.7/dist-packages/leap/soledad/common/couch.py", line 985, in get_sync_info
source_replica_uid)
File "/usr/lib/python2.7/dist-packages/leap/soledad/common/couch.py", line 695, in _get_replica_gen_and_trans_id
if len(result[other_replica_uid].rows) == 0:
File "/usr/lib/pymodules/python2.7/couchdb/client.py", line 1003, in rows
self._fetch()
File "/usr/lib/pymodules/python2.7/couchdb/client.py", line 990, in _fetch
data = self.view._exec(self.options)
File "/usr/lib/pymodules/python2.7/couchdb/client.py", line 880, in _exec
_, _, data = self.resource.get_json(**self._encode_options(options))
File "/usr/lib/pymodules/python2.7/couchdb/http.py", line 393, in get_json
status, headers, data = self.get(*a, **k)
File "/usr/lib/pymodules/python2.7/couchdb/http.py", line 374, in get
return self._request('GET', path, headers=headers, **params)
File "/usr/lib/pymodules/python2.7/couchdb/http.py", line 419, in _request
credentials=self.credentials)
File "/usr/lib/pymodules/python2.7/couchdb/http.py", line 304, in request
raise ResourceNotFound(error)
couchdb.http.ResourceNotFound: ('not_found', 'missing')
(from redmine: created on 2013-12-27, closed on 2014-01-23)