· 7 years ago · Nov 20, 2017, 02:18 PM
1 ~/work î‚° î‚ master ✚ î‚° nuuz-be exec 'tail -f /data/www/stage/nuuz-backend/log/staging.log'
2D, [2017-11-20T14:00:08.792841 #9737] DEBUG -- : Conversation Load (0.5ms) SELECT "conversations".* FROM "conversations" INNER JOIN "conversation_members" ON "conversations"."id" = "conversation_members"."conversation_id" WHERE "conversation_members"."user_id" = $1 ORDER BY "conversations"."updated_at" DESC LIMIT 30 OFFSET 0 [["user_id", 25]]
3D, [2017-11-20T14:00:08.794978 #9737] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (14, 25)
4D, [2017-11-20T14:00:08.797837 #9737] DEBUG -- : ConversationMember Load (0.5ms) SELECT "conversation_members".* FROM "conversation_members" WHERE "conversation_members"."conversation_id" IN (23, 45, 44, 25, 33, 30, 28, 27, 26, 22, 21, 20, 19, 16, 17, 15)
5D, [2017-11-20T14:00:08.808737 #9737] DEBUG -- : User Load (0.7ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (14, 17, 21, 25, 34, 48, 52, 50, 49, 33, 2)
6D, [2017-11-20T14:00:08.814038 #9737] DEBUG -- : Message Load (0.7ms) SELECT DISTINCT ON(conversation_id) "messages".* FROM "messages" WHERE "messages"."conversation_id" IN (23, 45, 44, 25, 33, 30, 28, 27, 26, 22, 21, 20, 19, 16, 17, 15) ORDER BY "messages"."conversation_id" DESC, "messages"."id" DESC
7D, [2017-11-20T14:00:08.816075 #9737] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (14, 25)
8D, [2017-11-20T14:00:08.816744 #9737] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 25]]
9D, [2017-11-20T14:00:08.818025 #9737] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 25]]
10I, [2017-11-20T14:00:09.048061 #9737] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (229.69ms)
11I, [2017-11-20T14:00:09.048343 #9737] INFO -- : Completed 200 OK in 280ms (Views: 230.5ms | ActiveRecord: 5.5ms)
12I, [2017-11-20T14:10:42.631525 #13628] INFO -- : Started GET "/api/v2/users/current/followers/requests" for 93.77.143.82 at 2017-11-20 14:10:42 +0000
13I, [2017-11-20T14:10:42.724213 #13628] INFO -- : Processing by Api::V2::FollowersController#requests as JSON
14I, [2017-11-20T14:10:42.724324 #13628] INFO -- : Parameters: {"user_id"=>"current"}
15D, [2017-11-20T14:10:42.749261 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.6ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "add6071ea2b96ebf4329758cc9d1e12f0be08d4254f532591cc7710975e1cb9b"]]
16D, [2017-11-20T14:10:42.763792 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
17D, [2017-11-20T14:10:42.764505 #13628] DEBUG -- : (0.1ms) BEGIN
18D, [2017-11-20T14:10:42.765659 #13628] DEBUG -- : (0.1ms) COMMIT
19I, [2017-11-20T14:10:42.766491 #13628] INFO -- : Filter chain halted as :require_doorkeeper_login rendered or redirected
20I, [2017-11-20T14:10:42.766699 #13628] INFO -- : Completed 401 Unauthorized in 42ms (ActiveRecord: 5.3ms)
21I, [2017-11-20T14:10:42.768204 #13628] INFO -- : Started GET "/api/v2/feed?last_id=0" for 93.77.143.82 at 2017-11-20 14:10:42 +0000
22I, [2017-11-20T14:10:42.771931 #13628] INFO -- : Processing by Api::V2::FeedController#index as JSON
23I, [2017-11-20T14:10:42.772012 #13628] INFO -- : Parameters: {"last_id"=>"0"}
24D, [2017-11-20T14:10:42.772796 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.3ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "add6071ea2b96ebf4329758cc9d1e12f0be08d4254f532591cc7710975e1cb9b"]]
25D, [2017-11-20T14:10:42.773455 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
26D, [2017-11-20T14:10:42.773894 #13628] DEBUG -- : (0.1ms) BEGIN
27D, [2017-11-20T14:10:42.774541 #13628] DEBUG -- : (0.1ms) COMMIT
28I, [2017-11-20T14:10:42.775022 #13628] INFO -- : Filter chain halted as :require_doorkeeper_login rendered or redirected
29I, [2017-11-20T14:10:42.775158 #13628] INFO -- : Completed 401 Unauthorized in 3ms (ActiveRecord: 0.7ms)
30I, [2017-11-20T14:10:42.776022 #13628] INFO -- : Started GET "/api/v2/users?name=" for 93.77.143.82 at 2017-11-20 14:10:42 +0000
31I, [2017-11-20T14:10:42.779177 #13628] INFO -- : Processing by Api::V2::UsersController#index as JSON
32I, [2017-11-20T14:10:42.779244 #13628] INFO -- : Parameters: {"name"=>""}
33D, [2017-11-20T14:10:42.780344 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "add6071ea2b96ebf4329758cc9d1e12f0be08d4254f532591cc7710975e1cb9b"]]
34D, [2017-11-20T14:10:42.780978 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
35D, [2017-11-20T14:10:42.781423 #13628] DEBUG -- : (0.1ms) BEGIN
36D, [2017-11-20T14:10:42.782100 #13628] DEBUG -- : (0.1ms) COMMIT
37I, [2017-11-20T14:10:42.782559 #13628] INFO -- : Filter chain halted as :require_doorkeeper_login rendered or redirected
38I, [2017-11-20T14:10:42.782685 #13628] INFO -- : Completed 401 Unauthorized in 3ms (ActiveRecord: 0.7ms)
39I, [2017-11-20T14:10:42.783630 #13628] INFO -- : Started GET "/api/v2/publication_sites" for 93.77.143.82 at 2017-11-20 14:10:42 +0000
40I, [2017-11-20T14:10:42.786749 #13628] INFO -- : Processing by Api::V2::PublicationSitesController#index as JSON
41D, [2017-11-20T14:10:42.787542 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.3ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "add6071ea2b96ebf4329758cc9d1e12f0be08d4254f532591cc7710975e1cb9b"]]
42D, [2017-11-20T14:10:42.788233 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
43D, [2017-11-20T14:10:42.788733 #13628] DEBUG -- : (0.1ms) BEGIN
44D, [2017-11-20T14:10:42.789468 #13628] DEBUG -- : (0.1ms) COMMIT
45I, [2017-11-20T14:10:42.790014 #13628] INFO -- : Filter chain halted as :require_doorkeeper_login rendered or redirected
46I, [2017-11-20T14:10:42.790173 #13628] INFO -- : Completed 401 Unauthorized in 3ms (ActiveRecord: 0.7ms)
47I, [2017-11-20T14:10:42.791182 #13628] INFO -- : Started GET "/api/v2/users/37/followings?name=" for 93.77.143.82 at 2017-11-20 14:10:42 +0000
48I, [2017-11-20T14:10:42.794592 #13628] INFO -- : Processing by Api::V2::FollowingsController#index as JSON
49I, [2017-11-20T14:10:42.794705 #13628] INFO -- : Parameters: {"name"=>"", "user_id"=>"37"}
50D, [2017-11-20T14:10:42.795588 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "add6071ea2b96ebf4329758cc9d1e12f0be08d4254f532591cc7710975e1cb9b"]]
51D, [2017-11-20T14:10:42.796271 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
52D, [2017-11-20T14:10:42.796747 #13628] DEBUG -- : (0.1ms) BEGIN
53D, [2017-11-20T14:10:42.797436 #13628] DEBUG -- : (0.1ms) COMMIT
54I, [2017-11-20T14:10:42.797936 #13628] INFO -- : Filter chain halted as :require_doorkeeper_login rendered or redirected
55I, [2017-11-20T14:10:42.798120 #13628] INFO -- : Completed 401 Unauthorized in 3ms (ActiveRecord: 0.7ms)
56I, [2017-11-20T14:10:42.799036 #13628] INFO -- : Started POST "/api/v2/devices" for 93.77.143.82 at 2017-11-20 14:10:42 +0000
57I, [2017-11-20T14:10:42.805458 #13628] INFO -- : Processing by Api::V2::DevicesController#create as JSON
58I, [2017-11-20T14:10:42.805590 #13628] INFO -- : Parameters: {"os"=>"ios", "token"=>"ftBSOlOS5S4:APA91bFT8lR9wrRZJRe5MberIUGNGs0ReXEORzrXCX4Dce1SVTKHfamDWMbFnl06AMfPx5xSUHA2ZnCR7oUnfQbU6dwhuat1u9DwhkvkUHXzLoe7sgiTEPpdC17odFKQ5n-YoNdRdBpj", "device"=>{"token"=>"ftBSOlOS5S4:APA91bFT8lR9wrRZJRe5MberIUGNGs0ReXEORzrXCX4Dce1SVTKHfamDWMbFnl06AMfPx5xSUHA2ZnCR7oUnfQbU6dwhuat1u9DwhkvkUHXzLoe7sgiTEPpdC17odFKQ5n-YoNdRdBpj", "os"=>"ios"}}
59D, [2017-11-20T14:10:42.806333 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "add6071ea2b96ebf4329758cc9d1e12f0be08d4254f532591cc7710975e1cb9b"]]
60D, [2017-11-20T14:10:42.807011 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
61D, [2017-11-20T14:10:42.807514 #13628] DEBUG -- : (0.1ms) BEGIN
62D, [2017-11-20T14:10:42.808240 #13628] DEBUG -- : (0.1ms) COMMIT
63I, [2017-11-20T14:10:42.808747 #13628] INFO -- : Filter chain halted as :require_doorkeeper_login rendered or redirected
64I, [2017-11-20T14:10:42.808921 #13628] INFO -- : Completed 401 Unauthorized in 3ms (ActiveRecord: 1.6ms)
65I, [2017-11-20T14:10:42.809786 #13628] INFO -- : Started GET "/api/v2/conversations" for 93.77.143.82 at 2017-11-20 14:10:42 +0000
66I, [2017-11-20T14:10:42.812921 #13628] INFO -- : Processing by Api::V2::ConversationsController#index as JSON
67D, [2017-11-20T14:10:42.813674 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "add6071ea2b96ebf4329758cc9d1e12f0be08d4254f532591cc7710975e1cb9b"]]
68D, [2017-11-20T14:10:42.814264 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.1ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
69D, [2017-11-20T14:10:42.814731 #13628] DEBUG -- : (0.1ms) BEGIN
70D, [2017-11-20T14:10:42.815406 #13628] DEBUG -- : (0.1ms) COMMIT
71I, [2017-11-20T14:10:42.815899 #13628] INFO -- : Filter chain halted as :require_doorkeeper_login rendered or redirected
72I, [2017-11-20T14:10:42.816090 #13628] INFO -- : Completed 401 Unauthorized in 3ms (ActiveRecord: 0.5ms)
73I, [2017-11-20T14:10:43.019476 #13628] INFO -- : Started POST "/oauth/token" for 93.77.143.82 at 2017-11-20 14:10:43 +0000
74I, [2017-11-20T14:10:43.021898 #13628] INFO -- : Processing by Doorkeeper::TokensController#create as */*
75I, [2017-11-20T14:10:43.022067 #13628] INFO -- : Parameters: {"client_secret"=>"[FILTERED]", "grant_type"=>"refresh_token", "scope"=>"", "refresh_token"=>"[FILTERED]", "client_id"=>"f6a807c3dd3755e3663cb4f4a470323e297e72b0b6dbc1d8737997cd037b69d0"}
76D, [2017-11-20T14:10:43.022882 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", "320b9f09a312239d5d6679549b3a55928abff6bb69d1e4d61c1a32b094dcb228"]]
77D, [2017-11-20T14:10:43.026643 #13628] DEBUG -- : Doorkeeper::Application Load (0.4ms) SELECT "oauth_applications".* FROM "oauth_applications" WHERE "oauth_applications"."uid" = $1 AND "oauth_applications"."secret" = $2 LIMIT 1 [["uid", "f6a807c3dd3755e3663cb4f4a470323e297e72b0b6dbc1d8737997cd037b69d0"], ["secret", "7efea1b4582b372d76a304261c7d1e7ce7c4b5ef0d2adb1befdde0d34cb518c8"]]
78D, [2017-11-20T14:10:43.037200 #13628] DEBUG -- : (0.1ms) BEGIN
79D, [2017-11-20T14:10:43.039820 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.3ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."id" = $1 LIMIT 1 FOR UPDATE [["id", 3715]]
80D, [2017-11-20T14:10:43.053434 #13628] DEBUG -- : Doorkeeper::Application Load (0.2ms) SELECT "oauth_applications".* FROM "oauth_applications" WHERE "oauth_applications"."id" = $1 LIMIT 1 [["id", 3]]
81D, [2017-11-20T14:10:43.055365 #13628] DEBUG -- : Doorkeeper::AccessToken Exists (0.3ms) SELECT 1 AS one FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = '661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8' LIMIT 1
82D, [2017-11-20T14:10:43.056383 #13628] DEBUG -- : Doorkeeper::AccessToken Exists (0.3ms) SELECT 1 AS one FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = '3d9056ebac30dbe0ea87679e1cababe79ec0f3bf43edfb8bdba6241acee8b873' LIMIT 1
83D, [2017-11-20T14:10:43.058568 #13628] DEBUG -- : SQL (0.8ms) INSERT INTO "oauth_access_tokens" ("application_id", "resource_owner_id", "scopes", "expires_in", "previous_refresh_token", "created_at", "token", "refresh_token") VALUES ($1, $2, $3, $4, $5, $6, $7, $8) RETURNING "id" [["application_id", 3], ["resource_owner_id", 37], ["scopes", "user_all"], ["expires_in", 7200], ["previous_refresh_token", "320b9f09a312239d5d6679549b3a55928abff6bb69d1e4d61c1a32b094dcb228"], ["created_at", "2017-11-20 14:10:43.041154"], ["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"], ["refresh_token", "3d9056ebac30dbe0ea87679e1cababe79ec0f3bf43edfb8bdba6241acee8b873"]]
84D, [2017-11-20T14:10:43.060279 #13628] DEBUG -- : (1.1ms) COMMIT
85I, [2017-11-20T14:10:43.060897 #13628] INFO -- : Completed 200 OK in 39ms
86I, [2017-11-20T14:10:43.300746 #13628] INFO -- : Started GET "/api/v2/users?name=" for 93.77.143.82 at 2017-11-20 14:10:43 +0000
87I, [2017-11-20T14:10:43.302591 #13628] INFO -- : Processing by Api::V2::UsersController#index as JSON
88I, [2017-11-20T14:10:43.302660 #13628] INFO -- : Parameters: {"name"=>""}
89D, [2017-11-20T14:10:43.303843 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.5ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
90D, [2017-11-20T14:10:43.304676 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", "320b9f09a312239d5d6679549b3a55928abff6bb69d1e4d61c1a32b094dcb228"]]
91D, [2017-11-20T14:10:43.305411 #13628] DEBUG -- : (0.1ms) BEGIN
92D, [2017-11-20T14:10:43.308445 #13628] DEBUG -- : SQL (0.3ms) UPDATE "oauth_access_tokens" SET "revoked_at" = $1 WHERE "oauth_access_tokens"."id" = $2 [["revoked_at", "2017-11-20 14:10:43.304989"], ["id", 3715]]
93D, [2017-11-20T14:10:43.309743 #13628] DEBUG -- : (0.8ms) COMMIT
94D, [2017-11-20T14:10:43.310149 #13628] DEBUG -- : (0.1ms) BEGIN
95D, [2017-11-20T14:10:43.311360 #13628] DEBUG -- : SQL (0.2ms) UPDATE "oauth_access_tokens" SET "previous_refresh_token" = $1 WHERE "oauth_access_tokens"."id" = $2 [["previous_refresh_token", ""], ["id", 3718]]
96D, [2017-11-20T14:10:43.312599 #13628] DEBUG -- : (0.8ms) COMMIT
97D, [2017-11-20T14:10:43.317387 #13628] DEBUG -- : User Load (0.5ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
98D, [2017-11-20T14:10:43.333561 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
99D, [2017-11-20T14:10:43.333861 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
100D, [2017-11-20T14:10:43.346477 #13628] DEBUG -- : ActivityHistory Load (1.1ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
101D, [2017-11-20T14:10:43.352897 #13628] DEBUG -- : Multi geocoding. address: 93.77.143.82, args []
102D, [2017-11-20T14:10:43.353053 #13628] DEBUG -- : GeoPlugin geocoding. address: 93.77.143.82, args []
103D, [2017-11-20T14:10:43.986116 #13628] DEBUG -- : GeoPlugin geocoding. Result: %3C%3Fxml+version%3D%221.0%22+encoding%3D%22UTF-8%22%3F%3E%0A%3CgeoPlugin%3E%0A%09%3Cgeoplugin_request%3E93.77.143.82%3C%2Fgeoplugin_request%3E%0A%09%3Cgeoplugin_status%3E200%3C%2Fgeoplugin_status%3E%0A%09%3Cgeoplugin_credit%3ESome+of+the+returned+data+includes+GeoLite+data+created+by+MaxMind%2C+available+from+%26lt%3Ba+href%3D%27http%3A%2F%2Fwww.maxmind.com%27%26gt%3Bhttp%3A%2F%2Fwww.maxmind.com%26lt%3B%2Fa%26gt%3B.%3C%2Fgeoplugin_credit%3E%0A%09%3Cgeoplugin_city%3ELviv%3C%2Fgeoplugin_city%3E%0A%09%3Cgeoplugin_region%3EL%26amp%3B%23039%3Bvivs%26amp%3B%23039%3Bka+Oblast%26amp%3B%23039%3B%3C%2Fgeoplugin_region%3E%0A%09%3Cgeoplugin_areaCode%3E0%3C%2Fgeoplugin_areaCode%3E%0A%09%3Cgeoplugin_dmaCode%3E0%3C%2Fgeoplugin_dmaCode%3E%0A%09%3Cgeoplugin_countryCode%3EUA%3C%2Fgeoplugin_countryCode%3E%0A%09%3Cgeoplugin_countryName%3EUkraine%3C%2Fgeoplugin_countryName%3E%0A%09%3Cgeoplugin_continentCode%3EEU%3C%2Fgeoplugin_continentCode%3E%0A%09%3Cgeoplugin_latitude%3E49.8407%3C%2Fgeoplugin_latitude%3E%0A%09%3Cgeoplugin_longitude%3E24.0305%3C%2Fgeoplugin_longitude%3E%0A%09%3Cgeoplugin_regionCode%3E15%3C%2Fgeoplugin_regionCode%3E%0A%09%3Cgeoplugin_regionName%3EL%26amp%3B%23039%3Bvivs%26amp%3B%23039%3Bka+Oblast%26amp%3B%23039%3B%3C%2Fgeoplugin_regionName%3E%0A%09%3Cgeoplugin_currencyCode%3EUAH%3C%2Fgeoplugin_currencyCode%3E%0A%09%3Cgeoplugin_currencySymbol%3E%26amp%3B%238372%3B%3C%2Fgeoplugin_currencySymbol%3E%0A%09%3Cgeoplugin_currencySymbol_UTF8%3E%E2%82%B4%3C%2Fgeoplugin_currencySymbol_UTF8%3E%0A%09%3Cgeoplugin_currencyConverter%3E26.465%3C%2Fgeoplugin_currencyConverter%3E%0A%3C%2FgeoPlugin%3E%0A
104D, [2017-11-20T14:10:43.998476 #13628] DEBUG -- : Location Load (0.4ms) SELECT "locations".* FROM "locations" WHERE "locations"."country" = $1 AND "locations"."city" = $2 ORDER BY "locations"."id" ASC LIMIT 1 [["country", "Ukraine"], ["city", "Lviv"]]
105D, [2017-11-20T14:10:44.003537 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
106D, [2017-11-20T14:10:44.005792 #13628] DEBUG -- : (0.1ms) BEGIN
107D, [2017-11-20T14:10:44.008366 #13628] DEBUG -- : SQL (0.7ms) INSERT INTO "activity_histories" ("user_id", "ip", "user_agent", "location_id", "more_data", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5, $6, $7) RETURNING "id" [["user_id", 37], ["ip", "93.77.143.82"], ["user_agent", "nuuz/1.0 (com.seafrontmedia.nuuz; build:15; iOS 10.3.1) Alamofire/4.5.1"], ["location_id", 1], ["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\"]}"], ["created_at", "2017-11-20 14:10:44.006464"], ["updated_at", "2017-11-20 14:10:44.006464"]]
108D, [2017-11-20T14:10:44.009990 #13628] DEBUG -- : (1.0ms) COMMIT
109D, [2017-11-20T14:10:44.010809 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
110D, [2017-11-20T14:10:44.014733 #13628] DEBUG -- : User Load (0.7ms) SELECT "users".* FROM "users" WHERE ("users"."id" != $1) ORDER BY "users"."id" DESC LIMIT 30 [["id", 37]]
111D, [2017-11-20T14:10:44.016356 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
112D, [2017-11-20T14:10:44.018247 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
113D, [2017-11-20T14:10:44.053928 #13628] DEBUG -- : [active_model_serializers] (0.4ms) SELECT "relationships"."following_id", "relationships"."status" FROM "relationships" WHERE "relationships"."follower_id" = $1 [["follower_id", 37]]
114D, [2017-11-20T14:10:44.055903 #13628] DEBUG -- : [active_model_serializers] (0.2ms) SELECT "relationships"."follower_id", "relationships"."status" FROM "relationships" WHERE "relationships"."following_id" = $1 [["following_id", 37]]
115I, [2017-11-20T14:10:44.134876 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (109.37ms)
116I, [2017-11-20T14:10:44.135207 #13628] INFO -- : Completed 200 OK in 832ms (Views: 114.8ms | ActiveRecord: 14.7ms)
117I, [2017-11-20T14:10:44.136863 #13628] INFO -- : Started GET "/api/v2/publication_sites" for 93.77.143.82 at 2017-11-20 14:10:44 +0000
118I, [2017-11-20T14:10:44.138113 #13628] INFO -- : Processing by Api::V2::PublicationSitesController#index as JSON
119D, [2017-11-20T14:10:44.138920 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
120D, [2017-11-20T14:10:44.139615 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
121D, [2017-11-20T14:10:44.140197 #13628] DEBUG -- : (0.1ms) BEGIN
122D, [2017-11-20T14:10:44.140915 #13628] DEBUG -- : (0.1ms) COMMIT
123D, [2017-11-20T14:10:44.141655 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
124D, [2017-11-20T14:10:44.142161 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
125D, [2017-11-20T14:10:44.142504 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
126D, [2017-11-20T14:10:44.144113 #13628] DEBUG -- : ActivityHistory Load (0.5ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
127D, [2017-11-20T14:10:44.144808 #13628] DEBUG -- : (0.1ms) BEGIN
128D, [2017-11-20T14:10:44.146497 #13628] DEBUG -- : SQL (0.3ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\"]}"], ["updated_at", "2017-11-20 14:10:44.145193"], ["id", 5967]]
129D, [2017-11-20T14:10:44.147845 #13628] DEBUG -- : (0.8ms) COMMIT
130D, [2017-11-20T14:10:44.148488 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
131D, [2017-11-20T14:10:44.153445 #13628] DEBUG -- : (0.4ms) SELECT COUNT(*) FROM "publication_sites_orders" WHERE "publication_sites_orders"."user_id" = $1 [["user_id", 37]]
132D, [2017-11-20T14:10:44.156309 #13628] DEBUG -- : (0.4ms) SELECT COUNT(*) FROM "publication_sites" WHERE "publication_sites"."user_id" IS NULL
133D, [2017-11-20T14:10:44.159120 #13628] DEBUG -- : PublicationSitesOrder Load (0.4ms) SELECT "publication_sites_orders".* FROM "publication_sites_orders" WHERE "publication_sites_orders"."user_id" = $1 AND "publication_sites_orders"."deleted_at" IS NULL ORDER BY "publication_sites_orders"."position" ASC LIMIT 30 [["user_id", 37]]
134D, [2017-11-20T14:10:44.175303 #13628] DEBUG -- : PublicationSite Load (0.5ms) SELECT "publication_sites".* FROM "publication_sites" WHERE "publication_sites"."user_id" IS NULL AND "publication_sites"."id" IN (22, 21, 19, 16, 15, 13, 12, 60, 55, 54, 53, 52, 51, 50, 49, 48, 47, 46, 45, 44, 43, 42, 41, 40, 39, 38, 37, 36, 35, 34) ORDER BY "publication_sites"."position" ASC
135D, [2017-11-20T14:10:44.182227 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
136D, [2017-11-20T14:10:44.184688 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
137I, [2017-11-20T14:10:44.294395 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (109.34ms)
138I, [2017-11-20T14:10:44.294733 #13628] INFO -- : Completed 200 OK in 156ms (Views: 110.2ms | ActiveRecord: 7.3ms)
139I, [2017-11-20T14:10:44.296270 #13628] INFO -- : Started GET "/api/v2/users/current/followers/requests" for 93.77.143.82 at 2017-11-20 14:10:44 +0000
140I, [2017-11-20T14:10:44.297550 #13628] INFO -- : Processing by Api::V2::FollowersController#requests as JSON
141I, [2017-11-20T14:10:44.297624 #13628] INFO -- : Parameters: {"user_id"=>"current"}
142D, [2017-11-20T14:10:44.298504 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
143D, [2017-11-20T14:10:44.299159 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
144D, [2017-11-20T14:10:44.299667 #13628] DEBUG -- : (0.1ms) BEGIN
145D, [2017-11-20T14:10:44.300459 #13628] DEBUG -- : (0.1ms) COMMIT
146D, [2017-11-20T14:10:44.301190 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
147D, [2017-11-20T14:10:44.301733 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
148D, [2017-11-20T14:10:44.302058 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
149D, [2017-11-20T14:10:44.303837 #13628] DEBUG -- : ActivityHistory Load (0.5ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
150D, [2017-11-20T14:10:44.304572 #13628] DEBUG -- : (0.1ms) BEGIN
151D, [2017-11-20T14:10:44.306192 #13628] DEBUG -- : SQL (0.3ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\"]}"], ["updated_at", "2017-11-20 14:10:44.304992"], ["id", 5967]]
152D, [2017-11-20T14:10:44.307587 #13628] DEBUG -- : (0.8ms) COMMIT
153D, [2017-11-20T14:10:44.308216 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
154D, [2017-11-20T14:10:44.308778 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
155D, [2017-11-20T14:10:44.309161 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
156D, [2017-11-20T14:10:44.313832 #13628] DEBUG -- : User Load (0.7ms) SELECT "users".* FROM "users" INNER JOIN "relationships" ON "users"."id" = "relationships"."follower_id" WHERE "relationships"."following_id" = $1 AND "relationships"."status" = $2 ORDER BY "users"."id" DESC LIMIT 30 [["following_id", 37], ["status", "pending"]]
157D, [2017-11-20T14:10:44.314299 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
158D, [2017-11-20T14:10:44.314952 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
159I, [2017-11-20T14:10:44.315379 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (0.11ms)
160I, [2017-11-20T14:10:44.315570 #13628] INFO -- : Completed 200 OK in 18ms (Views: 0.9ms | ActiveRecord: 3.6ms)
161I, [2017-11-20T14:10:44.316408 #13628] INFO -- : Started GET "/api/v2/users/37/followings?name=" for 93.77.143.82 at 2017-11-20 14:10:44 +0000
162I, [2017-11-20T14:10:44.317293 #13628] INFO -- : Processing by Api::V2::FollowingsController#index as JSON
163I, [2017-11-20T14:10:44.317362 #13628] INFO -- : Parameters: {"name"=>"", "user_id"=>"37"}
164D, [2017-11-20T14:10:44.317998 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
165D, [2017-11-20T14:10:44.318545 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.1ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
166D, [2017-11-20T14:10:44.318982 #13628] DEBUG -- : (0.1ms) BEGIN
167D, [2017-11-20T14:10:44.319659 #13628] DEBUG -- : (0.1ms) COMMIT
168D, [2017-11-20T14:10:44.320169 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
169D, [2017-11-20T14:10:44.320669 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
170D, [2017-11-20T14:10:44.320977 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
171D, [2017-11-20T14:10:44.322451 #13628] DEBUG -- : ActivityHistory Load (0.4ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
172D, [2017-11-20T14:10:44.323097 #13628] DEBUG -- : (0.1ms) BEGIN
173D, [2017-11-20T14:10:44.324604 #13628] DEBUG -- : SQL (0.2ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\"]}"], ["updated_at", "2017-11-20 14:10:44.323459"], ["id", 5967]]
174D, [2017-11-20T14:10:44.325918 #13628] DEBUG -- : (0.7ms) COMMIT
175D, [2017-11-20T14:10:44.326419 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
176D, [2017-11-20T14:10:44.329256 #13628] DEBUG -- : User Load (0.7ms) SELECT "users".* FROM "users" INNER JOIN "relationships" ON "users"."id" = "relationships"."following_id" WHERE "relationships"."follower_id" = $1 AND "relationships"."status" = $2 ORDER BY "users"."id" DESC LIMIT 30 [["follower_id", 37], ["status", "approved"]]
177D, [2017-11-20T14:10:44.329971 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
178D, [2017-11-20T14:10:44.330780 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
179D, [2017-11-20T14:10:44.337347 #13628] DEBUG -- : [active_model_serializers] (0.2ms) SELECT "relationships"."following_id", "relationships"."status" FROM "relationships" WHERE "relationships"."follower_id" = $1 [["follower_id", 37]]
180D, [2017-11-20T14:10:44.338278 #13628] DEBUG -- : [active_model_serializers] (0.2ms) SELECT "relationships"."follower_id", "relationships"."status" FROM "relationships" WHERE "relationships"."following_id" = $1 [["following_id", 37]]
181I, [2017-11-20T14:10:44.353287 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (22.2ms)
182I, [2017-11-20T14:10:44.353513 #13628] INFO -- : Completed 200 OK in 36ms (Views: 22.5ms | ActiveRecord: 3.6ms)
183I, [2017-11-20T14:10:44.354555 #13628] INFO -- : Started GET "/api/v2/conversations" for 93.77.143.82 at 2017-11-20 14:10:44 +0000
184I, [2017-11-20T14:10:44.355491 #13628] INFO -- : Processing by Api::V2::ConversationsController#index as JSON
185D, [2017-11-20T14:10:44.356192 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
186D, [2017-11-20T14:10:44.356802 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.1ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
187D, [2017-11-20T14:10:44.357274 #13628] DEBUG -- : (0.1ms) BEGIN
188D, [2017-11-20T14:10:44.357992 #13628] DEBUG -- : (0.1ms) COMMIT
189D, [2017-11-20T14:10:44.358543 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
190D, [2017-11-20T14:10:44.359028 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
191D, [2017-11-20T14:10:44.359351 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
192D, [2017-11-20T14:10:44.360830 #13628] DEBUG -- : ActivityHistory Load (0.4ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
193D, [2017-11-20T14:10:44.361474 #13628] DEBUG -- : (0.1ms) BEGIN
194D, [2017-11-20T14:10:44.363007 #13628] DEBUG -- : SQL (0.3ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\"]}"], ["updated_at", "2017-11-20 14:10:44.361860"], ["id", 5967]]
195D, [2017-11-20T14:10:44.383615 #13628] DEBUG -- : (0.8ms) COMMIT
196D, [2017-11-20T14:10:44.384328 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
197D, [2017-11-20T14:10:44.392688 #13628] DEBUG -- : Conversation Load (0.7ms) SELECT "conversations".* FROM "conversations" INNER JOIN "conversation_members" ON "conversations"."id" = "conversation_members"."conversation_id" WHERE "conversation_members"."user_id" = $1 ORDER BY "conversations"."updated_at" DESC LIMIT 30 OFFSET 0 [["user_id", 37]]
198D, [2017-11-20T14:10:44.400208 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37)
199D, [2017-11-20T14:10:44.410716 #13628] DEBUG -- : ConversationMember Load (0.3ms) SELECT "conversation_members".* FROM "conversation_members" WHERE "conversation_members"."conversation_id" IN (18, 47)
200D, [2017-11-20T14:10:44.418828 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37, 45)
201D, [2017-11-20T14:10:44.426877 #13628] DEBUG -- : Message Load (0.7ms) SELECT DISTINCT ON(conversation_id) "messages".* FROM "messages" WHERE "messages"."conversation_id" IN (18, 47) ORDER BY "messages"."conversation_id" DESC, "messages"."id" DESC
202D, [2017-11-20T14:10:44.435067 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (37)
203D, [2017-11-20T14:10:44.435716 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
204D, [2017-11-20T14:10:44.436737 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
205I, [2017-11-20T14:10:44.466391 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (29.32ms)
206I, [2017-11-20T14:10:44.466640 #13628] INFO -- : Completed 200 OK in 111ms (Views: 30.1ms | ActiveRecord: 9.5ms)
207I, [2017-11-20T14:10:44.468051 #13628] INFO -- : Started GET "/api/v2/feed?last_id=0" for 93.77.143.82 at 2017-11-20 14:10:44 +0000
208I, [2017-11-20T14:10:44.469230 #13628] INFO -- : Processing by Api::V2::FeedController#index as JSON
209I, [2017-11-20T14:10:44.469296 #13628] INFO -- : Parameters: {"last_id"=>"0"}
210D, [2017-11-20T14:10:44.470003 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
211D, [2017-11-20T14:10:44.470719 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
212D, [2017-11-20T14:10:44.471209 #13628] DEBUG -- : (0.1ms) BEGIN
213D, [2017-11-20T14:10:44.471903 #13628] DEBUG -- : (0.1ms) COMMIT
214D, [2017-11-20T14:10:44.472475 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
215D, [2017-11-20T14:10:44.472981 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
216D, [2017-11-20T14:10:44.473283 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
217D, [2017-11-20T14:10:44.474946 #13628] DEBUG -- : ActivityHistory Load (0.5ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
218D, [2017-11-20T14:10:44.475609 #13628] DEBUG -- : (0.1ms) BEGIN
219D, [2017-11-20T14:10:44.477398 #13628] DEBUG -- : SQL (0.3ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/feed\"]}"], ["updated_at", "2017-11-20 14:10:44.476194"], ["id", 5967]]
220D, [2017-11-20T14:10:44.478994 #13628] DEBUG -- : (0.8ms) COMMIT
221D, [2017-11-20T14:10:44.479541 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
222D, [2017-11-20T14:10:44.481230 #13628] DEBUG -- : (0.3ms) SELECT "relationships"."following_id" FROM "relationships" WHERE "relationships"."follower_id" = $1 AND "relationships"."status" = $2 [["follower_id", 37], ["status", "approved"]]
223D, [2017-11-20T14:10:44.488687 #13628] DEBUG -- : (0.5ms) SELECT "users"."id" FROM "users" INNER JOIN "blacklists" ON "users"."id" = "blacklists"."blacklisted_user_id" WHERE "blacklists"."user_id" = $1 [["user_id", 37]]
224D, [2017-11-20T14:10:44.489892 #13628] DEBUG -- : (0.2ms) SELECT "users"."id" FROM "users" WHERE "users"."is_active" = $1 [["is_active", "f"]]
225D, [2017-11-20T14:10:44.493417 #13628] DEBUG -- : Feed Load (0.9ms) SELECT "feeds".* FROM "feeds" WHERE "feeds"."user_id" IN (37, 12, 43, 44, 40, 39, 38, 50) AND "feeds"."feedable_type" = $1 ORDER BY "feeds"."feedable_id" DESC LIMIT 30 [["feedable_type", "Story"]]
226D, [2017-11-20T14:10:44.505981 #13628] DEBUG -- : Story Load (0.7ms) SELECT "stories".* FROM "stories" WHERE "stories"."id" IN (313, 312, 311, 257, 234, 233, 232, 218, 217, 142, 112)
227D, [2017-11-20T14:10:44.521533 #13628] DEBUG -- : Page Load (0.6ms) SELECT "pages".* FROM "pages" WHERE "pages"."id" IN (67, 166, 30, 127, 126, 139, 141, 140, 206, 200)
228D, [2017-11-20T14:10:44.531554 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (37, 12, 50)
229D, [2017-11-20T14:10:44.532370 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
230D, [2017-11-20T14:10:44.532734 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
231D, [2017-11-20T14:10:44.537337 #13628] DEBUG -- : StoryLike Load (0.7ms) SELECT "story_likes".* FROM "story_likes" WHERE "story_likes"."user_id" = $1 AND "story_likes"."story_id" IN (313, 312, 311, 257, 234, 233, 232, 218, 217, 142, 112) [["user_id", 37]]
232D, [2017-11-20T14:10:44.544576 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (37)
233D, [2017-11-20T14:10:44.549218 #13628] DEBUG -- : ReadingList Load (0.4ms) SELECT "reading_lists".* FROM "reading_lists" WHERE "reading_lists"."user_id" = $1 AND "reading_lists"."later_readable_type" = $2 AND "reading_lists"."later_readable_id" IN (313, 312, 311, 257, 234, 233, 232, 218, 217, 142, 112) [["user_id", 37], ["later_readable_type", "Story"]]
234D, [2017-11-20T14:10:44.556306 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
235I, [2017-11-20T14:10:44.626399 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (69.67ms)
236I, [2017-11-20T14:10:44.626737 #13628] INFO -- : Completed 200 OK in 157ms (Views: 69.9ms | ActiveRecord: 14.7ms)
237I, [2017-11-20T14:10:44.628279 #13628] INFO -- : Started POST "/api/v2/devices" for 93.77.143.82 at 2017-11-20 14:10:44 +0000
238I, [2017-11-20T14:10:44.629731 #13628] INFO -- : Processing by Api::V2::DevicesController#create as JSON
239I, [2017-11-20T14:10:44.629819 #13628] INFO -- : Parameters: {"os"=>"ios", "token"=>"ftBSOlOS5S4:APA91bFT8lR9wrRZJRe5MberIUGNGs0ReXEORzrXCX4Dce1SVTKHfamDWMbFnl06AMfPx5xSUHA2ZnCR7oUnfQbU6dwhuat1u9DwhkvkUHXzLoe7sgiTEPpdC17odFKQ5n-YoNdRdBpj", "device"=>{"token"=>"ftBSOlOS5S4:APA91bFT8lR9wrRZJRe5MberIUGNGs0ReXEORzrXCX4Dce1SVTKHfamDWMbFnl06AMfPx5xSUHA2ZnCR7oUnfQbU6dwhuat1u9DwhkvkUHXzLoe7sgiTEPpdC17odFKQ5n-YoNdRdBpj", "os"=>"ios"}}
240D, [2017-11-20T14:10:44.630676 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
241D, [2017-11-20T14:10:44.631359 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.1ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
242D, [2017-11-20T14:10:44.631868 #13628] DEBUG -- : (0.1ms) BEGIN
243D, [2017-11-20T14:10:44.632661 #13628] DEBUG -- : (0.1ms) COMMIT
244D, [2017-11-20T14:10:44.633261 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
245D, [2017-11-20T14:10:44.633804 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
246D, [2017-11-20T14:10:44.634136 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
247D, [2017-11-20T14:10:44.635656 #13628] DEBUG -- : ActivityHistory Load (0.3ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
248D, [2017-11-20T14:10:44.636361 #13628] DEBUG -- : (0.1ms) BEGIN
249D, [2017-11-20T14:10:44.638134 #13628] DEBUG -- : SQL (0.3ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/feed\",\"2017-11-20 14:10:44 +0000\\t/api/v2/devices\"]}"], ["updated_at", "2017-11-20 14:10:44.636818"], ["id", 5967]]
250D, [2017-11-20T14:10:44.639625 #13628] DEBUG -- : (0.8ms) COMMIT
251D, [2017-11-20T14:10:44.646887 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
252D, [2017-11-20T14:10:44.648598 #13628] DEBUG -- : (0.1ms) BEGIN
253D, [2017-11-20T14:10:44.650931 #13628] DEBUG -- : SQL (0.9ms) INSERT INTO "devices" ("token", "os", "user_id", "created_at", "updated_at") VALUES ($1, $2, $3, $4, $5) RETURNING "id" [["token", "ftBSOlOS5S4:APA91bFT8lR9wrRZJRe5MberIUGNGs0ReXEORzrXCX4Dce1SVTKHfamDWMbFnl06AMfPx5xSUHA2ZnCR7oUnfQbU6dwhuat1u9DwhkvkUHXzLoe7sgiTEPpdC17odFKQ5n-YoNdRdBpj"], ["os", 2], ["user_id", 37], ["created_at", "2017-11-20 14:10:44.649020"], ["updated_at", "2017-11-20 14:10:44.649020"]]
254D, [2017-11-20T14:10:44.651335 #13628] DEBUG -- : (0.1ms) ROLLBACK
255I, [2017-11-20T14:10:44.652182 #13628] INFO -- : Completed 500 Internal Server Error in 22ms (ActiveRecord: 4.2ms)
256F, [2017-11-20T14:10:44.653681 #13628] FATAL -- :
257PG::UniqueViolation (ERROR: duplicate key value violates unique constraint "index_devices_on_token"
258DETAIL: Key (token)=(ftBSOlOS5S4:APA91bFT8lR9wrRZJRe5MberIUGNGs0ReXEORzrXCX4Dce1SVTKHfamDWMbFnl06AMfPx5xSUHA2ZnCR7oUnfQbU6dwhuat1u9DwhkvkUHXzLoe7sgiTEPpdC17odFKQ5n-YoNdRdBpj) already exists.
259):
260 app/controllers/api/v2/devices_controller.rb:9:in `create'
261
262
263I, [2017-11-20T14:10:44.654769 #13628] INFO -- : Started GET "/api/v2/conversations" for 93.77.143.82 at 2017-11-20 14:10:44 +0000
264I, [2017-11-20T14:10:44.655851 #13628] INFO -- : Processing by Api::V2::ConversationsController#index as JSON
265D, [2017-11-20T14:10:44.656631 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
266D, [2017-11-20T14:10:44.657287 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
267D, [2017-11-20T14:10:44.657773 #13628] DEBUG -- : (0.1ms) BEGIN
268D, [2017-11-20T14:10:44.658521 #13628] DEBUG -- : (0.1ms) COMMIT
269D, [2017-11-20T14:10:44.659120 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
270D, [2017-11-20T14:10:44.659682 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
271D, [2017-11-20T14:10:44.660036 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
272D, [2017-11-20T14:10:44.661658 #13628] DEBUG -- : ActivityHistory Load (0.3ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
273D, [2017-11-20T14:10:44.662338 #13628] DEBUG -- : (0.1ms) BEGIN
274D, [2017-11-20T14:10:44.664067 #13628] DEBUG -- : SQL (0.2ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/feed\",\"2017-11-20 14:10:44 +0000\\t/api/v2/devices\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\"]}"], ["updated_at", "2017-11-20 14:10:44.662792"], ["id", 5967]]
275D, [2017-11-20T14:10:44.665512 #13628] DEBUG -- : (0.7ms) COMMIT
276D, [2017-11-20T14:10:44.666102 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
277D, [2017-11-20T14:10:44.668319 #13628] DEBUG -- : Conversation Load (0.5ms) SELECT "conversations".* FROM "conversations" INNER JOIN "conversation_members" ON "conversations"."id" = "conversation_members"."conversation_id" WHERE "conversation_members"."user_id" = $1 ORDER BY "conversations"."updated_at" DESC LIMIT 30 OFFSET 0 [["user_id", 37]]
278D, [2017-11-20T14:10:44.669910 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37)
279D, [2017-11-20T14:10:44.671543 #13628] DEBUG -- : ConversationMember Load (0.3ms) SELECT "conversation_members".* FROM "conversation_members" WHERE "conversation_members"."conversation_id" IN (18, 47)
280D, [2017-11-20T14:10:44.673903 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37, 45)
281D, [2017-11-20T14:10:44.675786 #13628] DEBUG -- : Message Load (0.5ms) SELECT DISTINCT ON(conversation_id) "messages".* FROM "messages" WHERE "messages"."conversation_id" IN (18, 47) ORDER BY "messages"."conversation_id" DESC, "messages"."id" DESC
282D, [2017-11-20T14:10:44.677412 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (37)
283D, [2017-11-20T14:10:44.678032 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
284D, [2017-11-20T14:10:44.678907 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
285I, [2017-11-20T14:10:44.705285 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (26.02ms)
286I, [2017-11-20T14:10:44.705563 #13628] INFO -- : Completed 200 OK in 50ms (Views: 26.9ms | ActiveRecord: 4.7ms)
287I, [2017-11-20T14:10:44.717341 #13628] INFO -- : Started GET "/api/v2/conversations" for 93.77.143.82 at 2017-11-20 14:10:44 +0000
288I, [2017-11-20T14:10:44.718171 #13628] INFO -- : Processing by Api::V2::ConversationsController#index as JSON
289D, [2017-11-20T14:10:44.718817 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
290D, [2017-11-20T14:10:44.719348 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
291D, [2017-11-20T14:10:44.719763 #13628] DEBUG -- : (0.1ms) BEGIN
292D, [2017-11-20T14:10:44.720423 #13628] DEBUG -- : (0.1ms) COMMIT
293D, [2017-11-20T14:10:44.720911 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
294D, [2017-11-20T14:10:44.721266 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
295D, [2017-11-20T14:10:44.721489 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
296D, [2017-11-20T14:10:44.724074 #13628] DEBUG -- : ActivityHistory Load (1.7ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
297D, [2017-11-20T14:10:44.724783 #13628] DEBUG -- : (0.1ms) BEGIN
298D, [2017-11-20T14:10:44.726506 #13628] DEBUG -- : SQL (0.2ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/feed\",\"2017-11-20 14:10:44 +0000\\t/api/v2/devices\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\"]}"], ["updated_at", "2017-11-20 14:10:44.725306"], ["id", 5967]]
299D, [2017-11-20T14:10:44.728099 #13628] DEBUG -- : (0.8ms) COMMIT
300D, [2017-11-20T14:10:44.728681 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
301D, [2017-11-20T14:10:44.730780 #13628] DEBUG -- : Conversation Load (0.5ms) SELECT "conversations".* FROM "conversations" INNER JOIN "conversation_members" ON "conversations"."id" = "conversation_members"."conversation_id" WHERE "conversation_members"."user_id" = $1 ORDER BY "conversations"."updated_at" DESC LIMIT 30 OFFSET 0 [["user_id", 37]]
302D, [2017-11-20T14:10:44.732357 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37)
303D, [2017-11-20T14:10:44.734047 #13628] DEBUG -- : ConversationMember Load (0.3ms) SELECT "conversation_members".* FROM "conversation_members" WHERE "conversation_members"."conversation_id" IN (18, 47)
304D, [2017-11-20T14:10:44.736337 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37, 45)
305D, [2017-11-20T14:10:44.738161 #13628] DEBUG -- : Message Load (0.4ms) SELECT DISTINCT ON(conversation_id) "messages".* FROM "messages" WHERE "messages"."conversation_id" IN (18, 47) ORDER BY "messages"."conversation_id" DESC, "messages"."id" DESC
306D, [2017-11-20T14:10:44.739519 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (37)
307D, [2017-11-20T14:10:44.740176 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
308D, [2017-11-20T14:10:44.741004 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
309I, [2017-11-20T14:10:44.767586 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (26.2ms)
310I, [2017-11-20T14:10:44.767898 #13628] INFO -- : Completed 200 OK in 50ms (Views: 27.1ms | ActiveRecord: 6.1ms)
311I, [2017-11-20T14:10:44.850353 #13628] INFO -- : Started GET "/api/v2/users/37/followings?last_id=12&name=" for 93.77.143.82 at 2017-11-20 14:10:44 +0000
312I, [2017-11-20T14:10:44.851793 #13628] INFO -- : Processing by Api::V2::FollowingsController#index as JSON
313I, [2017-11-20T14:10:44.851912 #13628] INFO -- : Parameters: {"last_id"=>"12", "name"=>"", "user_id"=>"37"}
314D, [2017-11-20T14:10:44.852763 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
315D, [2017-11-20T14:10:44.853463 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.1ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
316D, [2017-11-20T14:10:44.853994 #13628] DEBUG -- : (0.1ms) BEGIN
317D, [2017-11-20T14:10:44.854781 #13628] DEBUG -- : (0.1ms) COMMIT
318D, [2017-11-20T14:10:44.855386 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
319D, [2017-11-20T14:10:44.856013 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
320D, [2017-11-20T14:10:44.856366 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
321D, [2017-11-20T14:10:44.857973 #13628] DEBUG -- : ActivityHistory Load (0.3ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
322D, [2017-11-20T14:10:44.858686 #13628] DEBUG -- : (0.1ms) BEGIN
323D, [2017-11-20T14:10:44.860500 #13628] DEBUG -- : SQL (0.2ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/feed\",\"2017-11-20 14:10:44 +0000\\t/api/v2/devices\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\"]}"], ["updated_at", "2017-11-20 14:10:44.859201"], ["id", 5967]]
324D, [2017-11-20T14:10:44.862235 #13628] DEBUG -- : (0.9ms) COMMIT
325D, [2017-11-20T14:10:44.862784 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
326D, [2017-11-20T14:10:44.866549 #13628] DEBUG -- : User Load (0.7ms) SELECT "users".* FROM "users" INNER JOIN "relationships" ON "users"."id" = "relationships"."following_id" WHERE "relationships"."follower_id" = $1 AND "relationships"."status" = $2 AND ("users"."id" < 12) ORDER BY "users"."id" DESC LIMIT 30 [["follower_id", 37], ["status", "approved"]]
327D, [2017-11-20T14:10:44.867054 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
328D, [2017-11-20T14:10:44.867763 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
329I, [2017-11-20T14:10:44.868295 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (0.13ms)
330I, [2017-11-20T14:10:44.868551 #13628] INFO -- : Completed 200 OK in 16ms (Views: 1.0ms | ActiveRecord: 3.2ms)
331I, [2017-11-20T14:10:45.712614 #13628] INFO -- : Started GET "/api/v2/conversations" for 93.77.143.82 at 2017-11-20 14:10:45 +0000
332I, [2017-11-20T14:10:45.713879 #13628] INFO -- : Processing by Api::V2::ConversationsController#index as JSON
333D, [2017-11-20T14:10:45.714685 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
334D, [2017-11-20T14:10:45.715350 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
335D, [2017-11-20T14:10:45.715877 #13628] DEBUG -- : (0.1ms) BEGIN
336D, [2017-11-20T14:10:45.716731 #13628] DEBUG -- : (0.1ms) COMMIT
337D, [2017-11-20T14:10:45.717348 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
338D, [2017-11-20T14:10:45.717930 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
339D, [2017-11-20T14:10:45.718299 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
340D, [2017-11-20T14:10:45.720019 #13628] DEBUG -- : ActivityHistory Load (0.3ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
341D, [2017-11-20T14:10:45.720746 #13628] DEBUG -- : (0.1ms) BEGIN
342D, [2017-11-20T14:10:45.722615 #13628] DEBUG -- : SQL (0.2ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/feed\",\"2017-11-20 14:10:44 +0000\\t/api/v2/devices\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:45 +0000\\t/api/v2/conversations\"]}"], ["updated_at", "2017-11-20 14:10:45.721293"], ["id", 5967]]
343D, [2017-11-20T14:10:45.724332 #13628] DEBUG -- : (0.8ms) COMMIT
344D, [2017-11-20T14:10:45.724949 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
345D, [2017-11-20T14:10:45.727137 #13628] DEBUG -- : Conversation Load (0.5ms) SELECT "conversations".* FROM "conversations" INNER JOIN "conversation_members" ON "conversations"."id" = "conversation_members"."conversation_id" WHERE "conversation_members"."user_id" = $1 ORDER BY "conversations"."updated_at" DESC LIMIT 30 OFFSET 0 [["user_id", 37]]
346D, [2017-11-20T14:10:45.728787 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37)
347D, [2017-11-20T14:10:45.730509 #13628] DEBUG -- : ConversationMember Load (0.3ms) SELECT "conversation_members".* FROM "conversation_members" WHERE "conversation_members"."conversation_id" IN (18, 47)
348D, [2017-11-20T14:10:45.733173 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37, 45)
349D, [2017-11-20T14:10:45.735144 #13628] DEBUG -- : Message Load (0.5ms) SELECT DISTINCT ON(conversation_id) "messages".* FROM "messages" WHERE "messages"."conversation_id" IN (18, 47) ORDER BY "messages"."conversation_id" DESC, "messages"."id" DESC
350D, [2017-11-20T14:10:45.736668 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (37)
351D, [2017-11-20T14:10:45.737315 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
352D, [2017-11-20T14:10:45.738146 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
353I, [2017-11-20T14:10:45.765770 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (27.21ms)
354I, [2017-11-20T14:10:45.766089 #13628] INFO -- : Completed 200 OK in 52ms (Views: 28.2ms | ActiveRecord: 4.8ms)
355I, [2017-11-20T14:10:46.713473 #13628] INFO -- : Started GET "/api/v2/conversations" for 93.77.143.82 at 2017-11-20 14:10:46 +0000
356I, [2017-11-20T14:10:46.714777 #13628] INFO -- : Processing by Api::V2::ConversationsController#index as JSON
357D, [2017-11-20T14:10:46.715608 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.3ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
358D, [2017-11-20T14:10:46.716336 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
359D, [2017-11-20T14:10:46.716930 #13628] DEBUG -- : (0.1ms) BEGIN
360D, [2017-11-20T14:10:46.717745 #13628] DEBUG -- : (0.1ms) COMMIT
361D, [2017-11-20T14:10:46.718361 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
362D, [2017-11-20T14:10:46.718925 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
363D, [2017-11-20T14:10:46.719346 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
364D, [2017-11-20T14:10:46.720994 #13628] DEBUG -- : ActivityHistory Load (0.3ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
365D, [2017-11-20T14:10:46.721756 #13628] DEBUG -- : (0.1ms) BEGIN
366D, [2017-11-20T14:10:46.723617 #13628] DEBUG -- : SQL (0.2ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/feed\",\"2017-11-20 14:10:44 +0000\\t/api/v2/devices\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:45 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:46 +0000\\t/api/v2/conversations\"]}"], ["updated_at", "2017-11-20 14:10:46.722294"], ["id", 5967]]
367D, [2017-11-20T14:10:46.725373 #13628] DEBUG -- : (0.8ms) COMMIT
368D, [2017-11-20T14:10:46.725959 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
369D, [2017-11-20T14:10:46.728091 #13628] DEBUG -- : Conversation Load (0.5ms) SELECT "conversations".* FROM "conversations" INNER JOIN "conversation_members" ON "conversations"."id" = "conversation_members"."conversation_id" WHERE "conversation_members"."user_id" = $1 ORDER BY "conversations"."updated_at" DESC LIMIT 30 OFFSET 0 [["user_id", 37]]
370D, [2017-11-20T14:10:46.729643 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37)
371D, [2017-11-20T14:10:46.731277 #13628] DEBUG -- : ConversationMember Load (0.3ms) SELECT "conversation_members".* FROM "conversation_members" WHERE "conversation_members"."conversation_id" IN (18, 47)
372D, [2017-11-20T14:10:46.733731 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37, 45)
373D, [2017-11-20T14:10:46.735643 #13628] DEBUG -- : Message Load (0.5ms) SELECT DISTINCT ON(conversation_id) "messages".* FROM "messages" WHERE "messages"."conversation_id" IN (18, 47) ORDER BY "messages"."conversation_id" DESC, "messages"."id" DESC
374D, [2017-11-20T14:10:46.737196 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (37)
375D, [2017-11-20T14:10:46.737789 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
376D, [2017-11-20T14:10:46.738655 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
377I, [2017-11-20T14:10:46.765228 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (26.2ms)
378I, [2017-11-20T14:10:46.765503 #13628] INFO -- : Completed 200 OK in 51ms (Views: 27.1ms | ActiveRecord: 4.9ms)
379I, [2017-11-20T14:10:47.713361 #13628] INFO -- : Started GET "/api/v2/conversations" for 93.77.143.82 at 2017-11-20 14:10:47 +0000
380I, [2017-11-20T14:10:47.714650 #13628] INFO -- : Processing by Api::V2::ConversationsController#index as JSON
381D, [2017-11-20T14:10:47.715502 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.3ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
382D, [2017-11-20T14:10:47.716305 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
383D, [2017-11-20T14:10:47.716871 #13628] DEBUG -- : (0.1ms) BEGIN
384D, [2017-11-20T14:10:47.717689 #13628] DEBUG -- : (0.1ms) COMMIT
385D, [2017-11-20T14:10:47.718308 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
386D, [2017-11-20T14:10:47.718939 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
387D, [2017-11-20T14:10:47.719310 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
388D, [2017-11-20T14:10:47.721013 #13628] DEBUG -- : ActivityHistory Load (0.3ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
389D, [2017-11-20T14:10:47.721754 #13628] DEBUG -- : (0.1ms) BEGIN
390D, [2017-11-20T14:10:47.723715 #13628] DEBUG -- : SQL (0.2ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/feed\",\"2017-11-20 14:10:44 +0000\\t/api/v2/devices\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:45 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:46 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:47 +0000\\t/api/v2/conversations\"]}"], ["updated_at", "2017-11-20 14:10:47.722351"], ["id", 5967]]
391D, [2017-11-20T14:10:47.725505 #13628] DEBUG -- : (0.8ms) COMMIT
392D, [2017-11-20T14:10:47.726114 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
393D, [2017-11-20T14:10:47.728407 #13628] DEBUG -- : Conversation Load (0.5ms) SELECT "conversations".* FROM "conversations" INNER JOIN "conversation_members" ON "conversations"."id" = "conversation_members"."conversation_id" WHERE "conversation_members"."user_id" = $1 ORDER BY "conversations"."updated_at" DESC LIMIT 30 OFFSET 0 [["user_id", 37]]
394D, [2017-11-20T14:10:47.730028 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37)
395D, [2017-11-20T14:10:47.731725 #13628] DEBUG -- : ConversationMember Load (0.3ms) SELECT "conversation_members".* FROM "conversation_members" WHERE "conversation_members"."conversation_id" IN (18, 47)
396D, [2017-11-20T14:10:47.734164 #13628] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37, 45)
397D, [2017-11-20T14:10:47.736037 #13628] DEBUG -- : Message Load (0.5ms) SELECT DISTINCT ON(conversation_id) "messages".* FROM "messages" WHERE "messages"."conversation_id" IN (18, 47) ORDER BY "messages"."conversation_id" DESC, "messages"."id" DESC
398D, [2017-11-20T14:10:47.737369 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (37)
399D, [2017-11-20T14:10:47.737984 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
400D, [2017-11-20T14:10:47.738822 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
401I, [2017-11-20T14:10:47.766119 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (26.93ms)
402I, [2017-11-20T14:10:47.766457 #13628] INFO -- : Completed 200 OK in 52ms (Views: 27.9ms | ActiveRecord: 4.8ms)
403I, [2017-11-20T14:10:48.712950 #13628] INFO -- : Started GET "/api/v2/conversations" for 93.77.143.82 at 2017-11-20 14:10:48 +0000
404I, [2017-11-20T14:10:48.713910 #13628] INFO -- : Processing by Api::V2::ConversationsController#index as JSON
405D, [2017-11-20T14:10:48.714608 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
406D, [2017-11-20T14:10:48.715144 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.1ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
407D, [2017-11-20T14:10:48.715568 #13628] DEBUG -- : (0.1ms) BEGIN
408D, [2017-11-20T14:10:48.716237 #13628] DEBUG -- : (0.1ms) COMMIT
409D, [2017-11-20T14:10:48.716698 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
410D, [2017-11-20T14:10:48.717079 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
411D, [2017-11-20T14:10:48.717313 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
412D, [2017-11-20T14:10:48.718528 #13628] DEBUG -- : ActivityHistory Load (0.3ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]
413D, [2017-11-20T14:10:48.719067 #13628] DEBUG -- : (0.1ms) BEGIN
414D, [2017-11-20T14:10:48.720450 #13628] DEBUG -- : SQL (0.2ms) UPDATE "activity_histories" SET "more_data" = $1, "updated_at" = $2 WHERE "activity_histories"."id" = $3 [["more_data", "{\"log\":[\"2017-11-20 14:10:44 +0000\\t/api/v2/users\",\"2017-11-20 14:10:44 +0000\\t/api/v2/publication_sites\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/current/followers/requests\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/feed\",\"2017-11-20 14:10:44 +0000\\t/api/v2/devices\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:44 +0000\\t/api/v2/users/37/followings\",\"2017-11-20 14:10:45 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:46 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:47 +0000\\t/api/v2/conversations\",\"2017-11-20 14:10:48 +0000\\t/api/v2/conversations\"]}"], ["updated_at", "2017-11-20 14:10:48.719468"], ["id", 5967]]
415D, [2017-11-20T14:10:48.721793 #13628] DEBUG -- : (0.7ms) COMMIT
416D, [2017-11-20T14:10:48.722252 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
417D, [2017-11-20T14:10:48.723607 #13628] DEBUG -- : Conversation Load (0.2ms) SELECT "conversations".* FROM "conversations" INNER JOIN "conversation_members" ON "conversations"."id" = "conversation_members"."conversation_id" WHERE "conversation_members"."user_id" = $1 ORDER BY "conversations"."updated_at" DESC LIMIT 30 OFFSET 0 [["user_id", 37]]
418D, [2017-11-20T14:10:48.724785 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37)
419D, [2017-11-20T14:10:48.725967 #13628] DEBUG -- : ConversationMember Load (0.2ms) SELECT "conversation_members".* FROM "conversation_members" WHERE "conversation_members"."conversation_id" IN (18, 47)
420D, [2017-11-20T14:10:48.727589 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (12, 37, 45)
421D, [2017-11-20T14:10:48.728938 #13628] DEBUG -- : Message Load (0.4ms) SELECT DISTINCT ON(conversation_id) "messages".* FROM "messages" WHERE "messages"."conversation_id" IN (18, 47) ORDER BY "messages"."conversation_id" DESC, "messages"."id" DESC
422D, [2017-11-20T14:10:48.729991 #13628] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."id" IN (37)
423D, [2017-11-20T14:10:48.730428 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
424D, [2017-11-20T14:10:48.730997 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
425I, [2017-11-20T14:10:48.748305 #13628] INFO -- : [active_model_serializers] Rendered ActiveModel::Serializer::Null with Hash (17.04ms)
426I, [2017-11-20T14:10:48.748527 #13628] INFO -- : Completed 200 OK in 35ms (Views: 17.7ms | ActiveRecord: 3.8ms)
427I, [2017-11-20T14:10:49.712561 #13628] INFO -- : Started GET "/api/v2/conversations" for 93.77.143.82 at 2017-11-20 14:10:49 +0000
428I, [2017-11-20T14:10:49.713535 #13628] INFO -- : Processing by Api::V2::ConversationsController#index as JSON
429D, [2017-11-20T14:10:49.714200 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.2ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."token" = $1 LIMIT 1 [["token", "661a84ffc283b2d889bd4b3fd7534efa840f970195abaf49572cbe73ebbaacb8"]]
430D, [2017-11-20T14:10:49.714749 #13628] DEBUG -- : Doorkeeper::AccessToken Load (0.1ms) SELECT "oauth_access_tokens".* FROM "oauth_access_tokens" WHERE "oauth_access_tokens"."refresh_token" = $1 LIMIT 1 [["refresh_token", ""]]
431D, [2017-11-20T14:10:49.715167 #13628] DEBUG -- : (0.1ms) BEGIN
432D, [2017-11-20T14:10:49.715760 #13628] DEBUG -- : (0.1ms) COMMIT
433D, [2017-11-20T14:10:49.716253 #13628] DEBUG -- : User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
434D, [2017-11-20T14:10:49.716656 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
435D, [2017-11-20T14:10:49.716899 #13628] DEBUG -- : CACHE (0.0ms) SELECT "users".* FROM "users" WHERE "users"."id" = $1 LIMIT 1 [["id", 37]]
436D, [2017-11-20T14:10:49.718111 #13628] DEBUG -- : ActivityHistory Load (0.3ms) SELECT "activity_histories".* FROM "activity_histories" WHERE "activity_histories"."user_id" = $1 AND "activity_histories"."ip" = $2 ORDER BY "activity_histories"."id" DESC LIMIT 1 [["user_id", 37], ["ip", "93.77.143.82"]]