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)