Skip to content

Instantly share code, notes, and snippets.

@moolitayer

moolitayer/miq_api_alert_queries.log

Created March 14, 2017 06:12
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save moolitayer/d6e87267a3378da460d13229a4e07f66 to your computer and use it in GitHub Desktop.
Save moolitayer/d6e87267a3378da460d13229a4e07f66 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
miq_api_alert_queries.log
[----] I, [2017-03-14T08:10:36.359525 #773:2115e84] INFO -- : Started GET "/api/alerts?expand=resources" for 127.0.0.1 at 2017-03-14 08:10:36 +0200
[----] I, [2017-03-14T08:10:36.388548 #773:2115e84] INFO -- : Processing by Api::AlertsController#index as JSON
[----] I, [2017-03-14T08:10:36.388645 #773:2115e84] INFO -- : Parameters: {"expand"=>"resources"}
[----] D, [2017-03-14T08:10:36.390332 #773:2115e84] DEBUG -- : PostgreSQLAdapter#log_after_checkout, connection_pool: size: 8, connections: 3, in use: 3, waiting_in_queue: 0
[----] D, [2017-03-14T08:10:36.391437 #773:2115e84] DEBUG -- : User Load (0.7ms) SELECT "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4 [["id", 1000000000000], ["id", 1999999999999], ["userid", "admin"], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.391800 #773:2115e84] DEBUG -- : User Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.416046 #722:d27120] DEBUG -- :  (0.2ms) SELECT pg_backend_pid()
[----] D, [2017-03-14T08:10:36.453767 #694:d27120] DEBUG -- :  (0.2ms) SELECT pg_backend_pid()
[----] D, [2017-03-14T08:10:36.459624 #773:2115e84] DEBUG -- :  (0.2ms) BEGIN
[----] D, [2017-03-14T08:10:36.461241 #773:2115e84] DEBUG -- : SQL (0.6ms) INSERT INTO "audit_events" ("event", "status", "message", "severity", "userid", "source", "created_on") VALUES ($1, $2, $3, $4, $5, $6, $7) RETURNING "id" [["event", "authenticate_database"], ["status", "success"], ["message", "User admin successfully validated by EVM"], ["severity", "info"], ["userid", "admin"], ["source", "Authenticator.authenticate"], ["created_on", 2017-03-14 06:10:36 UTC]]
[----] D, [2017-03-14T08:10:36.480425 #682:d27120] DEBUG -- :  (0.4ms) SELECT pg_backend_pid()
[----] D, [2017-03-14T08:10:36.501316 #773:2115e84] DEBUG -- :  (39.8ms) COMMIT
[----] D, [2017-03-14T08:10:36.502627 #773:2115e84] DEBUG -- : User Load (0.5ms) SELECT "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4 [["id", 1000000000000], ["id", 1999999999999], ["userid", "admin"], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.502975 #773:2115e84] DEBUG -- : User Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.503728 #773:2115e84] DEBUG -- :  (0.2ms) BEGIN
[----] D, [2017-03-14T08:10:36.504991 #773:2115e84] DEBUG -- : SQL (0.4ms) INSERT INTO "audit_events" ("event", "status", "message", "severity", "userid", "source", "created_on") VALUES ($1, $2, $3, $4, $5, $6, $7) RETURNING "id" [["event", "authenticate_database"], ["status", "success"], ["message", "Authentication successful for user admin"], ["severity", "info"], ["userid", "admin"], ["source", "Authenticator.authenticate"], ["created_on", 2017-03-14 06:10:36 UTC]]
[----] D, [2017-03-14T08:10:36.509561 #773:2115e84] DEBUG -- :  (4.3ms) COMMIT
[----] D, [2017-03-14T08:10:36.510005 #773:2115e84] DEBUG -- :  (0.2ms) BEGIN
[----] D, [2017-03-14T08:10:36.511668 #773:2115e84] DEBUG -- :  (0.5ms) SELECT COUNT(*) FROM "users" WHERE LOWER("users"."userid") = 'admin' AND ("users"."id" BETWEEN $1 AND $2) AND ("users"."id" != $3) [["id", 1000000000000], ["id", 1999999999999], ["id", 1000000000001]]
[----] D, [2017-03-14T08:10:36.513078 #773:2115e84] DEBUG -- : MiqGroup Load (0.5ms) SELECT "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2 [["id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.513414 #773:2115e84] DEBUG -- : MiqGroup Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.515692 #773:2115e84] DEBUG -- : MiqGroup Exists (0.7ms) SELECT 1 AS one FROM "miq_groups" INNER JOIN "miq_groups_users" ON "miq_groups"."id" = "miq_groups_users"."miq_group_id" WHERE "miq_groups_users"."user_id" = $1 AND "miq_groups"."id" = $2 LIMIT $3 [["user_id", 1000000000001], ["id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.517852 #773:2115e84] DEBUG -- : SQL (0.6ms) UPDATE "users" SET "lastlogon" = $1, "updated_on" = $2 WHERE "users"."id" = $3 [["lastlogon", 2017-03-14 06:10:36 UTC], ["updated_on", 2017-03-14 06:10:36 UTC], ["id", 1000000000001]]
[----] D, [2017-03-14T08:10:36.519538 #704:d27120] DEBUG -- :  (0.3ms) SELECT pg_backend_pid()
[----] D, [2017-03-14T08:10:36.526343 #773:2115e84] DEBUG -- :  (8.0ms) COMMIT
[----] D, [2017-03-14T08:10:36.527782 #773:2115e84] DEBUG -- : User Load (0.6ms) SELECT "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4 [["id", 1000000000000], ["id", 1999999999999], ["userid", "admin"], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.528140 #773:2115e84] DEBUG -- : User Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.529365 #773:2115e84] DEBUG -- : MiqGroup Load (0.4ms) SELECT "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2 [["id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.529641 #773:2115e84] DEBUG -- : MiqGroup Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.530957 #773:2115e84] DEBUG -- : MiqUserRole Load (0.3ms) SELECT "miq_user_roles".* FROM "miq_user_roles" INNER JOIN "entitlements" ON "miq_user_roles"."id" = "entitlements"."miq_user_role_id" WHERE "entitlements"."miq_group_id" = $1 LIMIT $2 [["miq_group_id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.531194 #773:2115e84] DEBUG -- : MiqUserRole Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.532558 #773:2115e84] DEBUG -- : Tenant Load (0.3ms) SELECT "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2 [["id", 1000000000001], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.532863 #773:2115e84] DEBUG -- : Tenant Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.533966 #773:2115e84] DEBUG -- : Unpermitted parameter: expand
[----] D, [2017-03-14T08:10:36.537046 #773:2115e84] DEBUG -- : MiqProductFeature Load (0.9ms) SELECT "miq_product_features".* FROM "miq_product_features" INNER JOIN "miq_roles_features" ON "miq_product_features"."id" = "miq_roles_features"."miq_product_feature_id" WHERE "miq_roles_features"."miq_user_role_id" = $1 [["miq_user_role_id", 1000000000001]]
[----] D, [2017-03-14T08:10:36.537317 #773:2115e84] DEBUG -- : MiqProductFeature Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.539363 #773:2115e84] DEBUG -- : Entitlement Load (0.3ms) SELECT "entitlements".* FROM "entitlements" WHERE "entitlements"."miq_group_id" = $1 LIMIT $2 [["miq_group_id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.539566 #773:2115e84] DEBUG -- : Entitlement Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.540770 #773:2115e84] DEBUG -- :  (0.5ms) SELECT COUNT(*) FROM "miq_alert_statuses"
[----] D, [2017-03-14T08:10:36.541436 #773:2115e84] DEBUG -- : MiqAlertStatus Load (0.4ms) SELECT "miq_alert_statuses".* FROM "miq_alert_statuses"
[----] D, [2017-03-14T08:10:36.541693 #773:2115e84] DEBUG -- : MiqAlertStatus Inst Including Associations (0.1ms - 3rows)
[----] I, [2017-03-14T08:10:36.544567 #773:2115e84] INFO -- : Completed 200 OK in 156ms (Views: 0.1ms | ActiveRecord: 61.0ms)
[----] D, [2017-03-14T08:10:36.545192 #773:2115e84] DEBUG -- : PostgreSQLAdapter#log_after_checkin, connection_pool: size: 8, connections: 3, in use: 2, waiting_in_queue: 0
[----] I, [2017-03-14T08:10:36.558868 #773:2108040] INFO -- : Started GET "/api/alerts?expand=resources,alert_actions" for 127.0.0.1 at 2017-03-14 08:10:36 +0200
[----] I, [2017-03-14T08:10:36.589514 #773:2108040] INFO -- : Processing by Api::AlertsController#index as JSON
[----] I, [2017-03-14T08:10:36.589627 #773:2108040] INFO -- : Parameters: {"expand"=>"resources,alert_actions"}
[----] D, [2017-03-14T08:10:36.591156 #773:2108040] DEBUG -- : PostgreSQLAdapter#log_after_checkout, connection_pool: size: 8, connections: 3, in use: 3, waiting_in_queue: 0
[----] D, [2017-03-14T08:10:36.592075 #773:2108040] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4 [["id", 1000000000000], ["id", 1999999999999], ["userid", "admin"], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.592499 #773:2108040] DEBUG -- : User Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.659199 #773:2108040] DEBUG -- :  (0.3ms) BEGIN
[----] D, [2017-03-14T08:10:36.660778 #773:2108040] DEBUG -- : SQL (0.5ms) INSERT INTO "audit_events" ("event", "status", "message", "severity", "userid", "source", "created_on") VALUES ($1, $2, $3, $4, $5, $6, $7) RETURNING "id" [["event", "authenticate_database"], ["status", "success"], ["message", "User admin successfully validated by EVM"], ["severity", "info"], ["userid", "admin"], ["source", "Authenticator.authenticate"], ["created_on", 2017-03-14 06:10:36 UTC]]
[----] D, [2017-03-14T08:10:36.667963 #773:2108040] DEBUG -- :  (7.0ms) COMMIT
[----] D, [2017-03-14T08:10:36.669136 #773:2108040] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4 [["id", 1000000000000], ["id", 1999999999999], ["userid", "admin"], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.669468 #773:2108040] DEBUG -- : User Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.670143 #773:2108040] DEBUG -- :  (0.2ms) BEGIN
[----] D, [2017-03-14T08:10:36.671298 #773:2108040] DEBUG -- : SQL (0.3ms) INSERT INTO "audit_events" ("event", "status", "message", "severity", "userid", "source", "created_on") VALUES ($1, $2, $3, $4, $5, $6, $7) RETURNING "id" [["event", "authenticate_database"], ["status", "success"], ["message", "Authentication successful for user admin"], ["severity", "info"], ["userid", "admin"], ["source", "Authenticator.authenticate"], ["created_on", 2017-03-14 06:10:36 UTC]]
[----] D, [2017-03-14T08:10:36.676240 #773:2108040] DEBUG -- :  (4.8ms) COMMIT
[----] D, [2017-03-14T08:10:36.676641 #773:2108040] DEBUG -- :  (0.2ms) BEGIN
[----] D, [2017-03-14T08:10:36.678163 #773:2108040] DEBUG -- :  (0.3ms) SELECT COUNT(*) FROM "users" WHERE LOWER("users"."userid") = 'admin' AND ("users"."id" BETWEEN $1 AND $2) AND ("users"."id" != $3) [["id", 1000000000000], ["id", 1999999999999], ["id", 1000000000001]]
[----] D, [2017-03-14T08:10:36.679318 #773:2108040] DEBUG -- : MiqGroup Load (0.3ms) SELECT "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2 [["id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.679565 #773:2108040] DEBUG -- : MiqGroup Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.681427 #773:2108040] DEBUG -- : MiqGroup Exists (0.3ms) SELECT 1 AS one FROM "miq_groups" INNER JOIN "miq_groups_users" ON "miq_groups"."id" = "miq_groups_users"."miq_group_id" WHERE "miq_groups_users"."user_id" = $1 AND "miq_groups"."id" = $2 LIMIT $3 [["user_id", 1000000000001], ["id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.683209 #773:2108040] DEBUG -- : SQL (0.3ms) UPDATE "users" SET "lastlogon" = $1, "updated_on" = $2 WHERE "users"."id" = $3 [["lastlogon", 2017-03-14 06:10:36 UTC], ["updated_on", 2017-03-14 06:10:36 UTC], ["id", 1000000000001]]
[----] D, [2017-03-14T08:10:36.692902 #773:2108040] DEBUG -- :  (9.3ms) COMMIT
[----] D, [2017-03-14T08:10:36.693868 #773:2108040] DEBUG -- : User Load (0.3ms) SELECT "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4 [["id", 1000000000000], ["id", 1999999999999], ["userid", "admin"], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.694207 #773:2108040] DEBUG -- : User Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.695227 #773:2108040] DEBUG -- : MiqGroup Load (0.2ms) SELECT "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2 [["id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.695441 #773:2108040] DEBUG -- : MiqGroup Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.696520 #773:2108040] DEBUG -- : MiqUserRole Load (0.2ms) SELECT "miq_user_roles".* FROM "miq_user_roles" INNER JOIN "entitlements" ON "miq_user_roles"."id" = "entitlements"."miq_user_role_id" WHERE "entitlements"."miq_group_id" = $1 LIMIT $2 [["miq_group_id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.696726 #773:2108040] DEBUG -- : MiqUserRole Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.697894 #773:2108040] DEBUG -- : Tenant Load (0.2ms) SELECT "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2 [["id", 1000000000001], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.698211 #773:2108040] DEBUG -- : Tenant Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.699193 #773:2108040] DEBUG -- : Unpermitted parameter: expand
[----] D, [2017-03-14T08:10:36.701582 #773:2108040] DEBUG -- : MiqProductFeature Load (0.4ms) SELECT "miq_product_features".* FROM "miq_product_features" INNER JOIN "miq_roles_features" ON "miq_product_features"."id" = "miq_roles_features"."miq_product_feature_id" WHERE "miq_roles_features"."miq_user_role_id" = $1 [["miq_user_role_id", 1000000000001]]
[----] D, [2017-03-14T08:10:36.701826 #773:2108040] DEBUG -- : MiqProductFeature Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.703551 #773:2108040] DEBUG -- : Entitlement Load (0.2ms) SELECT "entitlements".* FROM "entitlements" WHERE "entitlements"."miq_group_id" = $1 LIMIT $2 [["miq_group_id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.703824 #773:2108040] DEBUG -- : Entitlement Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.704849 #773:2108040] DEBUG -- :  (0.3ms) SELECT COUNT(*) FROM "miq_alert_statuses"
[----] D, [2017-03-14T08:10:36.705291 #773:2108040] DEBUG -- : MiqAlertStatus Load (0.2ms) SELECT "miq_alert_statuses".* FROM "miq_alert_statuses"
[----] D, [2017-03-14T08:10:36.705581 #773:2108040] DEBUG -- : MiqAlertStatus Inst Including Associations (0.1ms - 3rows)
[----] D, [2017-03-14T08:10:36.707878 #773:2108040] DEBUG -- : MiqAlertStatusAction Load (0.3ms) SELECT "miq_alert_status_actions".* FROM "miq_alert_status_actions" WHERE "miq_alert_status_actions"."miq_alert_status_id" = $1 ORDER BY created_at [["miq_alert_status_id", 1000000000100]]
[----] D, [2017-03-14T08:10:36.708034 #773:2108040] DEBUG -- : MiqAlertStatusAction Inst Including Associations (0.0ms - 0rows)
[----] D, [2017-03-14T08:10:36.710182 #773:2108040] DEBUG -- : MiqAlertStatusAction Load (0.3ms) SELECT "miq_alert_status_actions".* FROM "miq_alert_status_actions" WHERE "miq_alert_status_actions"."miq_alert_status_id" = $1 ORDER BY created_at [["miq_alert_status_id", 1000000000102]]
[----] D, [2017-03-14T08:10:36.710454 #773:2108040] DEBUG -- : MiqAlertStatusAction Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.713006 #773:2108040] DEBUG -- : MiqAlertStatusAction Load (0.3ms) SELECT "miq_alert_status_actions".* FROM "miq_alert_status_actions" WHERE "miq_alert_status_actions"."miq_alert_status_id" = $1 ORDER BY created_at [["miq_alert_status_id", 1000000000101]]
[----] D, [2017-03-14T08:10:36.713279 #773:2108040] DEBUG -- : MiqAlertStatusAction Inst Including Associations (0.1ms - 2rows)
[----] I, [2017-03-14T08:10:36.715418 #773:2108040] INFO -- : Completed 200 OK in 126ms (Views: 0.1ms | ActiveRecord: 27.5ms)
[----] D, [2017-03-14T08:10:36.716007 #773:2108040] DEBUG -- : PostgreSQLAdapter#log_after_checkin, connection_pool: size: 8, connections: 3, in use: 2, waiting_in_queue: 0
[----] I, [2017-03-14T08:10:36.735782 #773:210af0c] INFO -- : Started GET "/api/alerts?attributes=resource&expand=resources" for 127.0.0.1 at 2017-03-14 08:10:36 +0200
[----] I, [2017-03-14T08:10:36.762834 #773:210af0c] INFO -- : Processing by Api::AlertsController#index as JSON
[----] I, [2017-03-14T08:10:36.762924 #773:210af0c] INFO -- : Parameters: {"attributes"=>"resource", "expand"=>"resources"}
[----] D, [2017-03-14T08:10:36.764267 #773:210af0c] DEBUG -- : PostgreSQLAdapter#log_after_checkout, connection_pool: size: 8, connections: 3, in use: 3, waiting_in_queue: 0
[----] D, [2017-03-14T08:10:36.765024 #773:210af0c] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4 [["id", 1000000000000], ["id", 1999999999999], ["userid", "admin"], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.765349 #773:210af0c] DEBUG -- : User Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.825002 #773:210af0c] DEBUG -- :  (0.2ms) BEGIN
[----] D, [2017-03-14T08:10:36.826532 #773:210af0c] DEBUG -- : SQL (0.5ms) INSERT INTO "audit_events" ("event", "status", "message", "severity", "userid", "source", "created_on") VALUES ($1, $2, $3, $4, $5, $6, $7) RETURNING "id" [["event", "authenticate_database"], ["status", "success"], ["message", "User admin successfully validated by EVM"], ["severity", "info"], ["userid", "admin"], ["source", "Authenticator.authenticate"], ["created_on", 2017-03-14 06:10:36 UTC]]
[----] D, [2017-03-14T08:10:36.834770 #773:210af0c] DEBUG -- :  (7.9ms) COMMIT
[----] D, [2017-03-14T08:10:36.836261 #773:210af0c] DEBUG -- : User Load (0.5ms) SELECT "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4 [["id", 1000000000000], ["id", 1999999999999], ["userid", "admin"], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.836605 #773:210af0c] DEBUG -- : User Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.837371 #773:210af0c] DEBUG -- :  (0.2ms) BEGIN
[----] D, [2017-03-14T08:10:36.838554 #773:210af0c] DEBUG -- : SQL (0.4ms) INSERT INTO "audit_events" ("event", "status", "message", "severity", "userid", "source", "created_on") VALUES ($1, $2, $3, $4, $5, $6, $7) RETURNING "id" [["event", "authenticate_database"], ["status", "success"], ["message", "Authentication successful for user admin"], ["severity", "info"], ["userid", "admin"], ["source", "Authenticator.authenticate"], ["created_on", 2017-03-14 06:10:36 UTC]]
[----] D, [2017-03-14T08:10:36.842963 #773:210af0c] DEBUG -- :  (4.2ms) COMMIT
[----] D, [2017-03-14T08:10:36.843371 #773:210af0c] DEBUG -- :  (0.1ms) BEGIN
[----] D, [2017-03-14T08:10:36.845134 #773:210af0c] DEBUG -- :  (0.4ms) SELECT COUNT(*) FROM "users" WHERE LOWER("users"."userid") = 'admin' AND ("users"."id" BETWEEN $1 AND $2) AND ("users"."id" != $3) [["id", 1000000000000], ["id", 1999999999999], ["id", 1000000000001]]
[----] D, [2017-03-14T08:10:36.846401 #773:210af0c] DEBUG -- : MiqGroup Load (0.3ms) SELECT "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2 [["id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.846719 #773:210af0c] DEBUG -- : MiqGroup Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.848763 #773:210af0c] DEBUG -- : MiqGroup Exists (0.4ms) SELECT 1 AS one FROM "miq_groups" INNER JOIN "miq_groups_users" ON "miq_groups"."id" = "miq_groups_users"."miq_group_id" WHERE "miq_groups_users"."user_id" = $1 AND "miq_groups"."id" = $2 LIMIT $3 [["user_id", 1000000000001], ["id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.850697 #773:210af0c] DEBUG -- : SQL (0.3ms) UPDATE "users" SET "lastlogon" = $1, "updated_on" = $2 WHERE "users"."id" = $3 [["lastlogon", 2017-03-14 06:10:36 UTC], ["updated_on", 2017-03-14 06:10:36 UTC], ["id", 1000000000001]]
[----] D, [2017-03-14T08:10:36.859592 #773:210af0c] DEBUG -- :  (8.4ms) COMMIT
[----] D, [2017-03-14T08:10:36.860637 #773:210af0c] DEBUG -- : User Load (0.4ms) SELECT "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4 [["id", 1000000000000], ["id", 1999999999999], ["userid", "admin"], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.860971 #773:210af0c] DEBUG -- : User Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.862027 #773:210af0c] DEBUG -- : MiqGroup Load (0.2ms) SELECT "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2 [["id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.862296 #773:210af0c] DEBUG -- : MiqGroup Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.863456 #773:210af0c] DEBUG -- : MiqUserRole Load (0.2ms) SELECT "miq_user_roles".* FROM "miq_user_roles" INNER JOIN "entitlements" ON "miq_user_roles"."id" = "entitlements"."miq_user_role_id" WHERE "entitlements"."miq_group_id" = $1 LIMIT $2 [["miq_group_id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.863675 #773:210af0c] DEBUG -- : MiqUserRole Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.864902 #773:210af0c] DEBUG -- : Tenant Load (0.2ms) SELECT "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2 [["id", 1000000000001], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.865251 #773:210af0c] DEBUG -- : Tenant Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.866326 #773:210af0c] DEBUG -- : Unpermitted parameters: attributes, expand
[----] D, [2017-03-14T08:10:36.868974 #773:210af0c] DEBUG -- : MiqProductFeature Load (0.5ms) SELECT "miq_product_features".* FROM "miq_product_features" INNER JOIN "miq_roles_features" ON "miq_product_features"."id" = "miq_roles_features"."miq_product_feature_id" WHERE "miq_roles_features"."miq_user_role_id" = $1 [["miq_user_role_id", 1000000000001]]
[----] D, [2017-03-14T08:10:36.869283 #773:210af0c] DEBUG -- : MiqProductFeature Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.871161 #773:210af0c] DEBUG -- : Entitlement Load (0.2ms) SELECT "entitlements".* FROM "entitlements" WHERE "entitlements"."miq_group_id" = $1 LIMIT $2 [["miq_group_id", 1000000000002], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.871384 #773:210af0c] DEBUG -- : Entitlement Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.872375 #773:210af0c] DEBUG -- :  (0.3ms) SELECT COUNT(*) FROM "miq_alert_statuses"
[----] D, [2017-03-14T08:10:36.872895 #773:210af0c] DEBUG -- : MiqAlertStatus Load (0.3ms) SELECT "miq_alert_statuses".* FROM "miq_alert_statuses"
[----] D, [2017-03-14T08:10:36.873184 #773:210af0c] DEBUG -- : MiqAlertStatus Inst Including Associations (0.1ms - 3rows)
[----] D, [2017-03-14T08:10:36.875184 #773:210af0c] DEBUG -- : ContainerNode Load (0.3ms) SELECT "container_nodes".* FROM "container_nodes" WHERE "container_nodes"."id" = $1 LIMIT $2 [["id", 1000000000001], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.875529 #773:210af0c] DEBUG -- : ContainerNode Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.877645 #773:210af0c] DEBUG -- : CACHE (0.0ms) SELECT "container_nodes".* FROM "container_nodes" WHERE "container_nodes"."id" = $1 LIMIT $2 [["id", 1000000000001], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.877799 #773:210af0c] DEBUG -- : ContainerNode Inst Including Associations (0.1ms - 1rows)
[----] D, [2017-03-14T08:10:36.879719 #773:210af0c] DEBUG -- : CACHE (0.0ms) SELECT "container_nodes".* FROM "container_nodes" WHERE "container_nodes"."id" = $1 LIMIT $2 [["id", 1000000000001], ["LIMIT", 1]]
[----] D, [2017-03-14T08:10:36.879863 #773:210af0c] DEBUG -- : ContainerNode Inst Including Associations (0.1ms - 1rows)
[----] I, [2017-03-14T08:10:36.882050 #773:210af0c] INFO -- : Completed 200 OK in 119ms (Views: 0.1ms | ActiveRecord: 27.0ms)
[----] D, [2017-03-14T08:10:36.882651 #773:210af0c] DEBUG -- : PostgreSQLAdapter#log_after_checkin, connection_pool: size: 8, connections: 3, in use: 2, waiting_in_queue: 0
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment