Skip to content

Instantly share code, notes, and snippets.

@dannycoates

dannycoates/auth-server.log

Created October 18, 2019 16:19
Show Gist options
  • Save dannycoates/391febd473b362ab2b8699c0643580ba to your computer and use it in GitHub Desktop.
Save dannycoates/391febd473b362ab2b8699c0643580ba to your computer and use it in GitHub Desktop.
Download ZIP
redis conflict
Raw
auth-server.log
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: request.summary {"status":200,"errno":0,"path":"/v1/session/verify_code","lang":"en-US,en;q=0.5","agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","remoteAddressChain":["127.0.0.1"],"t":230,"uid":"2bacd9c2fcba41ab8fb69186f55516ba","service":"sync","keys":false,"method":"post","email":"[email protected]"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"route./session/verify_code.200","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415144380,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":37413,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"route.performance./session/verify_code","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415144380,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":231,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.WARN: sms.getGeoData {"warning":"skipping geolocation step"}
10|auth-server key server PORT 9000 | fxa-auth-server.ERROR: geodb.1 {"err":"Unable to fetch data"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: request.summary {"status":200,"errno":0,"path":"/v1/sms/status","lang":"en-US,en;q=0.5","agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","remoteAddressChain":["127.0.0.1"],"t":13,"uid":"2bacd9c2fcba41ab8fb69186f55516ba","keys":false,"method":"get","email":"[email protected]"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"route./sms/status.200","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415144462,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":37495,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"route.performance./sms/status","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415144462,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":20,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: request.summary {"status":200,"errno":0,"path":"/v1/recovery_email/status","lang":"en-US,en;q=0.5","agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","remoteAddressChain":["127.0.0.1"],"t":14,"uid":"2bacd9c2fcba41ab8fb69186f55516ba","keys":false,"method":"get","email":"[email protected]"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: push.sendPush {"name":"push.account_verify.success"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: push.pushToDevices {"name":"recovery_email_reason.push"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: request.summary {"status":200,"errno":0,"path":"/v1/recovery_email/status","lang":"en-US, en","agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","remoteAddressChain":["127.0.0.1"],"t":14,"uid":"2bacd9c2fcba41ab8fb69186f55516ba","reason":"push","keys":false,"method":"get","email":"[email protected]"}
10|auth-server key server PORT 9000 | fxa-auth-server.ERROR: geodb.1 {"err":"Unable to fetch data"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: activityEvent {"event":"account.keyfetch","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"account.keyfetch","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415144686,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":37719,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: request.summary {"status":200,"errno":0,"path":"/v1/account/keys","lang":"en-US, en","agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","remoteAddressChain":["127.0.0.1"],"t":42,"uid":"2bacd9c2fcba41ab8fb69186f55516ba","keys":false,"method":"get"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"route./account/keys.200","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415144702,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":37735,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"route.performance./account/keys","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415144702,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":44,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.ERROR: geodb.1 {"err":"Unable to fetch data"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: stat {"stat":"mem","rss":220504064,"heapUsed":83506656}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: stat {"stat":"scrypt","maxPending":0,"numPending":0,"numPendingHWM":0}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: activityEvent {"event":"account.signed","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","uid":"2bacd9c2fcba41ab8fb69186f55516ba","device_id":"3555a4f337a3aab2768ee3cab43ec875","service":"sync"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: request.summary {"status":200,"errno":0,"path":"/v1/certificate/sign","lang":"en-US, en","agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","remoteAddressChain":["127.0.0.1"],"t":8346,"uid":"2bacd9c2fcba41ab8fb69186f55516ba","service":"sync","keys":false,"method":"post","email":"[email protected]"}
10|auth-server key server PORT 9000 | fxa-auth-server.ERROR: geodb.1 {"err":"Unable to fetch data"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: activityEvent {"event":"account.signed","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","uid":"2bacd9c2fcba41ab8fb69186f55516ba","device_id":"3555a4f337a3aab2768ee3cab43ec875","service":"sync"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: request.summary {"status":200,"errno":0,"path":"/v1/certificate/sign","lang":"en-US, en","agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","remoteAddressChain":["127.0.0.1"],"t":16125,"uid":"2bacd9c2fcba41ab8fb69186f55516ba","service":"sync","keys":false,"method":"post","email":"[email protected]"}
10|auth-server key server PORT 9000 | fxa-auth-server.ERROR: server.EndpointError {"message":"127.0.0.1:8000 error: request timed out","reason":"socket hang up","method":"GET"}
10|auth-server key server PORT 9000 | fxa-auth-server.ERROR: geodb.1 {"err":"Unable to fetch data"}
10|auth-server key server PORT 9000 | fxa-auth-server.ERROR: request.summary {"status":500,"errno":203,"path":"/v1/account/device","lang":"en-US, en","agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","remoteAddressChain":["127.0.0.1"],"t":16105,"uid":"00","keys":false,"method":"post","stack":"Error: A backend service request failed.\n at Function.AppError.backendServiceFailure (/Users/dcoates/code/fxa/packages/fxa-auth-server/lib/error.js:1213:10)\n at Function.AppError.translate (/Users/dcoates/code/fxa/packages/fxa-auth-server/lib/error.js:212:22)\n at server.ext.request (/Users/dcoates/code/fxa/packages/fxa-auth-server/lib/server.js:284:24)\n at module.exports.internals.Manager.execute (/Users/dcoates/code/fxa/packages/fxa-auth-server/node_modules/hapi/lib/toolkit.js:35:106)\n at Request._invoke (/Users/dcoates/code/fxa/packages/fxa-auth-server/node_modules/hapi/lib/request.js:293:55)\n at Request._postCycle (/Users/dcoates/code/fxa/packages/fxa-auth-server/node_modules/hapi/lib/request.js:356:86)\n at process._tickCallback (internal/process/next_tick.js:68:7)"}
10|auth-server key server PORT 9000 | fxa-auth-server.ERROR: metricsEvents.emitFlowEvent {"event":"route./account/device.500.203","missingFlowId":true}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"account.signed","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415161227,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":54260,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"account.signed","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415161232,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":54265,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.WARN: redis.watch.conflict {"key":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | { Error: Redis WATCH detected a conflicting update
10|auth-server key server PORT 9000 | at Function.AppError.redisConflict (/Users/dcoates/code/fxa/packages/fxa-auth-server/lib/error.js:992:10)
10|auth-server key server PORT 9000 | at Object.object.(anonymous function) [as update] (/Users/dcoates/code/fxa/packages/fxa-auth-server/lib/redis.js:25:25)
10|auth-server key server PORT 9000 | message: 'Redis WATCH detected a conflicting update',
10|auth-server key server PORT 9000 | isBoom: true,
10|auth-server key server PORT 9000 | errno: 165,
10|auth-server key server PORT 9000 | output:
10|auth-server key server PORT 9000 | { statusCode: 409,
10|auth-server key server PORT 9000 | payload:
10|auth-server key server PORT 9000 | { code: 409,
10|auth-server key server PORT 9000 | errno: 165,
10|auth-server key server PORT 9000 | error: 'Conflict',
10|auth-server key server PORT 9000 | message: 'Redis WATCH detected a conflicting update',
10|auth-server key server PORT 9000 | info:
10|auth-server key server PORT 9000 | 'https://github.com/mozilla/fxa/blob/master/packages/fxa-auth-server/docs/api.md#response-format' },
10|auth-server key server PORT 9000 | headers: {} } }
10|auth-server key server PORT 9000 | fxa-auth-server.CRITICAL: {"op":"promise.unhandledRejection","error":{"message":"Redis WATCH detected a conflicting update","isBoom":true,"errno":165,"output":{"statusCode":409,"payload":{"code":409,"errno":165,"error":"Conflict","message":"Redis WATCH detected a conflicting update","info":"https://github.com/mozilla/fxa/blob/master/packages/fxa-auth-server/docs/api.md#response-format"},"headers":{}}}} undefined
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: request.summary {"status":404,"errno":999,"path":"/v1/authorization","agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","t":3,"uid":"00","keys":false,"method":"post"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: amplitudeEvent {"op":"amplitudeEvent","event_type":"fxa_activity - cert_signed","time":1571415161227,"user_id":"2bacd9c2fcba41ab8fb69186f55516ba","device_id":"9eb4f240d1de4776983ae194edd22d70","session_id":1571415106967,"app_version":"148","language":"en","os_name":"Mac OS X","os_version":"10.14","event_properties":{"service":"sync"},"user_properties":{"flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","ua_browser":"Firefox","ua_version":"69.0","$append":{"fxa_services_used":"sync"},"sync_device_count":1,"sync_active_devices_day":1,"sync_active_devices_week":1,"sync_active_devices_month":1}}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"flow.complete","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415161227,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":54260,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: amplitudeEvent {"op":"amplitudeEvent","event_type":"fxa_reg - complete","time":1571415161227,"user_id":"2bacd9c2fcba41ab8fb69186f55516ba","device_id":"9eb4f240d1de4776983ae194edd22d70","session_id":1571415106967,"app_version":"148","language":"en","os_name":"Mac OS X","os_version":"10.14","event_properties":{"service":"sync"},"user_properties":{"flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","ua_browser":"Firefox","ua_version":"69.0","$append":{"fxa_services_used":"sync"},"sync_device_count":1,"sync_active_devices_day":1,"sync_active_devices_week":1,"sync_active_devices_month":1}}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: amplitudeEvent {"op":"amplitudeEvent","event_type":"fxa_activity - cert_signed","time":1571415161232,"user_id":"2bacd9c2fcba41ab8fb69186f55516ba","device_id":"9eb4f240d1de4776983ae194edd22d70","session_id":1571415106967,"app_version":"148","language":"en","os_name":"Mac OS X","os_version":"10.14","event_properties":{"service":"sync"},"user_properties":{"flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","ua_browser":"Firefox","ua_version":"69.0","$append":{"fxa_services_used":"sync"},"sync_device_count":1,"sync_active_devices_day":1,"sync_active_devices_week":1,"sync_active_devices_month":1}}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: flowEvent {"event":"flow.complete","locale":"en","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0","time":1571415161232,"device_id":"9eb4f240d1de4776983ae194edd22d70","flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","flow_time":54265,"flowBeginTime":1571415106967,"flowCompleteSignal":"account.signed","flowType":"registration","service":"sync","entrypoint":"fxa_discoverability_native","uid":"2bacd9c2fcba41ab8fb69186f55516ba"}
10|auth-server key server PORT 9000 | fxa-auth-server.INFO: amplitudeEvent {"op":"amplitudeEvent","event_type":"fxa_reg - complete","time":1571415161232,"user_id":"2bacd9c2fcba41ab8fb69186f55516ba","device_id":"9eb4f240d1de4776983ae194edd22d70","session_id":1571415106967,"app_version":"148","language":"en","os_name":"Mac OS X","os_version":"10.14","event_properties":{"service":"sync"},"user_properties":{"flow_id":"4e20a331e2f748f421f0d03c3bcac3f11e6d8798f27312394ebc169523fd4d6b","ua_browser":"Firefox","ua_version":"69.0","$append":{"fxa_services_used":"sync"},"sync_device_count":1,"sync_active_devices_day":1,"sync_active_devices_week":1,"sync_active_devices_month":1}}
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment