{"action":"created","issue":{"url":"https://api.github.com/repos/mozilla-services/syncserver/issues/140","repository_url":"https://api.github.com/repos/mozilla-services/syncserver","labels_url":"https://api.github.com/repos/mozilla-services/syncserver/issues/140/labels{/name}","comments_url":"https://api.github.com/repos/mozilla-services/syncserver/issues/140/comments","events_url":"https://api.github.com/repos/mozilla-services/syncserver/issues/140/events","html_url":"https://github.com/mozilla-services/syncserver/issues/140","id":365215642,"node_id":"MDU6SXNzdWUzNjUyMTU2NDI=","number":140,"title":"syncserver storage error","user":{"login":"snIP3r23","id":36365840,"node_id":"MDQ6VXNlcjM2MzY1ODQw","avatar_url":"https://avatars3.githubusercontent.com/u/36365840?v=4","gravatar_id":"","url":"https://api.github.com/users/snIP3r23","html_url":"https://github.com/snIP3r23","followers_url":"https://api.github.com/users/snIP3r23/followers","following_url":"https://api.github.com/users/snIP3r23/following{/other_user}","gists_url":"https://api.github.com/users/snIP3r23/gists{/gist_id}","starred_url":"https://api.github.com/users/snIP3r23/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/snIP3r23/subscriptions","organizations_url":"https://api.github.com/users/snIP3r23/orgs","repos_url":"https://api.github.com/users/snIP3r23/repos","events_url":"https://api.github.com/users/snIP3r23/events{/privacy}","received_events_url":"https://api.github.com/users/snIP3r23/received_events","type":"User","site_admin":false},"labels":[],"state":"closed","locked":false,"assignee":null,"assignees":[],"milestone":null,"comments":3,"created_at":"2018-09-30T11:01:47Z","updated_at":"2018-10-01T08:39:18Z","closed_at":"2018-10-01T08:39:18Z","author_association":"NONE","body":"hi all!\r\nafter recovering my working installation of syncserver i now cannot get it working again. in my apace logs i get theses messages:\r\nerror_log:\r\n`[Fri Sep 28 20:25:09.940481 2018] [wsgi:error] [pid 4910] [remote 217.235.72.198:50308] WARNING:syncstorage.views.authentication:Authentication Failed: invalid hawk id`\r\naccess_log:\r\n`217.235.66.61 - - [30/Sep/2018:12:51:06 +0200] \"GET /token/1.0/sync/1.5 HTTP/1.1\" 200 521\r\n217.235.66.61 - - [30/Sep/2018:12:51:06 +0200] \"GET /storage/1.5/1/info/collections HTTP/1.1\" 401 1\r\n217.235.66.61 - - [30/Sep/2018:12:51:06 +0200] \"GET /token/1.0/sync/1.5 HTTP/1.1\" 200 521\r\n`\r\nin /var/log/apache2/ssl_error_log:\r\n`[Fri Sep 28 20:25:09.940481 2018] [wsgi:error] [pid 4910] [remote 217.235.72.198:50308] WARNING:syncstorage.views.authentication:Authentication Faile\r\nd: invalid hawk id`\r\n\r\n\r\nin sync log of firefox:\r\n`1538158770071\tSync.LogManager\tDEBUG\tFlushing file log\r\n1538158770080\tSync.LogManager\tDEBUG\tLog cleanup threshold time: 1537294770080\r\n1538158770094\tSync.LogManager\tDEBUG\tDone deleting files.\r\n1538158771570\tServices.Common.RESTRequest\tTRACE\tonStartRequest: GET https://profile.accounts.firefox.com/v1/profile\r\n1538158771570\tServices.Common.RESTRequest\tTRACE\tChannel for GET https://profile.accounts.firefox.com/v1/profile returned status code 0\r\n1538158771570\tServices.Common.RESTRequest\tDEBUG\tGET https://profile.accounts.firefox.com/v1/profile 304\r\n1538158771570\tServices.Common.RESTRequest\tTRACE\tGET body: \r\n1538159107546\tSync.Service\tDEBUG\tUser-Agent: Firefox/62.0.2 (Windows NT 10.0; Win64; x64) FxSync/1.64.0.20180920131237.desktop\r\n1538159107546\tSync.Service\tINFO\tStarting sync at 2018-09-28 20:25:07 in browser session ZiYq54onf_5V\r\n1538159107546\tSync.Service\tDEBUG\tIn sync: should login.\r\n1538159107546\tSync.Service\tINFO\tUser logged in successfully - verifying login.\r\n1538159107547\tSync.BrowserIDManager\tDEBUG\tunlockAndVerifyAuthState already has (or can fetch) sync keys\r\n1538159107547\tSync.Status\tDEBUG\tStatus.login: error.login.reason.network => success.status_ok\r\n1538159107547\tSync.Status\tDEBUG\tStatus.service: error.login.failed => error.login.failed\r\n1538159107547\tSync.Service\tDEBUG\tFetching unlocked auth state returned success.status_ok\r\n1538159107547\tSync.BrowserIDManager\tINFO\tGetting keys\r\n1538159107548\tSync.BrowserIDManager\tINFO\tGetting an assertion from: https://SERVER/token/1.0/sync/1.5\r\n1538159107548\tFirefoxAccounts\tDEBUG\tenter getAssertion()\r\n1538159107548\tFirefoxAccounts\tDEBUG\tgetKeypairAndCertificate: already have keyPair and certificate\r\n1538159107548\tFirefoxAccounts\tDEBUG\tgetAssertionFromCert\r\n1538159107549\tFirefoxAccounts\tDEBUG\tgetAssertionFromCert returning signed: true\r\n1538159107549\tSync.BrowserIDManager\tDEBUG\tGetting a token\r\n1538159107549\tServices.Common.TokenServerClient\tDEBUG\tBeginning BID assertion exchange: https://SERVER/token/1.0/sync/1.5\r\n1538159107549\tServices.Common.RESTRequest\tDEBUG\tGET request to https://SERVER/token/1.0/sync/1.5\r\n1538159107549\tServices.Common.RESTRequest\tTRACE\tHTTP Header accept: application/json\r\n1538159107549\tServices.Common.RESTRequest\tTRACE\tHTTP Header authorization: ***** (suppressed)\r\n1538159107549\tServices.Common.RESTRequest\tTRACE\tHTTP Header x-client-state: cdc794b9bd26b64f527f2387158874a2\r\n1538159107605\tServices.Common.RESTRequest\tTRACE\tonStartRequest: GET https://SERVER/token/1.0/sync/1.5\r\n1538159107605\tServices.Common.RESTRequest\tTRACE\tChannel for GET https://SERVER/token/1.0/sync/1.5 returned status code 0\r\n1538159107605\tServices.Common.RESTRequest\tDEBUG\tGET https://SERVER/token/1.0/sync/1.5 200\r\n1538159107605\tServices.Common.RESTRequest\tTRACE\tGET body: {\"uid\": 1, \"hashalg\": \"sha256\", \"api_endpoint\": \"https://SERVER/storage/1.5/1\", \"duration\": 300, \"key\": \"PZ4Q39PDHSV3Sei8aXfVvmwB1bvdAp_hL8VimFBmf2Q=\", \"hashed_fxa_uid\": \"ID\", \"id\": \"eyJub2RlIjogImh0dHBzOi8vZnMuYXJlYTUyLmRuc2hvbWUuZGUiLCAidWlkIjogMSwgImV4cGlyZXMiOiAxNTM4MTU5NDA5LCAiZnhhX3VpZCI6ICIwM2VmZDIwODhlNTY4YzI1ZGE0ZDFjNGY0Yjg4NzFjOSIsICJzYWx0IjogIjgzZjEzMCIsICJkZXZpY2VfaWQiOiAiNDY4YWI3ZGRjMmNhZjZhYjNkOWMzMjRjNGU5N2NiMjkifXxhUnipQUM8rGeCmC0lheLI-uWPDFz9lWItbeVojZBL\"}\r\n1538159107605\tServices.Common.TokenServerClient\tDEBUG\tGot token response: 200\r\n1538159107605\tServices.Common.RESTResponse\tTRACE\tProcessing response headers.\r\n1538159107605\tServices.Common.TokenServerClient\tDEBUG\tSuccessful token response\r\n1538159107605\tSync.Status\tDEBUG\tStatus.login: success.status_ok => success.login\r\n1538159107605\tSync.Status\tDEBUG\tStatus.service: error.login.failed => success.status_ok\r\n1538159107607\tFirefoxAccounts\tDEBUG\tFxAccountsProfileClient: Requested profile\r\n1538159107607\tFirefoxAccounts\tDEBUG\tgetOAuthToken enter\r\n1538159107607\tFirefoxAccounts\tTRACE\tgetCachedToken returning cached token\r\n1538159107607\tFirefoxAccounts\tDEBUG\tgetOAuthToken returning a cached token\r\n1538159107607\tServices.Common.RESTRequest\tDEBUG\tGET request to https://profile.accounts.firefox.com/v1/profile\r\n1538159107607\tServices.Common.RESTRequest\tTRACE\tHTTP Header authorization: ***** (suppressed)\r\n1538159107607\tServices.Common.RESTRequest\tTRACE\tHTTP Header accept: application/json\r\n1538159107607\tServices.Common.RESTRequest\tTRACE\tHTTP Header if-none-match: \"3f0d91e7170f4a0b726f7f8cb4b73690e425fc26-gzip\"\r\n1538159107616\tSync.Resource\tDEBUG\tGET fail 401 https://SERVER/storage/1.5/1/info/collections\r\n1538159107616\tSync.Resource\tWARN\tGET request to https://SERVER/storage/1.5/1/info/collections failed with status 401\r\n1538159107616\tSync.Service\tWARN\t401: login failed.\r\n1538159107616\tSync.BrowserIDManager\tDEBUG\t_findCluster has a pre-existing clusterURL, so fetching a new token token\r\n1538159107617\tSync.BrowserIDManager\tINFO\tGetting keys\r\n1538159107617\tSync.BrowserIDManager\tINFO\tGetting an assertion from: https://SERVER/token/1.0/sync/1.5\r\n1538159107617\tFirefoxAccounts\tDEBUG\tenter getAssertion()\r\n1538159107617\tFirefoxAccounts\tDEBUG\tgetKeypairAndCertificate: already have keyPair and certificate\r\n1538159107617\tFirefoxAccounts\tDEBUG\tgetAssertionFromCert\r\n1538159107619\tFirefoxAccounts\tDEBUG\tgetAssertionFromCert returning signed: true\r\n1538159107619\tSync.BrowserIDManager\tDEBUG\tGetting a token\r\n1538159107619\tServices.Common.TokenServerClient\tDEBUG\tBeginning BID assertion exchange: https://SERVER/token/1.0/sync/1.5\r\n1538159107619\tServices.Common.RESTRequest\tDEBUG\tGET request to https://SERVER/token/1.0/sync/1.5\r\n1538159107619\tServices.Common.RESTRequest\tTRACE\tHTTP Header accept: application/json\r\n1538159107620\tServices.Common.RESTRequest\tTRACE\tHTTP Header authorization: ***** (suppressed)\r\n1538159107620\tServices.Common.RESTRequest\tTRACE\tHTTP Header x-client-state: cdc794b9bd26b64f527f2387158874a2\r\n1538159107625\tServices.Common.RESTRequest\tTRACE\tonStartRequest: GET https://SERVER/token/1.0/sync/1.5\r\n1538159107625\tServices.Common.RESTRequest\tTRACE\tChannel for GET https://SERVER/token/1.0/sync/1.5 returned status code 0\r\n1538159107625\tServices.Common.RESTRequest\tDEBUG\tGET https://SERVER/token/1.0/sync/1.5 200\r\n1538159107625\tServices.Common.RESTRequest\tTRACE\tGET body: {\"uid\": 1, \"hashalg\": \"sha256\", \"api_endpoint\": \"https://SERVER/storage/1.5/1\", \"duration\": 300, \"key\": \"7QOZw0QYJjUxNkaJH02nPatLij0kR07rkk4E8bHHBIs=\", \"hashed_fxa_uid\": \"ID\", \"id\": \"eyJub2RlIjogImh0dHBzOi8vZnMuYXJlYTUyLmRuc2hvbWUuZGUiLCAidWlkIjogMSwgImV4cGlyZXMiOiAxNTM4MTU5NDA5LCAiZnhhX3VpZCI6ICIwM2VmZDIwODhlNTY4YzI1ZGE0ZDFjNGY0Yjg4NzFjOSIsICJzYWx0IjogIjJhZDY4ZiIsICJkZXZpY2VfaWQiOiAiNDY4YWI3ZGRjMmNhZjZhYjNkOWMzMjRjNGU5N2NiMjkifVneEeh2uDn9CuvL894lfLSjrlz0klmcsWjaAvzy4DeQ\"}\r\n1538159107625\tServices.Common.TokenServerClient\tDEBUG\tGot token response: 200\r\n1538159107625\tServices.Common.RESTResponse\tTRACE\tProcessing response headers.\r\n1538159107625\tServices.Common.TokenServerClient\tDEBUG\tSuccessful token response\r\n1538159107625\tSync.Status\tDEBUG\tStatus.login: success.login => success.login\r\n1538159107626\tSync.Status\tDEBUG\tStatus.service: success.status_ok => success.status_ok\r\n1538159107626\tSync.BrowserIDManager\tDEBUG\t_findCluster returning https://SERVER/storage/1.5/1/\r\n1538159107626\tSync.BrowserIDManager\tDEBUG\tCluster value = https://SERVER/storage/1.5/1/\r\n1538159107626\tSync.Status\tDEBUG\tStatus.login: success.login => error.login.reason.network\r\n1538159107626\tSync.Status\tDEBUG\tStatus.service: success.status_ok => error.login.failed\r\n1538159107626\tSync.ErrorHandler\tERROR\tSync encountered a login error\r\n1538159107626\tSync.SyncScheduler\tDEBUG\tClearing sync triggers and the global score.\r\n1538159107626\tSync.SyncScheduler\tDEBUG\tNext sync in 3600000 ms. (why=schedule)\r\n1538159107627\tSync.Service\tDEBUG\tException calling WrappedLock: Error: Login failed: error.login.reason.network (resource://services-sync/service.js:868:15) JS Stack trace: onNotify@service.js:868:15\r\n1538159107628\tSync.Service\tDEBUG\tNot syncing: login returned false.\r\n`\r\n\r\n\r\n\r\ni also tried to add a new user - same error. can anyone help me with this?\r\n\r\nths in advance\r\nsnIP3r"},"comment":{"url":"https://api.github.com/repos/mozilla-services/syncserver/issues/comments/425830539","html_url":"https://github.com/mozilla-services/syncserver/issues/140#issuecomment-425830539","issue_url":"https://api.github.com/repos/mozilla-services/syncserver/issues/140","id":425830539,"node_id":"MDEyOklzc3VlQ29tbWVudDQyNTgzMDUzOQ==","user":{"login":"snIP3r23","id":36365840,"node_id":"MDQ6VXNlcjM2MzY1ODQw","avatar_url":"https://avatars3.githubusercontent.com/u/36365840?v=4","gravatar_id":"","url":"https://api.github.com/users/snIP3r23","html_url":"https://github.com/snIP3r23","followers_url":"https://api.github.com/users/snIP3r23/followers","following_url":"https://api.github.com/users/snIP3r23/following{/other_user}","gists_url":"https://api.github.com/users/snIP3r23/gists{/gist_id}","starred_url":"https://api.github.com/users/snIP3r23/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/snIP3r23/subscriptions","organizations_url":"https://api.github.com/users/snIP3r23/orgs","repos_url":"https://api.github.com/users/snIP3r23/repos","events_url":"https://api.github.com/users/snIP3r23/events{/privacy}","received_events_url":"https://api.github.com/users/snIP3r23/received_events","type":"User","site_admin":false},"created_at":"2018-10-01T08:39:18Z","updated_at":"2018-10-01T08:39:18Z","author_association":"NONE","body":"> Which might indicate a problem with the shared secret between tokenserver and storage node. Have you configured an explicit secret in your config file? (The default contents of the file at this point at `#secret = INSERT_SECRET_KEY_HERE`).\r\nAdding an own secret fixed it! thx for the hint!!!\r\n"}}
{"actor":{"display_login":"snIP3r23"}}