deadlock_log

text/plain
Filename: deadlock_log
Type: text/plain
Part: 0
Message: regression, deadlock in high frequency single-row UPDATE
2014-07-30 09:41:53 PDT PID:4727 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4727 XID:0 LOG:  duration: 0.309 ms
2014-07-30 09:41:53 PDT PID:4725 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4725 XID:0 LOG:  duration: 0.227 ms
2014-07-30 09:41:53 PDT PID:4724 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4724 XID:0 LOG:  duration: 0.318 ms
2014-07-30 09:41:53 PDT PID:4729 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4729 XID:0 LOG:  duration: 0.210 ms
2014-07-30 09:41:53 PDT PID:4730 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4730 XID:0 LOG:  duration: 0.269 ms
2014-07-30 09:41:53 PDT PID:4727 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4727 XID:0 LOG:  duration: 0.071 ms
2014-07-30 09:41:53 PDT PID:4725 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4725 XID:0 LOG:  duration: 0.068 ms
2014-07-30 09:41:53 PDT PID:4729 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4729 XID:0 LOG:  duration: 0.075 ms
2014-07-30 09:41:53 PDT PID:4724 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4724 XID:0 LOG:  duration: 0.110 ms
2014-07-30 09:41:53 PDT PID:4725 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4727 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4730 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4730 XID:0 LOG:  duration: 0.114 ms
2014-07-30 09:41:53 PDT PID:4724 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4724 XID:0 LOG:  duration: 3.396 ms
2014-07-30 09:41:53 PDT PID:4730 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4728 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4728 XID:0 LOG:  duration: 0.395 ms
2014-07-30 09:41:53 PDT PID:4729 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4728 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4728 XID:0 LOG:  duration: 0.123 ms
2014-07-30 09:41:53 PDT PID:4728 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4728 XID:0 LOG:  duration: 3.318 ms
2014-07-30 09:41:53 PDT PID:4727 XID:0 LOG:  duration: 18.022 ms
2014-07-30 09:41:53 PDT PID:4725 XID:0 LOG:  duration: 20.795 ms
2014-07-30 09:41:53 PDT PID:4730 XID:0 LOG:  duration: 14.789 ms
2014-07-30 09:41:53 PDT PID:4729 XID:0 LOG:  duration: 13.943 ms
2014-07-30 09:41:53 PDT PID:4731 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4731 XID:0 LOG:  duration: 0.304 ms
2014-07-30 09:41:53 PDT PID:4731 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4731 XID:0 LOG:  duration: 0.063 ms
2014-07-30 09:41:53 PDT PID:4731 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4731 XID:0 LOG:  duration: 2.902 ms
2014-07-30 09:41:53 PDT PID:4716 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('2rbwfyzt43caf7u5', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:53 PDT PID:4715 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4716 XID:0 LOG:  duration: 7.380 ms
2014-07-30 09:41:53 PDT PID:4715 XID:0 LOG:  duration: 7.373 ms
2014-07-30 09:41:53 PDT PID:4717 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('dakk4ujk973vmnxa', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:53 PDT PID:4718 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4717 XID:0 LOG:  duration: 1.267 ms
2014-07-30 09:41:53 PDT PID:4718 XID:0 LOG:  duration: 0.852 ms
2014-07-30 09:41:53 PDT PID:4719 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4720 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4721 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4722 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4726 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4723 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4724 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4728 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4727 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4725 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4730 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4729 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4731 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4715 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('f8fxwchxw967ja5n', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:53 PDT PID:4718 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('f8sj4nf38d6jf3vq', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:53 PDT PID:4719 XID:0 LOG:  duration: 40.852 ms
2014-07-30 09:41:53 PDT PID:4715 XID:0 LOG:  duration: 18.684 ms
2014-07-30 09:41:53 PDT PID:4718 XID:0 LOG:  duration: 18.113 ms
2014-07-30 09:41:53 PDT PID:4717 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4717 XID:0 LOG:  duration: 0.349 ms
2014-07-30 09:41:53 PDT PID:4716 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4716 XID:0 LOG:  duration: 0.382 ms
2014-07-30 09:41:53 PDT PID:4733 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4733 XID:0 LOG:  duration: 5.118 ms
2014-07-30 09:41:53 PDT PID:4734 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4736 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4733 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4733 XID:0 LOG:  duration: 0.125 ms
2014-07-30 09:41:53 PDT PID:4734 XID:0 LOG:  duration: 1.169 ms
2014-07-30 09:41:53 PDT PID:4736 XID:0 LOG:  duration: 1.111 ms
2014-07-30 09:41:53 PDT PID:4733 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4733 XID:0 LOG:  duration: 3.475 ms
2014-07-30 09:41:53 PDT PID:4732 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4736 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4736 XID:0 LOG:  duration: 0.094 ms
2014-07-30 09:41:53 PDT PID:4732 XID:0 LOG:  duration: 0.626 ms
2014-07-30 09:41:53 PDT PID:4734 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4734 XID:0 LOG:  duration: 0.219 ms
2014-07-30 09:41:53 PDT PID:4736 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4732 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4732 XID:0 LOG:  duration: 0.122 ms
2014-07-30 09:41:53 PDT PID:4736 XID:0 LOG:  duration: 4.150 ms
2014-07-30 09:41:53 PDT PID:4734 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4734 XID:0 LOG:  duration: 3.539 ms
2014-07-30 09:41:53 PDT PID:4732 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4732 XID:0 LOG:  duration: 7.804 ms
2014-07-30 09:41:53 PDT PID:4738 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4738 XID:0 LOG:  duration: 0.449 ms
2014-07-30 09:41:53 PDT PID:4737 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4737 XID:0 LOG:  duration: 0.259 ms
2014-07-30 09:41:53 PDT PID:4735 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4735 XID:0 LOG:  duration: 0.480 ms
2014-07-30 09:41:53 PDT PID:4738 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4738 XID:0 LOG:  duration: 0.074 ms
2014-07-30 09:41:53 PDT PID:4737 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4737 XID:0 LOG:  duration: 0.104 ms
2014-07-30 09:41:53 PDT PID:4739 XID:0 LOG:  statement: SET standard_conforming_strings TO on;SET datestyle TO ISO
2014-07-30 09:41:53 PDT PID:4739 XID:0 LOG:  duration: 0.331 ms
2014-07-30 09:41:53 PDT PID:4738 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4735 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4735 XID:0 LOG:  duration: 0.135 ms
2014-07-30 09:41:53 PDT PID:4738 XID:0 LOG:  duration: 3.674 ms
2014-07-30 09:41:53 PDT PID:4739 XID:0 LOG:  statement: SET client_encoding TO utf8
2014-07-30 09:41:53 PDT PID:4739 XID:0 LOG:  duration: 0.143 ms
2014-07-30 09:41:53 PDT PID:4737 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4737 XID:0 LOG:  duration: 3.995 ms
2014-07-30 09:41:53 PDT PID:4735 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4735 XID:0 LOG:  duration: 3.503 ms
2014-07-30 09:41:53 PDT PID:4739 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4739 XID:0 LOG:  duration: 2.830 ms
2014-07-30 09:41:53 PDT PID:4717 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4716 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4733 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4719 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('m789teb7pe4erpwu', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:53 PDT PID:4736 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4732 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4734 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4718 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4718 XID:0 LOG:  duration: 0.445 ms
2014-07-30 09:41:53 PDT PID:4738 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4715 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4715 XID:0 LOG:  duration: 0.253 ms
2014-07-30 09:41:53 PDT PID:4718 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4719 XID:0 LOG:  duration: 12.996 ms
2014-07-30 09:41:53 PDT PID:4715 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4737 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4719 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:53 PDT PID:4719 XID:0 LOG:  duration: 0.376 ms
2014-07-30 09:41:53 PDT PID:4735 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4719 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:53 PDT PID:4739 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:54 PDT PID:4729 XID:25780 ERROR:  deadlock detected
2014-07-30 09:41:54 PDT PID:4729 XID:25780 DETAIL:  Process 4729 waits for ShareLock on transaction 25779; blocked by process 4727.
2014-07-30 09:41:54 PDT PID:4729 XID:25780 HINT:  See server log for query details.
2014-07-30 09:41:54 PDT PID:4729 XID:25780 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:54 PDT PID:4729 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:54 PDT PID:4729 XID:0 LOG:  duration: 0.865 ms
2014-07-30 09:41:54 PDT PID:4729 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4731 XID:25781 ERROR:  deadlock detected
2014-07-30 09:41:55 PDT PID:4731 XID:25781 DETAIL:  Process 4731 waits for ShareLock on transaction 25779; blocked by process 4727.
2014-07-30 09:41:55 PDT PID:4731 XID:25781 HINT:  See server log for query details.
2014-07-30 09:41:55 PDT PID:4731 XID:25781 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4722 XID:0 LOG:  duration: 2047.311 ms
2014-07-30 09:41:55 PDT PID:4727 XID:0 LOG:  duration: 2042.021 ms
2014-07-30 09:41:55 PDT PID:4716 XID:0 LOG:  duration: 1796.127 ms
2014-07-30 09:41:55 PDT PID:4717 XID:0 LOG:  duration: 1798.057 ms
2014-07-30 09:41:55 PDT PID:4722 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('3njjbdymr6rt4yna', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4716 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('ac5wdak8e3d9brph', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4717 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('7sa56tqda9sycnrx', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4731 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4731 XID:0 LOG:  duration: 0.276 ms
2014-07-30 09:41:55 PDT PID:4727 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('jqq8wc7zhtrffuru', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4724 XID:0 LOG:  duration: 2061.442 ms
2014-07-30 09:41:55 PDT PID:4726 XID:0 LOG:  duration: 2063.663 ms
2014-07-30 09:41:55 PDT PID:4731 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4722 XID:0 LOG:  duration: 10.416 ms
2014-07-30 09:41:55 PDT PID:4716 XID:0 LOG:  duration: 4.895 ms
2014-07-30 09:41:55 PDT PID:4717 XID:0 LOG:  duration: 3.979 ms
2014-07-30 09:41:55 PDT PID:4727 XID:0 LOG:  duration: 3.065 ms
2014-07-30 09:41:55 PDT PID:4720 XID:0 LOG:  duration: 2071.239 ms
2014-07-30 09:41:55 PDT PID:4721 XID:0 LOG:  duration: 2069.597 ms
2014-07-30 09:41:55 PDT PID:4728 XID:0 LOG:  duration: 2065.082 ms
2014-07-30 09:41:55 PDT PID:4736 XID:0 LOG:  duration: 1815.498 ms
2014-07-30 09:41:55 PDT PID:4728 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('xys7vzs94huex2y7', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4733 XID:0 LOG:  duration: 1821.035 ms
2014-07-30 09:41:55 PDT PID:4721 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('srqvfyvm5hjbqjgh', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4724 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('nj9gmnsm6gvracn8', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4736 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('a5bp927ef8wtmyzq', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4722 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4722 XID:0 LOG:  duration: 0.402 ms
2014-07-30 09:41:55 PDT PID:4727 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4735 XID:0 LOG:  duration: 1804.859 ms
2014-07-30 09:41:55 PDT PID:4727 XID:0 LOG:  duration: 0.314 ms
2014-07-30 09:41:55 PDT PID:4734 XID:0 LOG:  duration: 1822.148 ms
2014-07-30 09:41:55 PDT PID:4716 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4716 XID:0 LOG:  duration: 0.209 ms
2014-07-30 09:41:55 PDT PID:4733 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('c6q4y7d8sje5nrfn', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4717 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4717 XID:0 LOG:  duration: 0.207 ms
2014-07-30 09:41:55 PDT PID:4726 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('zdhr775vbuut7jaw', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4722 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4727 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4716 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4724 XID:0 LOG:  duration: 16.024 ms
2014-07-30 09:41:55 PDT PID:4728 XID:0 LOG:  duration: 20.277 ms
2014-07-30 09:41:55 PDT PID:4726 XID:0 LOG:  duration: 6.604 ms
2014-07-30 09:41:55 PDT PID:4733 XID:0 LOG:  duration: 8.228 ms
2014-07-30 09:41:55 PDT PID:4736 XID:0 LOG:  duration: 17.109 ms
2014-07-30 09:41:55 PDT PID:4721 XID:0 LOG:  duration: 18.528 ms
2014-07-30 09:41:55 PDT PID:4720 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('t9xhe6f3pea76fn3', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4717 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4735 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('hsw4d44texh95tqh', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4735 XID:0 LOG:  duration: 2.891 ms
2014-07-30 09:41:55 PDT PID:4734 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('ep2n66dqv7da759v', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:55 PDT PID:4734 XID:0 LOG:  duration: 2.171 ms
2014-07-30 09:41:55 PDT PID:4720 XID:0 LOG:  duration: 8.575 ms
2014-07-30 09:41:55 PDT PID:4736 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4736 XID:0 LOG:  duration: 0.348 ms
2014-07-30 09:41:55 PDT PID:4726 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4726 XID:0 LOG:  duration: 0.232 ms
2014-07-30 09:41:55 PDT PID:4733 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4733 XID:0 LOG:  duration: 0.288 ms
2014-07-30 09:41:55 PDT PID:4728 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4728 XID:0 LOG:  duration: 0.274 ms
2014-07-30 09:41:55 PDT PID:4721 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4721 XID:0 LOG:  duration: 0.291 ms
2014-07-30 09:41:55 PDT PID:4724 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4724 XID:0 LOG:  duration: 0.342 ms
2014-07-30 09:41:55 PDT PID:4720 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4720 XID:0 LOG:  duration: 0.347 ms
2014-07-30 09:41:55 PDT PID:4735 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4735 XID:0 LOG:  duration: 0.198 ms
2014-07-30 09:41:55 PDT PID:4734 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:55 PDT PID:4734 XID:0 LOG:  duration: 0.177 ms
2014-07-30 09:41:55 PDT PID:4726 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4736 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4728 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4733 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4721 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4724 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4735 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4720 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:55 PDT PID:4734 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4731 XID:25812 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4731 XID:25812 DETAIL:  Process 4731 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4731 XID:25812 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4731 XID:25812 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4719 XID:25802 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4719 XID:25802 DETAIL:  Process 4719 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4719 XID:25802 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4719 XID:25802 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4718 XID:25797 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4718 XID:25797 DETAIL:  Process 4718 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4718 XID:25797 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4718 XID:25797 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4725 XID:25791 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4725 XID:25791 DETAIL:  Process 4725 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4725 XID:25791 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4725 XID:25791 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4730 XID:25785 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4730 XID:25785 DETAIL:  Process 4730 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4730 XID:25785 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4730 XID:25785 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4737 XID:25800 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4737 XID:25800 DETAIL:  Process 4737 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4737 XID:25800 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4737 XID:25800 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4715 XID:25799 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4715 XID:25799 DETAIL:  Process 4715 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4715 XID:25799 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4715 XID:25799 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4732 XID:25798 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4732 XID:25798 DETAIL:  Process 4732 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4732 XID:25798 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4732 XID:25798 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4723 XID:25786 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4723 XID:25786 DETAIL:  Process 4723 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4723 XID:25786 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4723 XID:25786 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4739 XID:25806 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4739 XID:25806 DETAIL:  Process 4739 waits for ExclusiveLock on tuple (1,98) of relation 16553 of database 16385; blocked by process 4738.
2014-07-30 09:41:56 PDT PID:4739 XID:25806 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4739 XID:25806 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4738 XID:25805 ERROR:  deadlock detected
2014-07-30 09:41:56 PDT PID:4738 XID:25805 DETAIL:  Process 4738 waits for ShareLock on transaction 25807; blocked by process 4729.
2014-07-30 09:41:56 PDT PID:4738 XID:25805 HINT:  See server log for query details.
2014-07-30 09:41:56 PDT PID:4738 XID:25805 STATEMENT:  UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4729 XID:0 LOG:  duration: 2041.316 ms
2014-07-30 09:41:56 PDT PID:4716 XID:0 LOG:  duration: 1000.119 ms
2014-07-30 09:41:56 PDT PID:4717 XID:0 LOG:  duration: 997.180 ms
2014-07-30 09:41:56 PDT PID:4727 XID:0 LOG:  duration: 1003.124 ms
2014-07-30 09:41:56 PDT PID:4722 XID:0 LOG:  duration: 1003.866 ms
2014-07-30 09:41:56 PDT PID:4716 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('cb3tb8q3q8w7jgb9', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4733 XID:0 LOG:  duration: 968.410 ms
2014-07-30 09:41:56 PDT PID:4726 XID:0 LOG:  duration: 971.450 ms
2014-07-30 09:41:56 PDT PID:4716 XID:0 LOG:  duration: 1.847 ms
2014-07-30 09:41:56 PDT PID:4736 XID:0 LOG:  duration: 971.586 ms
2014-07-30 09:41:56 PDT PID:4724 XID:0 LOG:  duration: 956.523 ms
2014-07-30 09:41:56 PDT PID:4728 XID:0 LOG:  duration: 971.513 ms
2014-07-30 09:41:56 PDT PID:4721 XID:0 LOG:  duration: 959.007 ms
2014-07-30 09:41:56 PDT PID:4734 XID:0 LOG:  duration: 955.818 ms
2014-07-30 09:41:56 PDT PID:4735 XID:0 LOG:  duration: 958.631 ms
2014-07-30 09:41:56 PDT PID:4728 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('2d4k7qkj7pxeaxyd', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4724 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('7gt9tzmenv7hs8x9', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4726 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('8xrhdtfypy5pdaxp', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4727 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('fn6ybvr58nghqfvm', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4722 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('65wj9wtqat933hn5', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4733 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('r52xxsdjdbj6vk59', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4729 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('9d5pe3rqrbgh96bs', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4736 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('zucqgdgf8vrxkdub', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4721 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('apgkehysf7eu4dr2', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4728 XID:0 LOG:  duration: 5.846 ms
2014-07-30 09:41:56 PDT PID:4727 XID:0 LOG:  duration: 3.622 ms
2014-07-30 09:41:56 PDT PID:4722 XID:0 LOG:  duration: 3.294 ms
2014-07-30 09:41:56 PDT PID:4733 XID:0 LOG:  duration: 2.938 ms
2014-07-30 09:41:56 PDT PID:4726 XID:0 LOG:  duration: 4.417 ms
2014-07-30 09:41:56 PDT PID:4736 XID:0 LOG:  duration: 1.680 ms
2014-07-30 09:41:56 PDT PID:4729 XID:0 LOG:  duration: 2.775 ms
2014-07-30 09:41:56 PDT PID:4724 XID:0 LOG:  duration: 5.320 ms
2014-07-30 09:41:56 PDT PID:4734 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('3rabn98sk63nmmkw', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4721 XID:0 LOG:  duration: 2.047 ms
2014-07-30 09:41:56 PDT PID:4734 XID:0 LOG:  duration: 0.639 ms
2014-07-30 09:41:56 PDT PID:4735 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('nfm3p27uva72vunn', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4735 XID:0 LOG:  duration: 0.505 ms
2014-07-30 09:41:56 PDT PID:4720 XID:0 LOG:  duration: 968.198 ms
2014-07-30 09:41:56 PDT PID:4720 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('psejqv8mv5jnvkgy', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4720 XID:0 LOG:  duration: 1.174 ms
2014-07-30 09:41:56 PDT PID:4717 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('zhq43nvs9wdpb4nh', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4717 XID:0 LOG:  duration: 0.946 ms
2014-07-30 09:41:56 PDT PID:4718 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:56 PDT PID:4718 XID:0 LOG:  duration: 0.414 ms
2014-07-30 09:41:56 PDT PID:4718 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4718 XID:0 LOG:  duration: 0.763 ms
2014-07-30 09:41:56 PDT PID:4730 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:56 PDT PID:4730 XID:0 LOG:  duration: 0.254 ms
2014-07-30 09:41:56 PDT PID:4719 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:56 PDT PID:4719 XID:0 LOG:  duration: 0.164 ms
2014-07-30 09:41:56 PDT PID:4718 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('6nf4akd5cng3dcsm', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4719 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4719 XID:0 LOG:  duration: 1.102 ms
2014-07-30 09:41:56 PDT PID:4715 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:56 PDT PID:4715 XID:0 LOG:  duration: 0.391 ms
2014-07-30 09:41:56 PDT PID:4718 XID:0 LOG:  duration: 1.852 ms
2014-07-30 09:41:56 PDT PID:4719 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('5vwxvj7yzqkv2438', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4730 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4715 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4728 XID:0 LOG:  statement: SELECT "user_1"."id", "user_1"."uuid", "user_1"."lastUpdated" AT TIME ZONE 'UTC', "user_1"."id" as _id FROM "z8z6px927zu6qzzbnb5ntgghxg"."user_1" AS "user_1"  WHERE "user_1"."id" = 129
2014-07-30 09:41:56 PDT PID:4728 XID:0 LOG:  duration: 0.223 ms
2014-07-30 09:41:56 PDT PID:4719 XID:0 LOG:  duration: 4.042 ms
2014-07-30 09:41:56 PDT PID:4728 XID:0 LOG:  statement: UPDATE "z8z6px927zu6qzzbnb5ntgghxg"."access_grants" ag SET last_issued=DEFAULT FROM "z8z6px927zu6qzzbnb5ntgghxg"."oauth_clients" oc WHERE oc.id = ag.client_id AND ag.entity_name = 'user' AND ag.entity_id = 129 AND oc.client_id = '3hp45h9d4f9wwtx7cvpus6rdb4s5kb9f' RETURNING ag.id
2014-07-30 09:41:56 PDT PID:4730 XID:0 LOG:  duration: 9.535 ms
2014-07-30 09:41:56 PDT PID:4715 XID:0 LOG:  duration: 8.274 ms
2014-07-30 09:41:56 PDT PID:4728 XID:0 LOG:  duration: 5.898 ms
2014-07-30 09:41:56 PDT PID:4730 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('exemfjaxzsebk7uc', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4715 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('3pqfgs5zmm9cnheh', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4728 XID:0 LOG:  statement: INSERT INTO "z8z6px927zu6qzzbnb5ntgghxg"."access_tokens"(token, access_id, refresh_token_id, issued, expiry) VALUES('5c8r8epmtq3abcy2', 2, null, timezone('UTC'::text, now()), timezone('UTC'::text, now()) + '1:00:00':: interval)
2014-07-30 09:41:56 PDT PID:4715 XID:0 LOG:  duration: 1.194 ms
2014-07-30 09:41:56 PDT PID:4728 XID:0 LOG:  duration: 1.439 ms
2014-07-30 09:41:56 PDT PID:4730 XID:0 LOG:  duration: 3.975 ms