Hello all, I was using a Sync Server 1.5 with Apache, WSGI, and MySQL for several months now, but had to migrate it to another machine. After that, I did a upgrade via git. I use Mozilla's auth servers.
Since then, Firefox does not sync anymore. The (webserver) log looks like quoted below. The most important line seems to be the last one - an unknown column in a database. Is that MY sync database or Mozilla's user database? At first, I thought my database scheme would be out of sync somehow, but the columns names, especially "email", don't seem to fit my setup, as it should never store the user's mail address (should it?) Could you give me any hint on how to solve this problem? Thanks in advance Philipp > [Thu May 14 22:49:05 2015] [error] > INFO:requests.packages.urllib3.connectionpool:Starting new HTTPS connection > (1): verifier.accounts.firefox.com > [Thu May 14 22:49:06 2015] [error] ERROR:mozsvc:Uncaught exception while > processing request: > [Thu May 14 22:49:06 2015] [error] GET https://$MYURL/token/1.0/sync/1.5 > [Thu May 14 22:49:06 2015] [error] Uncaught exception: > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/mozsvc/tweens.py", line 59, in > log_uncaught_exceptions_tween > [Thu May 14 22:49:06 2015] [error] return handler(request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/mozsvc/tweens.py", line 26, in > catch_backend_errors_tween > [Thu May 14 22:49:06 2015] [error] return handler(request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/tweens.py", line 21, > in excview_tween > [Thu May 14 22:49:06 2015] [error] response = handler(request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/router.py", line 163, > in handle_request > [Thu May 14 22:49:06 2015] [error] response = view_callable(context, > request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 596, in __call__ > [Thu May 14 22:49:06 2015] [error] return view(context, request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 329, in attr_view > [Thu May 14 22:49:06 2015] [error] return view(context, request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 305, in predicate_wrapper > [Thu May 14 22:49:06 2015] [error] return view(context, request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 355, in rendered_view > [Thu May 14 22:49:06 2015] [error] result = view(context, request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 501, in _requestonly_view > [Thu May 14 22:49:06 2015] [error] response = view(request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/cornice/service.py", line 514, > in wrapper > [Thu May 14 22:49:06 2015] [error] response = view_(request) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/tokenserver/views.py", line > 182, in return_token > [Thu May 14 22:49:06 2015] [error] user = backend.get_user(service, email) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/syncserver/staticnode.py", line 129, in get_user > [Thu May 14 22:49:06 2015] [error] res = > self._engine.execute(_GET_USER_RECORDS, **params) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 1682, in execute > [Thu May 14 22:49:06 2015] [error] return connection.execute(statement, > *multiparams, **params) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 720, in execute > [Thu May 14 22:49:06 2015] [error] return meth(self, multiparams, params) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/sql/elements.py", > line 317, in _execute_on_connection > [Thu May 14 22:49:06 2015] [error] return > connection._execute_clauseelement(self, multiparams, params) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 817, in _execute_clauseelement > [Thu May 14 22:49:06 2015] [error] compiled_sql, distilled_params > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 947, in _execute_context > [Thu May 14 22:49:06 2015] [error] context) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 1108, in _handle_dbapi_exception > [Thu May 14 22:49:06 2015] [error] exc_info > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/util/compat.py", > line 185, in raise_from_cause > [Thu May 14 22:49:06 2015] [error] reraise(type(exception), exception, > tb=exc_tb) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 940, in _execute_context > [Thu May 14 22:49:06 2015] [error] context) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/default.py", > line 435, in do_execute > [Thu May 14 22:49:06 2015] [error] cursor.execute(statement, parameters) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/cursors.py", line 132, > in execute > [Thu May 14 22:49:06 2015] [error] result = self._query(query) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/cursors.py", line 271, > in _query > [Thu May 14 22:49:06 2015] [error] conn.query(q) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 726, in query > [Thu May 14 22:49:06 2015] [error] self._affected_rows = > self._read_query_result(unbuffered=unbuffered) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 861, in _read_query_result > [Thu May 14 22:49:06 2015] [error] result.read() > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 1064, in read > [Thu May 14 22:49:06 2015] [error] first_packet = > self.connection._read_packet() > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 826, in _read_packet > [Thu May 14 22:49:06 2015] [error] packet.check_error() > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 370, in check_error > [Thu May 14 22:49:06 2015] [error] raise_mysql_exception(self._data) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/err.py", line 116, in > raise_mysql_exception > [Thu May 14 22:49:06 2015] [error] _check_mysql_exception(errinfo) > [Thu May 14 22:49:06 2015] [error] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/err.py", line 112, in > _check_mysql_exception > [Thu May 14 22:49:06 2015] [error] raise InternalError(errno, errorvalue) > [Thu May 14 22:49:06 2015] [error] <class 'sqlalchemy.exc.InternalError'> > [Thu May 14 22:49:06 2015] [error] InternalError('(InternalError) (1054, > u"Unknown column \\'uid\\' in \\'field list\\'")',) > [Thu May 14 22:49:06 2015] [error] > [Thu May 14 22:49:06 2015] [error] > INFO:mozsvc.metrics:{"tokenserver.backend.get_user": 0.0011780261993408203, > "code": 999, "request_time": 0.7120170593261719, "remoteAddressChain": > ["2001:4dd4:[......]"], "agent": "Mozilla/5.0 (Windows NT 6.3; WOW64; > rv:38.0) Gecko/20100101 Firefox/38.0", "tokenserver.assertion.verify": > 0.7041330337524414, "token.assertion.verify_success": 1, "path": > "https://$MYURL/token/1.0/sync/1.5";, "method": "GET"} > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] mod_wsgi > (pid=24093): Exception occurred processing WSGI script > '/var/www/fs/syncserver.wsgi'. > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] Traceback > (most recent call last): > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/router.py", line 242, > in __call__ > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > self.invoke_subrequest(request, use_tweens=True) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/router.py", line 217, > in invoke_subrequest > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > handle_request(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/tokenserver/tweens.py", line > 15, in set_x_timestamp_header_tween > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > handler(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/syncstorage/tweens.py", line > 120, in convert_non_json_responses_tween > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > handler(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/syncstorage/tweens.py", line > 96, in convert_cornice_errors_to_respcodes_tween > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > handler(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/syncstorage/tweens.py", line > 52, in set_default_accept_header_tween > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] return > handler(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/syncstorage/tweens.py", line > 29, in set_x_timestamp_header_tween > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > handler(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/mozsvc/tweens.py", line 94, in > fuzz_backoff_headers_tween > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > handler(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/mozsvc/tweens.py", line 59, in > log_uncaught_exceptions_tween > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] return > handler(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/mozsvc/tweens.py", line 26, in > catch_backend_errors_tween > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] return > handler(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/tweens.py", line 21, > in excview_tween > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > handler(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/router.py", line 163, > in handle_request > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > view_callable(context, request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 596, in __call__ > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] return > view(context, request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 329, in attr_view > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] return > view(context, request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 305, in predicate_wrapper > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] return > view(context, request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 355, in rendered_view > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] result = > view(context, request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pyramid/config/views.py", line > 501, in _requestonly_view > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > view(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/cornice/service.py", line 514, > in wrapper > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] response = > view_(request) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/tokenserver/views.py", line > 182, in return_token > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] user = > backend.get_user(service, email) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/syncserver/staticnode.py", line 129, in get_user > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] res = > self._engine.execute(_GET_USER_RECORDS, **params) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 1682, in execute > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] return > connection.execute(statement, *multiparams, **params) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 720, in execute > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] return > meth(self, multiparams, params) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/sql/elements.py", > line 317, in _execute_on_connection > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] return > connection._execute_clauseelement(self, multiparams, params) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 817, in _execute_clauseelement > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > compiled_sql, distilled_params > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 947, in _execute_context > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] context) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 1108, in _handle_dbapi_exception > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] exc_info > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/util/compat.py", > line 185, in raise_from_cause > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > reraise(type(exception), exception, tb=exc_tb) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/base.py", > line 940, in _execute_context > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] context) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/sqlalchemy/engine/default.py", > line 435, in do_execute > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > cursor.execute(statement, parameters) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/cursors.py", line 132, > in execute > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] result = > self._query(query) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/cursors.py", line 271, > in _query > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > conn.query(q) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 726, in query > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > self._affected_rows = self._read_query_result(unbuffered=unbuffered) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 861, in _read_query_result > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > result.read() > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 1064, in read > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > first_packet = self.connection._read_packet() > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 826, in _read_packet > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > packet.check_error() > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/connections.py", line > 370, in check_error > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > raise_mysql_exception(self._data) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/err.py", line 116, in > raise_mysql_exception > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] > _check_mysql_exception(errinfo) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] File > "/var/www/fs/local/lib/python2.7/site-packages/pymysql/err.py", line 112, in > _check_mysql_exception > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] raise > InternalError(errno, errorvalue) > [Thu May 14 22:49:06 2015] [error] [client 2001:4dd4:[......]] InternalError: > (InternalError) (1054, u"Unknown column 'uid' in 'field list'") 'select\\n > uid, generation, client_state\\nfrom\\n users\\nwhere\\n email = > %s\\nand\\n service = %s\\norder by\\n created_at desc, uid > desc\\nlimit\\n 20\\n' > (u'[email protected]', u'sync-1.5') _______________________________________________ Sync-dev mailing list [email protected] https://mail.mozilla.org/listinfo/sync-dev
