Skip to content

Instantly share code, notes, and snippets.

@d-m-u

d-m-u/not working.md

Last active September 4, 2019 11:17
Show Gist options
  • Save d-m-u/3487a0b9061bc121aa2460f9ce5b9481 to your computer and use it in GitHub Desktop.
Save d-m-u/3487a0b9061bc121aa2460f9ce5b9481 to your computer and use it in GitHub Desktop.
Download ZIP
rails 2.5.5 threading things
Raw
not working.md

2.5.5 rails console output from simulate_queue_worker

the first ~180 lines 

** Delivering Message id: [92],  id: [], Zone: [], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [18], Task id: [], Command: [ServiceTemplateProvisionRequest.call_automate_event], Timeout: [3600], Priority: [100], State: [ready], Deliver On: [], Data: [], Args: ["request_created"]

  ServiceTemplateProvisionRequest Load (1.8ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 18], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (44.6ms - 1rows)
   (0.3ms)  BEGIN
  SQL (1.9ms)  INSERT INTO "event_streams" ("event_type", "source", "created_on", "type", "target_type", "target_id") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id"  [["event_type", "request_created"], ["source", "Request"], ["created_on", "2019-09-03 22:47:23.037976"], ["type", "RequestEvent"], ["target_type", "MiqRequest"], ["target_id", 18]]
   (1.0ms)  COMMIT
   (1.3ms)  SELECT "notification_types"."name" FROM "notification_types"
  User Load (1.3ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" IS NULL LIMIT $3  [["id", 0], ["id", 999999999999], ["LIMIT", 1]]
  User Inst Including Associations (0.2ms - 0rows)
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (38.5ms - 1rows)
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (1.4ms)  SELECT "miq_groups".* 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  [["user_id", 1]]
  MiqGroup Inst Including Associations (22.1ms - 1rows)
  MiqGroup Load (0.3ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  MiqUserRole Load (1.0ms)  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", 2], ["LIMIT", 1]]
  MiqUserRole Inst Including Associations (12.0ms - 1rows)
   (1.1ms)  SELECT "miq_product_features"."identifier" 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", 1]]
  Tenant Load (0.8ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (28.6ms - 1rows)
   (0.8ms)  SELECT "server_roles"."name" FROM "server_roles" INNER JOIN "assigned_server_roles" ON "server_roles"."id" = "assigned_server_roles"."server_role_id" WHERE "assigned_server_roles"."miq_server_id" = $1 AND "assigned_server_roles"."active" = $2  [["miq_server_id", 1], ["active", "t"]]
   (0.1ms)  BEGIN
  SQL (0.5ms)  INSERT INTO "miq_queue" ("priority", "method_name", "state", "created_on", "updated_on", "queue_name", "class_name", "args", "role", "msg_timeout", "user_id", "group_id", "tenant_id", "lock_version") VALUES ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13, $14) RETURNING "id"  [["priority", 20], ["method_name", "deliver"], ["state", "ready"], ["created_on", "2019-09-03 22:47:24.159262"], ["updated_on", "2019-09-03 22:47:24.159262"], ["queue_name", "generic"], ["class_name", "MiqAeEngine"], ["args", "---\n- :object_type: ServiceTemplateProvisionRequest\n  :object_id: 18\n  :attrs:\n    :event_type: request_created\n    EventStream::event_stream: 44\n    :event_stream_id: 44\n  :instance_name: Event\n  :user_id: 1\n  :miq_group_id: 2\n  :tenant_id: 1\n  :automate_message: \n"], ["role", "automate"], ["msg_timeout", 3600], ["user_id", 1], ["group_id", 2], ["tenant_id", 1], ["lock_version", 0]]
   (1.5ms)  COMMIT
   (0.1ms)  BEGIN
  SQL (0.4ms)  DELETE FROM "miq_queue" WHERE "miq_queue"."id" = $1 AND "miq_queue"."lock_version" = $2  [["id", 92], ["lock_version", 0]]
   (0.3ms)  COMMIT

** Delivering Message id: [93],  id: [], Zone: [], Role: [automate], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqAeEngine.deliver], Timeout: [3600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: [{:object_type=>"ServiceTemplateProvisionRequest", :object_id=>18, :attrs=>{:event_type=>"request_created", "EventStream::event_stream"=>44, :event_stream_id=>44}, :instance_name=>"Event", :user_id=>1, :miq_group_id=>2, :tenant_id=>1, :automate_message=>nil}]

  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.2ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  ServiceTemplateProvisionRequest Load (0.4ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 18], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (0.1ms - 1rows)
   (0.1ms)  BEGIN
  Tenant Load (0.3ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  SQL (0.5ms)  UPDATE "miq_requests" SET "options" = $1, "updated_on" = $2 WHERE "miq_requests"."id" = $3  [["options", "---\n:dialog:\n  dialog_textarea_box_1: ''\n:workflow_settings:\n  :resource_action_id: 12\n  :dialog_id: 2\n:initiator: \n:src_id: 1\n:request_options:\n  :submit_workflow: true\n  :init_defaults: false\n:cart_state: ordered\n:requester_group: EvmGroup-super_administrator\n:executed_on_servers:\n- 1\n"], ["updated_on", "2019-09-03 22:47:24.177615"], ["id", 18]]
   (0.3ms)  COMMIT
  Tenant Load (0.4ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  MiqAeDomain Load (1.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" INNER JOIN "tenants" ON "tenants"."id" = "miq_ae_namespaces"."tenant_id" WHERE "miq_ae_namespaces"."parent_id" IS NULL AND ("miq_ae_namespaces"."name" != '$') AND "miq_ae_namespaces"."tenant_id" = 1 AND "miq_ae_namespaces"."enabled" = $1 ORDER BY tenants.ancestry DESC NULLS LAST, priority DESC  [["enabled", "t"]]
  MiqAeDomain Inst Including Associations (36.8ms - 1rows)
  MiqAeNamespace Load (0.9ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.2ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Exists (0.7ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'process') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Load (0.5ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'process') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (9.0ms - 1rows)
  MiqAeInstance Load (0.7ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'event') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (7.2ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Load (0.4ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 212
  MiqAeClass Inst Including Associations (0.2ms - 5rows)
  MiqAeMethod Load (0.9ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" IN (323, 324, 325, 326, 327)
  MiqAeMethod Inst Including Associations (22.0ms - 23rows)
  MiqAeField Load (1.1ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" IN (323, 324, 325, 326, 327) ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (29.2ms - 70rows)
  MiqAeInstance Load (1.0ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" IN (323, 324, 325, 326, 327)
  MiqAeInstance Inst Including Associations (1.0ms - 79rows)
  MiqAeValue Load (1.8ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (1944, 1956, 1973, 1977, 1978, 1983, 1992, 1945, 1946, 1947, 1948, 1949, 1950, 1951, 1952, 1953, 1954, 1955, 1957, 1958, 1959, 1960, 1961, 1962, 1963, 1964, 1965, 1966, 1967, 1968, 1969, 1970, 1971, 1972, 1974, 1975, 1976, 1979, 1980, 1981, 1982, 1984, 1985, 1986, 1987, 1988, 1989, 1990, 1991, 1993, 1994, 1995, 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005, 2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013, 2014, 2015, 2016, 2017, 2018, 2019, 2020, 2021, 2022)
  MiqAeValue Inst Including Associations (23.8ms - 104rows)
  MiqAeField Load (0.7ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" IN (3183, 3181, 3179, 3172, 3170, 3175, 3182, 3195, 3194, 3192, 3201, 3202, 3219, 3215, 3221, 3222, 3214, 3213, 3220, 3238)
  MiqAeField Inst Including Associations (0.6ms - 20rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = '$'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Load (0.4ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 228
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" = 360
  MiqAeMethod Inst Including Associations (0.2ms - 3rows)
  MiqAeField Load (0.3ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" = 360 ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeInstance Load (0.2ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" = 360
  MiqAeInstance Inst Including Associations (0.0ms - 0rows)
  EventStream Load (1.0ms)  SELECT  "event_streams".* FROM "event_streams" WHERE "event_streams"."id" = $1 LIMIT $2  [["id", 44], ["LIMIT", 1]]
  EventStream Inst Including Associations (195.8ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqRequest Load (0.4ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."id" = $1 LIMIT $2  [["id", 18], ["LIMIT", 1]]
  MiqRequest Inst Including Associations (17.4ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqServer Load (0.4ms)  SELECT  "miq_servers".* FROM "miq_servers" WHERE "miq_servers"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  MiqServer Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  ServiceTemplateProvisionRequest Load (0.4ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 18], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'requestevent'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 214
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'requestevent'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 214
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.3ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'request') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 219], ["LIMIT", 1]]
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'request') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 219], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.0ms - 1rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'request_created') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 349], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'requestevent'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 214
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Load (0.3ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 219
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" = 349
  MiqAeMethod Inst Including Associations (0.0ms - 0rows)
  MiqAeField Load (0.5ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" = 349 ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (0.4ms - 21rows)
  MiqAeInstance Load (0.3ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" = 349
  MiqAeInstance Inst Including Associations (0.2ms - 7rows)
  MiqAeValue Load (0.5ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (2540, 2539, 2538, 2537, 2536, 2535, 2534)
  MiqAeValue Inst Including Associations (0.2ms - 6rows)
  MiqAeField Load (0.3ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" = 3628
  MiqAeField Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.3ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'policy') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'policy') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.0ms - 1rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'request_created') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 324], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.0ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'get_request_type') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 324], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.3ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 760]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 324], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)

PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
   (0.1ms)  BEGIN
  SQL (0.3ms)  DELETE FROM "miq_queue" WHERE "miq_queue"."id" = $1 AND "miq_queue"."lock_version" = $2  [["id", 93], ["lock_version", 0]]
   (1.1ms)  COMMIT

and then nothing. it hangs, after blowing up in the automate log:

log/automation.log:[----] E, [2019-09-03T13:52:55.644782 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: The following error occurred during inline method preamble evaluation:
log/automation.log:[----] E, [2019-09-03T13:52:55.645139 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: ArgumentError: wrong number of arguments (given 1, expected 0)
log/automation.log:[----] E, [2019-09-03T13:52:55.645238 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: /Users/duhlmann/.rvm/gems/ruby-2.5.5/gems/activesupport-5.1.7/lib/active_support/values/time_zone.rb:203:in `initialize'
log/automation.log:[----] E, [2019-09-03T13:52:55.645280 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: /Users/duhlmann/.rvm/gems/ruby-2.5.5/gems/activesupport-5.1.7/lib/active_support/values/time_zone.rb:203:in `new'
log/automation.log:[----] E, [2019-09-03T13:52:55.645424 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: /Users/duhlmann/.rvm/gems/ruby-2.5.5/gems/activesupport-5.1.7/lib/active_support/values/time_zone.rb:203:in `find_tzinfo'
log/automation.log:[----] E, [2019-09-03T13:52:55.645560 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: /Users/duhlmann/.rvm/gems/ruby-2.5.5/gems/activesupport-5.1.7/lib/active_support/values/time_zone.rb:290:in `initialize'
log/automation.log:[----] E, [2019-09-03T13:52:55.645591 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: /Users/duhlmann/.rvm/gems/ruby-2.5.5/gems/activesupport-5.1.7/lib/active_support/values/time_zone.rb:231:in `new'
log/automation.log:[----] E, [2019-09-03T13:52:55.645729 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: /Users/duhlmann/.rvm/gems/ruby-2.5.5/gems/activesupport-5.1.7/lib/active_support/values/time_zone.rb:231:in `[]'
log/automation.log:[----] E, [2019-09-03T13:52:55.645817 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: /Users/duhlmann/.rvm/gems/ruby-2.5.5/gems/activesupport-5.1.7/lib/active_support/core_ext/time/zones.rb:87:in `find_zone!'
log/automation.log:[----] E, [2019-09-03T13:52:55.645849 #6775:3fd4945f2a8c] ERROR -- : Method STDERR: /Users/duhlmann/.rvm/gems/ruby-2.5.5/gems/activesupport-5.1.7/lib/active_support/core_ext/time/zones.rb:40:in `zone='
Raw
working.md

2.5.4 rails console output from simulate_queue_worker

the first ~180 lines are similar 

** Delivering Message id: [94],  id: [], Zone: [], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [19], Task id: [], Command: [ServiceTemplateProvisionRequest.call_automate_event], Timeout: [3600], Priority: [100], State: [ready], Deliver On: [], Data: [], Args: ["request_created"]

  ServiceTemplateProvisionRequest Load (1.3ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (51.5ms - 1rows)
   (0.2ms)  BEGIN
  SQL (2.2ms)  INSERT INTO "event_streams" ("event_type", "source", "created_on", "type", "target_type", "target_id") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id"  [["event_type", "request_created"], ["source", "Request"], ["created_on", "2019-09-03 22:48:56.268658"], ["type", "RequestEvent"], ["target_type", "MiqRequest"], ["target_id", 19]]
   (1.4ms)  COMMIT
   (0.5ms)  SELECT "notification_types"."name" FROM "notification_types"
  User Load (0.8ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" IS NULL LIMIT $3  [["id", 0], ["id", 999999999999], ["LIMIT", 1]]
  User Inst Including Associations (0.0ms - 0rows)
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (39.7ms - 1rows)
  User Load (0.5ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (1.0ms)  SELECT "miq_groups".* 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  [["user_id", 1]]
  MiqGroup Inst Including Associations (16.2ms - 1rows)
  MiqGroup Load (0.4ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  MiqUserRole Load (0.8ms)  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", 2], ["LIMIT", 1]]
  MiqUserRole Inst Including Associations (8.3ms - 1rows)
   (1.3ms)  SELECT "miq_product_features"."identifier" 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", 1]]
  Tenant Load (1.0ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (18.2ms - 1rows)
   (0.9ms)  SELECT "server_roles"."name" FROM "server_roles" INNER JOIN "assigned_server_roles" ON "server_roles"."id" = "assigned_server_roles"."server_role_id" WHERE "assigned_server_roles"."miq_server_id" = $1 AND "assigned_server_roles"."active" = $2  [["miq_server_id", 1], ["active", "t"]]
   (0.1ms)  BEGIN
  SQL (0.6ms)  INSERT INTO "miq_queue" ("priority", "method_name", "state", "created_on", "updated_on", "queue_name", "class_name", "args", "role", "msg_timeout", "user_id", "group_id", "tenant_id", "lock_version") VALUES ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13, $14) RETURNING "id"  [["priority", 20], ["method_name", "deliver"], ["state", "ready"], ["created_on", "2019-09-03 22:48:57.234989"], ["updated_on", "2019-09-03 22:48:57.234989"], ["queue_name", "generic"], ["class_name", "MiqAeEngine"], ["args", "---\n- :object_type: ServiceTemplateProvisionRequest\n  :object_id: 19\n  :attrs:\n    :event_type: request_created\n    EventStream::event_stream: 45\n    :event_stream_id: 45\n  :instance_name: Event\n  :user_id: 1\n  :miq_group_id: 2\n  :tenant_id: 1\n  :automate_message: \n"], ["role", "automate"], ["msg_timeout", 3600], ["user_id", 1], ["group_id", 2], ["tenant_id", 1], ["lock_version", 0]]
   (1.3ms)  COMMIT
   (0.2ms)  BEGIN
  SQL (0.5ms)  DELETE FROM "miq_queue" WHERE "miq_queue"."id" = $1 AND "miq_queue"."lock_version" = $2  [["id", 94], ["lock_version", 0]]
   (0.2ms)  COMMIT

** Delivering Message id: [95],  id: [], Zone: [], Role: [automate], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqAeEngine.deliver], Timeout: [3600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: [{:object_type=>"ServiceTemplateProvisionRequest", :object_id=>19, :attrs=>{:event_type=>"request_created", "EventStream::event_stream"=>45, :event_stream_id=>45}, :instance_name=>"Event", :user_id=>1, :miq_group_id=>2, :tenant_id=>1, :automate_message=>nil}]

  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.3ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  ServiceTemplateProvisionRequest Load (0.5ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (0.1ms - 1rows)
   (0.1ms)  BEGIN
  Tenant Load (0.4ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  SQL (0.5ms)  UPDATE "miq_requests" SET "options" = $1, "updated_on" = $2 WHERE "miq_requests"."id" = $3  [["options", "---\n:dialog:\n  dialog_textarea_box_1: ''\n:workflow_settings:\n  :resource_action_id: 12\n  :dialog_id: 2\n:initiator: \n:src_id: 1\n:request_options:\n  :submit_workflow: true\n  :init_defaults: false\n:cart_state: ordered\n:requester_group: EvmGroup-super_administrator\n:executed_on_servers:\n- 1\n"], ["updated_on", "2019-09-03 22:48:57.255819"], ["id", 19]]
   (0.3ms)  COMMIT
  Tenant Load (0.5ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.2ms - 1rows)
  MiqAeDomain Load (1.8ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" INNER JOIN "tenants" ON "tenants"."id" = "miq_ae_namespaces"."tenant_id" WHERE "miq_ae_namespaces"."parent_id" IS NULL AND ("miq_ae_namespaces"."name" != '$') AND "miq_ae_namespaces"."tenant_id" = 1 AND "miq_ae_namespaces"."enabled" = $1 ORDER BY tenants.ancestry DESC NULLS LAST, priority DESC  [["enabled", "t"]]
  MiqAeDomain Inst Including Associations (45.4ms - 1rows)
  MiqAeNamespace Load (0.7ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Exists (0.9ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'process') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Load (0.5ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'process') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (10.0ms - 1rows)
  MiqAeInstance Load (0.8ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'event') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (9.4ms - 1rows)
  MiqAeNamespace Load (0.6ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Load (0.5ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 212
  MiqAeClass Inst Including Associations (0.2ms - 5rows)
  MiqAeMethod Load (1.2ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" IN (323, 324, 325, 326, 327)
  MiqAeMethod Inst Including Associations (28.3ms - 23rows)
  MiqAeField Load (1.5ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" IN (323, 324, 325, 326, 327) ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (36.9ms - 70rows)
  MiqAeInstance Load (1.2ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" IN (323, 324, 325, 326, 327)
  MiqAeInstance Inst Including Associations (1.1ms - 79rows)
  MiqAeValue Load (2.4ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (1944, 1956, 1973, 1977, 1978, 1983, 1992, 1945, 1946, 1947, 1948, 1949, 1950, 1951, 1952, 1953, 1954, 1955, 1957, 1958, 1959, 1960, 1961, 1962, 1963, 1964, 1965, 1966, 1967, 1968, 1969, 1970, 1971, 1972, 1974, 1975, 1976, 1979, 1980, 1981, 1982, 1984, 1985, 1986, 1987, 1988, 1989, 1990, 1991, 1993, 1994, 1995, 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005, 2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013, 2014, 2015, 2016, 2017, 2018, 2019, 2020, 2021, 2022)
  MiqAeValue Inst Including Associations (30.7ms - 104rows)
  MiqAeField Load (0.9ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" IN (3183, 3181, 3179, 3172, 3170, 3175, 3182, 3195, 3194, 3192, 3201, 3202, 3219, 3215, 3221, 3222, 3214, 3213, 3220, 3238)
  MiqAeField Inst Including Associations (0.6ms - 20rows)
  MiqAeNamespace Load (0.6ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = '$'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Load (0.4ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 228
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.4ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" = 360
  MiqAeMethod Inst Including Associations (0.2ms - 3rows)
  MiqAeField Load (0.5ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" = 360 ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeInstance Load (0.4ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" = 360
  MiqAeInstance Inst Including Associations (0.0ms - 0rows)
  EventStream Load (1.2ms)  SELECT  "event_streams".* FROM "event_streams" WHERE "event_streams"."id" = $1 LIMIT $2  [["id", 45], ["LIMIT", 1]]
  EventStream Inst Including Associations (185.7ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqRequest Load (0.4ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  MiqRequest Inst Including Associations (16.5ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqServer Load (0.4ms)  SELECT  "miq_servers".* FROM "miq_servers" WHERE "miq_servers"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  MiqServer Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  ServiceTemplateProvisionRequest Load (0.5ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'requestevent'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 214
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'requestevent'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 214
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.4ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'request') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 219], ["LIMIT", 1]]
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'request') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 219], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.0ms - 1rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'request_created') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 349], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'requestevent'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 214
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Load (0.4ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 219
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" = 349
  MiqAeMethod Inst Including Associations (0.0ms - 0rows)
  MiqAeField Load (0.6ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" = 349 ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (0.4ms - 21rows)
  MiqAeInstance Load (0.4ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" = 349
  MiqAeInstance Inst Including Associations (0.1ms - 7rows)
  MiqAeValue Load (0.5ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (2540, 2539, 2538, 2537, 2536, 2535, 2534)
  MiqAeValue Inst Including Associations (0.2ms - 6rows)
  MiqAeField Load (0.4ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" = 3628
  MiqAeField Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.4ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'policy') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Load (0.4ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'policy') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeInstance Load (0.4ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'request_created') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 324], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'get_request_type') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 324], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.3ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 760]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 324], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)

PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeInstance Load (1.2ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'parse_provider_category') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'parse_provider_category') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.4ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 766]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 325], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  ServiceTemplate Load (0.9ms)  SELECT  "service_templates".* FROM "service_templates" WHERE "service_templates"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  ServiceTemplate Inst Including Associations (24.2ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeInstance Load (0.5ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'servicetemplateprovisionrequest_created') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 324], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
and the rest ... 

PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'provisioning'
  MiqAeNamespace Inst Including Associations (0.1ms - 7rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" IN (158, 162, 170, 185, 190, 202, 205)
  MiqAeNamespace Inst Including Associations (0.2ms - 7rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 201], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'provisioning'
  MiqAeNamespace Inst Including Associations (0.1ms - 7rows)
  MiqAeNamespace Load (1.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" IN (158, 162, 170, 185, 190, 202, 205)
  MiqAeNamespace Inst Including Associations (0.2ms - 7rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 201], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.4ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'profile') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 208], ["LIMIT", 1]]
  MiqAeClass Load (0.4ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'profile') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 208], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'evmgroup-super_administrator') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 314], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.0ms - 0rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE '.missing') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 314], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'provisioning'
  MiqAeNamespace Inst Including Associations (0.1ms - 7rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" IN (158, 162, 170, 185, 190, 202, 205)
  MiqAeNamespace Inst Including Associations (0.2ms - 7rows)
  MiqAeClass Load (0.5ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" IN (159, 164, 172, 186, 194, 203, 208)
  MiqAeClass Inst Including Associations (0.4ms - 16rows)
  MiqAeMethod Load (1.2ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" IN (244, 248, 260, 261, 262, 263, 278, 279, 292, 293, 294, 295, 308, 313, 314, 315)
  MiqAeMethod Inst Including Associations (1.1ms - 33rows)
  MiqAeField Load (0.9ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" IN (244, 248, 260, 261, 262, 263, 278, 279, 292, 293, 294, 295, 308, 313, 314, 315) ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (1.9ms - 90rows)
  MiqAeInstance Load (1.1ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" IN (244, 248, 260, 261, 262, 263, 278, 279, 292, 293, 294, 295, 308, 313, 314, 315)
  MiqAeInstance Inst Including Associations (0.7ms - 35rows)
  MiqAeValue Load (1.0ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (1791, 1792, 1827, 1828, 1829, 1830, 1859, 1860, 1901, 1902, 1749, 1750, 1761, 1762, 1785, 1786, 1787, 1788, 1789, 1790, 1825, 1826, 1853, 1854, 1855, 1856, 1857, 1858, 1915, 1916, 1917, 1918, 1919, 1920, 1921)
  MiqAeValue Inst Including Associations (0.6ms - 30rows)
  MiqAeField Load (0.7ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" IN (2495, 2547, 2644, 2648, 2660, 2659, 2658, 2657, 2805, 2905, 2909, 2920, 2919, 2918, 3124, 3127, 3128)
  MiqAeField Inst Including Associations (0.3ms - 17rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 201], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'statemachines'
  MiqAeNamespace Inst Including Associations (0.3ms - 19rows)
  MiqAeNamespace Load (0.6ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" IN (156, 159, 164, 166, 168, 172, 174, 186, 191, 194, 197, 199, 203, 206, 208, 210, 214, 222, 226)
  MiqAeNamespace Inst Including Associations (0.4ms - 19rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 158], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 162], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 162], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 162], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 170], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 170], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 185], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.7ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 202], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.7ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 201], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.6ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.6ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 205], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.6ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.6ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 205], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.7ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 205], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 222], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'statemachines'
  MiqAeNamespace Inst Including Associations (0.2ms - 19rows)
  MiqAeNamespace Load (0.6ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" IN (156, 159, 164, 166, 168, 172, 174, 186, 191, 194, 197, 199, 203, 206, 208, 210, 214, 222, 226)
  MiqAeNamespace Inst Including Associations (0.5ms - 19rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 158], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 162], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 162], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 162], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 170], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 170], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 185], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 202], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 201], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 205], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 205], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.3ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'serviceprovisionrequestapproval') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 209], ["LIMIT", 1]]
  MiqAeClass Load (0.4ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'serviceprovisionrequestapproval') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 209], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'default') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 317], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'statemachines'
  MiqAeNamespace Inst Including Associations (0.3ms - 19rows)
  MiqAeNamespace Load (0.6ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" IN (156, 159, 164, 166, 168, 172, 174, 186, 191, 194, 197, 199, 203, 206, 208, 210, 214, 222, 226)
  MiqAeNamespace Inst Including Associations (0.3ms - 19rows)
  MiqAeClass Load (0.7ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" IN (157, 160, 165, 167, 169, 173, 175, 187, 192, 195, 198, 200, 204, 207, 209, 211, 220, 225, 227)
  MiqAeClass Inst Including Associations (0.7ms - 41rows)
  MiqAeMethod Load (2.3ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" IN (243, 245, 249, 250, 252, 253, 255, 256, 264, 265, 266, 268, 269, 270, 280, 281, 282, 288, 289, 296, 297, 298, 299, 302, 303, 304, 306, 309, 310, 312, 316, 317, 318, 320, 321, 322, 350, 355, 356, 357, 359)
  MiqAeMethod Inst Including Associations (4.8ms - 146rows)
  MiqAeField Load (2.6ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" IN (243, 245, 249, 250, 252, 253, 255, 256, 264, 265, 266, 268, 269, 270, 280, 281, 282, 288, 289, 296, 297, 298, 299, 302, 303, 304, 306, 309, 310, 312, 316, 317, 318, 320, 321, 322, 350, 355, 356, 357, 359) ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (7.3ms - 357rows)
  MiqAeInstance Load (1.5ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" IN (243, 245, 249, 250, 252, 253, 255, 256, 264, 265, 266, 268, 269, 270, 280, 281, 282, 288, 289, 296, 297, 298, 299, 302, 303, 304, 306, 309, 310, 312, 316, 317, 318, 320, 321, 322, 350, 355, 356, 357, 359)
  MiqAeInstance Inst Including Associations (1.7ms - 120rows)
  MiqAeValue Load (2.8ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (1748, 1775, 1782, 1812, 1834, 1835, 1851, 1869, 1887, 1899, 1904, 1905, 1929, 1933, 1941, 1943, 1751, 1752, 1763, 1764, 1765, 1766, 1767, 1768, 1771, 1772, 1773, 1774, 1777, 1778, 1779, 1780, 1781, 1793, 1794, 1795, 1796, 1797, 1798, 1799, 1800, 1801, 1804, 1805, 1806, 1807, 1808, 1809, 1810, 1811, 1813, 1831, 1832, 1833, 1845, 1846, 1847, 1848, 1849, 1850, 1861, 1862, 1863, 1864, 1865, 1866, 1867, 1868, 1870, 1871, 1876, 1877, 1878, 1879, 1880, 1881, 1882, 1883, 1884, 1885, 1886, 1888, 1889, 1903, 1906, 1907, 1909, 1910, 1911, 1912, 1913, 1914, 1922, 1923, 1924, 1925, 1926, 1927, 1928, 1930, 1931, 1932, 1934, 1935, 1936, 1937, 1938, 1939, 1940, 1942, 2541, 2604, 2605, 2606, 2607, 2608, 2609, 2611, 2612, 2613)
  MiqAeValue Inst Including Associations (3.3ms - 215rows)
  MiqAeField Load (1.8ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" IN (2498, 2497, 2496, 2548, 2551, 2550, 2549, 2569, 2590, 2672, 2676, 2674, 2680, 2678, 2682, 2701, 2698, 2695, 2697, 2696, 2718, 2720, 2715, 2713, 2711, 2740, 2738, 2735, 2811, 2879, 2933, 2937, 2935, 2941, 2971, 2965, 2967, 2966, 2984, 2992, 2987, 2985, 3012, 3010, 3007, 3079, 3087, 3086, 3085, 3084, 3083, 3118, 3117, 3111, 3110, 3109, 3108, 3107, 3105, 3100, 3114, 3113, 3103, 3102, 3129, 3135, 3134, 3133, 3155, 3164, 3162, 3161, 3160, 3159, 3158, 3157, 3642, 3640, 3639, 3668, 3665, 3670, 3691, 3688, 3685, 3682, 3679, 3676, 3674, 3673, 3699, 3698)
  MiqAeField Inst Including Associations (1.8ms - 92rows)
  MiqAeNamespace Load (0.5ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 158], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 162], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 162], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 162], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 170], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 170], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 185], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.8ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.6ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 190], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 202], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 201], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 205], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 205], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'validate_request') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 317], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.4ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 747]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 317], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 209], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 208], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 205], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeMethod Load (0.4ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'approve_request') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 317], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.4ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 745]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 317], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 209], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 208], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 205], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqApproval Load (0.7ms)  SELECT "miq_approvals".* FROM "miq_approvals" WHERE "miq_approvals"."miq_request_id" = $1  [["miq_request_id", 19]]
  MiqApproval Inst Including Associations (69.9ms - 1rows)
  User Load (0.5ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqProductFeature Load (8.0ms)  SELECT "miq_product_features"."id", "miq_product_features"."identifier", "miq_product_features"."name", "miq_product_features"."description", "miq_product_features"."feature_type", "miq_product_features"."hidden", "miq_product_features"."protected", "miq_product_features"."tenant_id", (SELECT "miq_product_features_sub"."identifier" FROM "miq_product_features" "miq_product_features_sub" WHERE "miq_product_features_sub"."id" = "miq_product_features"."parent_id") AS "parent_identifier" FROM "miq_product_features"
  MiqProductFeature Inst Including Associations (20.6ms - 1429rows)
  Tenant Load (0.6ms)  SELECT "tenants".* FROM "tenants" WHERE "tenants"."id" = 1
  Tenant Inst Including Associations (2.1ms - 1rows)
  MiqGroup Load (0.4ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  MiqUserRole Load (0.4ms)  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", 2], ["LIMIT", 1]]
  MiqUserRole Inst Including Associations (0.1ms - 1rows)
   (0.8ms)  SELECT "miq_product_features"."identifier" 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", 1]]
   (0.1ms)  BEGIN
  SQL (0.5ms)  UPDATE "miq_approvals" SET "state" = $1, "reason" = $2, "stamper_id" = $3, "stamper_name" = $4, "stamped_on" = $5, "updated_on" = $6 WHERE "miq_approvals"."id" = $7  [["state", "approved"], ["reason", "Auto-Approved"], ["stamper_id", 1], ["stamper_name", "Administrator"], ["stamped_on", "2019-09-03 22:48:59.419991"], ["updated_on", "2019-09-03 22:48:59.421674"], ["id", 19]]
   (1.1ms)  COMMIT
   (0.1ms)  BEGIN
  Tenant Load (0.4ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  SQL (0.4ms)  UPDATE "miq_requests" SET "approval_state" = $1, "updated_on" = $2 WHERE "miq_requests"."id" = $3  [["approval_state", "approved"], ["updated_on", "2019-09-03 22:48:59.427748"], ["id", 19]]
   (0.3ms)  COMMIT
   (0.3ms)  BEGIN
  SQL (0.6ms)  INSERT INTO "miq_queue" ("priority", "method_name", "state", "created_on", "updated_on", "queue_name", "class_name", "instance_id", "args", "msg_timeout", "lock_version") VALUES ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11) RETURNING "id"  [["priority", 100], ["method_name", "call_automate_event"], ["state", "ready"], ["created_on", "2019-09-03 22:48:59.433191"], ["updated_on", "2019-09-03 22:48:59.433191"], ["queue_name", "generic"], ["class_name", "ServiceTemplateProvisionRequest"], ["instance_id", 19], ["args", "---\n- request_approved\n"], ["msg_timeout", 3600], ["lock_version", 0]]
   (0.3ms)  COMMIT
   (0.1ms)  BEGIN
  SQL (0.4ms)  INSERT INTO "miq_queue" ("priority", "method_name", "state", "created_on", "updated_on", "queue_name", "class_name", "instance_id", "role", "msg_timeout", "tracking_label", "lock_version") VALUES ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12) RETURNING "id"  [["priority", 100], ["method_name", "create_request_tasks"], ["state", "ready"], ["created_on", "2019-09-03 22:48:59.437153"], ["updated_on", "2019-09-03 22:48:59.437153"], ["queue_name", "generic"], ["class_name", "ServiceTemplateProvisionRequest"], ["instance_id", 19], ["role", "automate"], ["msg_timeout", 3600], ["tracking_label", "r19_service_template_provision_request_19"], ["lock_version", 0]]
   (0.5ms)  COMMIT
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
   (0.1ms)  BEGIN
  SQL (0.4ms)  DELETE FROM "miq_queue" WHERE "miq_queue"."id" = $1 AND "miq_queue"."lock_version" = $2  [["id", 95], ["lock_version", 0]]
   (0.4ms)  COMMIT

** Delivering Message id: [96],  id: [], Zone: [], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [19], Task id: [], Command: [ServiceTemplateProvisionRequest.call_automate_event], Timeout: [3600], Priority: [100], State: [ready], Deliver On: [], Data: [], Args: ["request_approved"]

  ServiceTemplateProvisionRequest Load (0.7ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (0.2ms - 1rows)
   (0.1ms)  BEGIN
  SQL (0.5ms)  INSERT INTO "event_streams" ("event_type", "source", "created_on", "type", "target_type", "target_id") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id"  [["event_type", "request_approved"], ["source", "Request"], ["created_on", "2019-09-03 22:48:59.453456"], ["type", "RequestEvent"], ["target_type", "MiqRequest"], ["target_id", 19]]
   (0.2ms)  COMMIT
  NotificationType Load (0.3ms)  SELECT  "notification_types".* FROM "notification_types" WHERE "notification_types"."name" = $1 LIMIT $2  [["name", "request_approved"], ["LIMIT", 1]]
  NotificationType Inst Including Associations (20.8ms - 1rows)
   (0.2ms)  BEGIN
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.3ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
   (0.4ms)  SELECT "users"."id" FROM "users" INNER JOIN "miq_groups_users" ON "users"."id" = "miq_groups_users"."user_id" WHERE "miq_groups_users"."miq_group_id" = $1  [["miq_group_id", 2]]
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.3ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  Entitlement Load (0.2ms)  SELECT  "entitlements".* FROM "entitlements" WHERE "entitlements"."miq_group_id" = $1 LIMIT $2  [["miq_group_id", 2], ["LIMIT", 1]]
  Entitlement Inst Including Associations (24.7ms - 1rows)
  Tenant Load (0.5ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  MiqUserRole Load (0.4ms)  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", 2], ["LIMIT", 1]]
  MiqUserRole Inst Including Associations (0.1ms - 1rows)
  MiqRequest Load (0.5ms)  SELECT "miq_requests".* FROM "miq_requests" WHERE (miq_requests.id IN (19))
  MiqRequest Inst Including Associations (0.1ms - 1rows)
  SQL (0.8ms)  INSERT INTO "notifications" ("notification_type_id", "subject_type", "subject_id", "created_at", "updated_at", "options") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id"  [["notification_type_id", 7], ["subject_type", "MiqRequest"], ["subject_id", 19], ["created_at", "2019-09-03 22:48:59.506281"], ["updated_at", "2019-09-03 22:48:59.506281"], ["options", "---\n:subject: Provisioning Service [drew] from [drew]\n"]]
  SQL (0.6ms)  INSERT INTO "notification_recipients" ("notification_id", "user_id", "seen") VALUES ($1, $2, $3) RETURNING "id"  [["notification_id", 17], ["user_id", 1], ["seen", "f"]]
   (0.6ms)  COMMIT
   (0.6ms)  SELECT "notification_recipients"."id", "notification_recipients"."user_id" FROM "notification_recipients" WHERE "notification_recipients"."notification_id" = $1  [["notification_id", 17]]
[ActionCable] Broadcasting to notifications_1: {:level=>"success", :created_at=>Tue, 03 Sep 2019 22:48:59 UTC +00:00, :text=>"Request %{subject} has been approved.", :bindings=>{:subject=>{:text=>"Provisioning Service [drew] from [drew]"}}, :id=>"17"}
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" IS NULL LIMIT $3  [["id", 0], ["id", 999999999999], ["LIMIT", 1]]
  User Inst Including Associations (0.0ms - 0rows)
  User Load (0.5ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  User Load (0.4ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.4ms)  SELECT "miq_groups".* 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  [["user_id", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.3ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  MiqUserRole Load (0.4ms)  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", 2], ["LIMIT", 1]]
  MiqUserRole Inst Including Associations (0.1ms - 1rows)
   (0.6ms)  SELECT "miq_product_features"."identifier" 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", 1]]
  Tenant Load (0.3ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
   (0.5ms)  SELECT "server_roles"."name" FROM "server_roles" INNER JOIN "assigned_server_roles" ON "server_roles"."id" = "assigned_server_roles"."server_role_id" WHERE "assigned_server_roles"."miq_server_id" = $1 AND "assigned_server_roles"."active" = $2  [["miq_server_id", 1], ["active", "t"]]
   (0.1ms)  BEGIN
  SQL (0.5ms)  INSERT INTO "miq_queue" ("priority", "method_name", "state", "created_on", "updated_on", "queue_name", "class_name", "args", "role", "msg_timeout", "user_id", "group_id", "tenant_id", "lock_version") VALUES ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13, $14) RETURNING "id"  [["priority", 20], ["method_name", "deliver"], ["state", "ready"], ["created_on", "2019-09-03 22:48:59.634800"], ["updated_on", "2019-09-03 22:48:59.634800"], ["queue_name", "generic"], ["class_name", "MiqAeEngine"], ["args", "---\n- :object_type: ServiceTemplateProvisionRequest\n  :object_id: 19\n  :attrs:\n    :event_type: request_approved\n    EventStream::event_stream: 46\n    :event_stream_id: 46\n  :instance_name: Event\n  :user_id: 1\n  :miq_group_id: 2\n  :tenant_id: 1\n  :automate_message: \n"], ["role", "automate"], ["msg_timeout", 3600], ["user_id", 1], ["group_id", 2], ["tenant_id", 1], ["lock_version", 0]]
   (0.4ms)  COMMIT
   (0.1ms)  BEGIN
  SQL (0.4ms)  DELETE FROM "miq_queue" WHERE "miq_queue"."id" = $1 AND "miq_queue"."lock_version" = $2  [["id", 96], ["lock_version", 0]]
   (0.3ms)  COMMIT

** Delivering Message id: [97],  id: [], Zone: [], Role: [automate], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [19], Task id: [], Command: [ServiceTemplateProvisionRequest.create_request_tasks], Timeout: [3600], Priority: [100], State: [ready], Deliver On: [], Data: [], Args: []

  ServiceTemplateProvisionRequest Load (0.6ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (0.1ms - 1rows)
   (0.1ms)  BEGIN
  SQL (0.6ms)  INSERT INTO "event_streams" ("event_type", "source", "created_on", "type", "target_type", "target_id") VALUES ($1, $2, $3, $4, $5, $6) RETURNING "id"  [["event_type", "request_starting"], ["source", "Request"], ["created_on", "2019-09-03 22:48:59.644277"], ["type", "RequestEvent"], ["target_type", "MiqRequest"], ["target_id", 19]]
   (0.3ms)  COMMIT
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" IS NULL LIMIT $3  [["id", 0], ["id", 999999999999], ["LIMIT", 1]]
  User Inst Including Associations (0.0ms - 0rows)
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.5ms)  SELECT "miq_groups".* 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  [["user_id", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.2ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.0ms - 1rows)
  MiqUserRole Load (0.4ms)  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", 2], ["LIMIT", 1]]
  MiqUserRole Inst Including Associations (0.1ms - 1rows)
   (1.1ms)  SELECT "miq_product_features"."identifier" 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", 1]]
  Tenant Load (0.2ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.2ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  ServiceTemplateProvisionRequest Load (0.4ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (0.1ms - 1rows)
   (0.1ms)  BEGIN
  Tenant Load (0.2ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
   (0.1ms)  COMMIT
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" IS NULL LIMIT $3  [["id", 0], ["id", 999999999999], ["LIMIT", 1]]
  User Inst Including Associations (0.0ms - 0rows)
  Tenant Load (0.2ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  MiqAeDomain Load (0.8ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" INNER JOIN "tenants" ON "tenants"."id" = "miq_ae_namespaces"."tenant_id" WHERE "miq_ae_namespaces"."parent_id" IS NULL AND ("miq_ae_namespaces"."name" != '$') AND "miq_ae_namespaces"."tenant_id" = 1 AND "miq_ae_namespaces"."enabled" = $1 ORDER BY tenants.ancestry DESC NULLS LAST, priority DESC  [["enabled", "t"]]
  MiqAeDomain Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Exists (0.4ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'process') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'process') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeInstance Load (0.4ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'event') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Load (0.4ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 212
  MiqAeClass Inst Including Associations (0.2ms - 5rows)
  MiqAeMethod Load (0.5ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" IN (323, 324, 325, 326, 327)
  MiqAeMethod Inst Including Associations (0.9ms - 23rows)
  MiqAeField Load (0.8ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" IN (323, 324, 325, 326, 327) ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (1.6ms - 70rows)
  MiqAeInstance Load (0.8ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" IN (323, 324, 325, 326, 327)
  MiqAeInstance Inst Including Associations (2.4ms - 79rows)
  MiqAeValue Load (1.6ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (1944, 1956, 1973, 1977, 1978, 1983, 1992, 1945, 1946, 1947, 1948, 1949, 1950, 1951, 1952, 1953, 1954, 1955, 1957, 1958, 1959, 1960, 1961, 1962, 1963, 1964, 1965, 1966, 1967, 1968, 1969, 1970, 1971, 1972, 1974, 1975, 1976, 1979, 1980, 1981, 1982, 1984, 1985, 1986, 1987, 1988, 1989, 1990, 1991, 1993, 1994, 1995, 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005, 2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013, 2014, 2015, 2016, 2017, 2018, 2019, 2020, 2021, 2022)
  MiqAeValue Inst Including Associations (2.0ms - 104rows)
  MiqAeField Load (0.7ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" IN (3183, 3181, 3179, 3172, 3170, 3175, 3182, 3195, 3194, 3192, 3201, 3202, 3219, 3215, 3221, 3222, 3214, 3213, 3220, 3238)
  MiqAeField Inst Including Associations (0.6ms - 20rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = '$'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Load (0.4ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 228
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.4ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" = 360
  MiqAeMethod Inst Including Associations (0.2ms - 3rows)
  MiqAeField Load (0.5ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" = 360 ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeInstance Load (0.3ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" = 360
  MiqAeInstance Inst Including Associations (0.0ms - 0rows)
  EventStream Load (0.6ms)  SELECT  "event_streams".* FROM "event_streams" WHERE "event_streams"."id" = $1 LIMIT $2  [["id", 47], ["LIMIT", 1]]
  EventStream Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqRequest Load (0.3ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  MiqRequest Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqServer Load (0.4ms)  SELECT  "miq_servers".* FROM "miq_servers" WHERE "miq_servers"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  MiqServer Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  ServiceTemplateProvisionRequest Load (0.4ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'requestevent'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 214
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'requestevent'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 214
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.3ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'request') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 219], ["LIMIT", 1]]
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'request') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 219], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.0ms - 1rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'request_starting') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 349], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'requestevent'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 214
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Load (0.4ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 219
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" = 349
  MiqAeMethod Inst Including Associations (0.0ms - 0rows)
  MiqAeField Load (0.6ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" = 349 ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (0.6ms - 21rows)
  MiqAeInstance Load (0.3ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" = 349
  MiqAeInstance Inst Including Associations (0.2ms - 7rows)
  MiqAeValue Load (0.5ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (2540, 2539, 2538, 2537, 2536, 2535, 2534)
  MiqAeValue Inst Including Associations (0.2ms - 6rows)
  MiqAeField Load (0.4ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" = 3628
  MiqAeField Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.5ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'policy') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Load (0.4ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'policy') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeInstance Load (0.4ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'request_starting') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 324], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.4ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'get_request_type') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 324], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.4ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 760]]
  MiqAeField Inst Including Associations (0.1ms - 0rows)
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 324], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeInstance Load (0.5ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'parse_provider_category') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.5ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'parse_provider_category') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.4ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 766]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.5ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 325], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.2ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  ServiceTemplate Load (0.4ms)  SELECT  "service_templates".* FROM "service_templates" WHERE "service_templates"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  ServiceTemplate Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeInstance Load (0.5ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'servicetemplateprovisionrequest_starting') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 324], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'commonmethods'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 212
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'commonmethods'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 212
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.4ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'quotastatemachine') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 213], ["LIMIT", 1]]
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'quotastatemachine') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 213], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'quota') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 330], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'commonmethods'
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 212
  MiqAeNamespace Inst Including Associations (0.2ms - 1rows)
  MiqAeClass Load (0.4ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 213
  MiqAeClass Inst Including Associations (0.2ms - 5rows)
  MiqAeMethod Load (0.8ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" IN (328, 329, 330, 331, 332)
  MiqAeMethod Inst Including Associations (0.5ms - 9rows)
  MiqAeField Load (0.6ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" IN (328, 329, 330, 331, 332) ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (0.6ms - 22rows)
  MiqAeInstance Load (0.5ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" IN (328, 329, 330, 331, 332)
  MiqAeInstance Inst Including Associations (0.2ms - 12rows)
  MiqAeValue Load (0.5ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (2027, 2026, 2025, 2024, 2023, 2028, 2034, 2033, 2032, 2031, 2030, 2029)
  MiqAeValue Inst Including Associations (0.7ms - 25rows)
  MiqAeField Load (0.6ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" IN (3254, 3269, 3268, 3267, 3266, 3265, 3264, 3263, 3262, 3261, 3260, 3259, 3258, 3257, 3256, 3271)
  MiqAeField Inst Including Associations (0.4ms - 16rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeClass Exists (0.5ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'quotamethods') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 213], ["LIMIT", 1]]
  MiqAeClass Load (0.4ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'quotamethods') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 213], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'quota_source') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 329], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'quota_source') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 329], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.2ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 785]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.2ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 329], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 213], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.2ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  Tenant Load (0.3ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.3ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeInstance Load (0.4ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'limits') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 329], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'limits') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 329], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.2ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 784]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 329], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 213], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  TenantQuota Load (0.4ms)  SELECT "tenant_quotas".* FROM "tenant_quotas" WHERE "tenant_quotas"."tenant_id" = $1  [["tenant_id", 1]]
  TenantQuota Inst Including Associations (0.0ms - 0rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
   (0.1ms)  BEGIN
  SQL (0.4ms)  UPDATE "miq_requests" SET "options" = $1, "updated_on" = $2 WHERE "miq_requests"."id" = $3  [["options", "---\n:dialog:\n  dialog_textarea_box_1: ''\n:workflow_settings:\n  :resource_action_id: 12\n  :dialog_id: 2\n:initiator: \n:src_id: 1\n:request_options:\n  :submit_workflow: true\n  :init_defaults: false\n:cart_state: ordered\n:requester_group: EvmGroup-super_administrator\n:executed_on_servers:\n- 1\n:delivered_on: 2019-09-03 22:49:00.840757000 Z\n"], ["updated_on", "2019-09-03 22:49:00.843616"], ["id", 19]]
   (1.0ms)  COMMIT
   (0.2ms)  BEGIN
  Tenant Load (0.2ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.2ms - 1rows)
  SQL (0.9ms)  INSERT INTO "miq_request_tasks" ("description", "request_type", "userid", "options", "created_on", "updated_on", "message", "type", "miq_request_id", "source_id", "source_type", "tenant_id", "phase_context", "state", "status") VALUES ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13, $14, $15) RETURNING "id"  [["description", "Provisioning Service [drew] from [drew]"], ["request_type", "clone_to_service"], ["userid", "admin"], ["options", "---\n:dialog:\n  dialog_textarea_box_1: ''\n:workflow_settings:\n  :resource_action_id: 12\n  :dialog_id: 2\n:initiator: \n:src_id: 1\n:request_options:\n  :submit_workflow: true\n  :init_defaults: false\n:cart_state: ordered\n:requester_group: EvmGroup-super_administrator\n:executed_on_servers:\n- 1\n:delivered_on: 2019-09-03 22:49:00.840757000 Z\n:pass: 0\n"], ["created_on", "2019-09-03 22:49:00.946196"], ["updated_on", "2019-09-03 22:49:00.946196"], ["message", "Service_Template_Provisioning - Request Created"], ["type", "ServiceTemplateProvisionTask"], ["miq_request_id", 19], ["source_id", 1], ["source_type", "ServiceTemplate"], ["tenant_id", 1], ["phase_context", nil], ["state", "pending"], ["status", "Ok"]]
   (1.2ms)  COMMIT
  ServiceTemplate Load (0.6ms)  SELECT  "service_templates".* FROM "service_templates" WHERE "service_templates"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  ServiceTemplate Inst Including Associations (0.2ms - 1rows)
   (0.1ms)  BEGIN
  SQL (0.6ms)  UPDATE "miq_request_tasks" SET "description" = $1, "updated_on" = $2 WHERE "miq_request_tasks"."id" = $3  [["description", "Provisioning [drew] for Service [drew]"], ["updated_on", "2019-09-03 22:49:00.962231"], ["id", 10]]
   (0.2ms)  COMMIT
  Service Load (1.0ms)  SELECT  "services".* FROM "services" WHERE "services"."id" IS NULL LIMIT $1  [["LIMIT", 1]]
  Service Inst Including Associations (0.0ms - 0rows)
  ServiceTemplate Load (0.6ms)  SELECT  "service_templates".* FROM "service_templates" WHERE "service_templates"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  ServiceTemplate Inst Including Associations (0.1ms - 1rows)
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" = $3 LIMIT $4  [["id", 0], ["id", 999999999999], ["userid", "admin"], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.4ms)  SELECT "miq_groups".* 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  [["user_id", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  MiqGroup Load (0.2ms)  SELECT  "miq_groups".* FROM "miq_groups" WHERE "miq_groups"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  MiqGroup Inst Including Associations (0.1ms - 1rows)
  MiqUserRole Load (0.5ms)  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", 2], ["LIMIT", 1]]
  MiqUserRole Inst Including Associations (0.1ms - 1rows)
   (0.7ms)  SELECT "miq_product_features"."identifier" 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", 1]]
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE ("users"."id" BETWEEN $1 AND $2) AND "users"."userid" IS NULL LIMIT $3  [["id", 0], ["id", 999999999999], ["LIMIT", 1]]
  User Inst Including Associations (0.0ms - 0rows)
  Tenant Load (0.2ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  MiqAeDomain Load (0.8ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" INNER JOIN "tenants" ON "tenants"."id" = "miq_ae_namespaces"."tenant_id" WHERE "miq_ae_namespaces"."parent_id" IS NULL AND ("miq_ae_namespaces"."name" != '$') AND "miq_ae_namespaces"."tenant_id" = 1 AND "miq_ae_namespaces"."enabled" = $1 ORDER BY tenants.ancestry DESC NULLS LAST, priority DESC  [["enabled", "t"]]
  MiqAeDomain Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.7ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.2ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Exists (0.4ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'process') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Load (1.1ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'process') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeInstance Load (0.4ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'request') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.4ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'system'
  MiqAeNamespace Inst Including Associations (0.4ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = 153
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Load (0.3ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 212
  MiqAeClass Inst Including Associations (0.1ms - 5rows)
  MiqAeMethod Load (0.5ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" IN (323, 324, 325, 326, 327)
  MiqAeMethod Inst Including Associations (2.0ms - 23rows)
  MiqAeField Load (1.1ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" IN (323, 324, 325, 326, 327) ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (1.9ms - 70rows)
  MiqAeInstance Load (1.0ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" IN (323, 324, 325, 326, 327)
  MiqAeInstance Inst Including Associations (1.6ms - 79rows)
  MiqAeValue Load (1.8ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (1944, 1956, 1973, 1977, 1978, 1983, 1992, 1945, 1946, 1947, 1948, 1949, 1950, 1951, 1952, 1953, 1954, 1955, 1957, 1958, 1959, 1960, 1961, 1962, 1963, 1964, 1965, 1966, 1967, 1968, 1969, 1970, 1971, 1972, 1974, 1975, 1976, 1979, 1980, 1981, 1982, 1984, 1985, 1986, 1987, 1988, 1989, 1990, 1991, 1993, 1994, 1995, 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005, 2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013, 2014, 2015, 2016, 2017, 2018, 2019, 2020, 2021, 2022)
  MiqAeValue Inst Including Associations (2.6ms - 104rows)
  MiqAeField Load (0.9ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" IN (3183, 3181, 3179, 3172, 3170, 3175, 3182, 3195, 3194, 3192, 3201, 3202, 3219, 3215, 3221, 3222, 3214, 3213, 3220, 3238)
  MiqAeField Inst Including Associations (0.8ms - 20rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = '$'
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Load (0.3ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" = 228
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" = 360
  MiqAeMethod Inst Including Associations (0.2ms - 3rows)
  MiqAeField Load (0.4ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" = 360 ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeInstance Load (0.3ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" = 360
  MiqAeInstance Inst Including Associations (0.0ms - 0rows)
  MiqServer Load (0.4ms)  SELECT  "miq_servers".* FROM "miq_servers" WHERE "miq_servers"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  MiqServer Inst Including Associations (0.3ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  ServiceTemplate Load (0.4ms)  SELECT  "service_templates".* FROM "service_templates" WHERE "service_templates"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  ServiceTemplate Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  ServiceTemplateProvisionTask Load (0.5ms)  SELECT  "miq_request_tasks".* FROM "miq_request_tasks" WHERE "miq_request_tasks"."type" IN ('ServiceTemplateProvisionTask', 'ServiceTemplateTransformationPlanTask') AND "miq_request_tasks"."id" = $1 LIMIT $2  [["id", 10], ["LIMIT", 1]]
  ServiceTemplateProvisionTask Inst Including Associations (0.2ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  User Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeMethod Load (0.3ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'parse_provider_category') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.2ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 766]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.2ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 325], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.4ms - 1rows)
  MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 212], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.4ms - 1rows)
  MiqAeClass Exists (0.5ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'request') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Load (0.4ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'request') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 212], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.0ms - 1rows)
  MiqAeInstance Load (0.4ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'service_provision_info') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 326], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeNamespace Load (0.3ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'provisioning'
  MiqAeNamespace Inst Including Associations (0.1ms - 7rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" IN (158, 162, 170, 185, 190, 202, 205)
  MiqAeNamespace Inst Including Associations (0.2ms - 7rows)
  MiqAeNamespace Load (0.6ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 201], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.6ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'provisioning'
  MiqAeNamespace Inst Including Associations (0.1ms - 7rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" IN (158, 162, 170, 185, 190, 202, 205)
  MiqAeNamespace Inst Including Associations (0.2ms - 7rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.2ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 201], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.8ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.7ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeClass Exists (0.5ms)  SELECT  1 AS one FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'profile') AND "miq_ae_classes"."namespace_id" = $1 LIMIT $2  [["namespace_id", 208], ["LIMIT", 1]]
  MiqAeClass Load (0.4ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE (LOWER("miq_ae_classes"."name") ILIKE 'profile') AND "miq_ae_classes"."namespace_id" = $1 ORDER BY "miq_ae_classes"."id" ASC LIMIT $2  [["namespace_id", 208], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  MiqAeInstance Load (0.4ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'evmgroup-super_administrator') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 314], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.0ms - 0rows)
  MiqAeInstance Load (0.3ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE '.missing') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 314], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE LOWER("miq_ae_namespaces"."name") = 'provisioning'
  MiqAeNamespace Inst Including Associations (0.3ms - 7rows)
  MiqAeNamespace Load (0.5ms)  SELECT "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" IN (158, 162, 170, 185, 190, 202, 205)
  MiqAeNamespace Inst Including Associations (0.3ms - 7rows)
  MiqAeClass Load (0.5ms)  SELECT "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."namespace_id" IN (159, 164, 172, 186, 194, 203, 208)
  MiqAeClass Inst Including Associations (0.7ms - 16rows)
  MiqAeMethod Load (1.3ms)  SELECT "miq_ae_methods".* FROM "miq_ae_methods" WHERE "miq_ae_methods"."class_id" IN (244, 248, 260, 261, 262, 263, 278, 279, 292, 293, 294, 295, 308, 313, 314, 315)
  MiqAeMethod Inst Including Associations (1.4ms - 33rows)
  MiqAeField Load (1.0ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."class_id" IN (244, 248, 260, 261, 262, 263, 278, 279, 292, 293, 294, 295, 308, 313, 314, 315) ORDER BY "miq_ae_fields"."priority" ASC
  MiqAeField Inst Including Associations (1.8ms - 90rows)
  MiqAeInstance Load (0.9ms)  SELECT "miq_ae_instances".* FROM "miq_ae_instances" WHERE "miq_ae_instances"."class_id" IN (244, 248, 260, 261, 262, 263, 278, 279, 292, 293, 294, 295, 308, 313, 314, 315)
  MiqAeInstance Inst Including Associations (0.7ms - 35rows)
  MiqAeValue Load (0.8ms)  SELECT "miq_ae_values".* FROM "miq_ae_values" WHERE "miq_ae_values"."instance_id" IN (1791, 1792, 1827, 1828, 1829, 1830, 1859, 1860, 1901, 1902, 1749, 1750, 1761, 1762, 1785, 1786, 1787, 1788, 1789, 1790, 1825, 1826, 1853, 1854, 1855, 1856, 1857, 1858, 1915, 1916, 1917, 1918, 1919, 1920, 1921)
  MiqAeValue Inst Including Associations (0.7ms - 30rows)
  MiqAeField Load (0.8ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."id" IN (2495, 2547, 2644, 2648, 2660, 2659, 2658, 2657, 2805, 2905, 2909, 2920, 2919, 2918, 3124, 3127, 3128)
  MiqAeField Inst Including Associations (0.5ms - 17rows)
  MiqAeNamespace Load (0.6ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 155], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.1ms - 1rows)
  MiqAeNamespace Load (0.3ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 154], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 161], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 182], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 201], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  MiqAeNamespace Load (0.4ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
  ServiceResource Load (0.8ms)  SELECT "service_resources".* FROM "service_resources" WHERE "service_resources"."service_template_id" = $1 ORDER BY group_idx ASC  [["service_template_id", 1]]
  ServiceResource Inst Including Associations (0.0ms - 0rows)
   (0.1ms)  BEGIN
  Tenant Load (0.2ms)  SELECT  "tenants".* FROM "tenants" WHERE "tenants"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Tenant Inst Including Associations (0.1ms - 1rows)
  Service Exists (0.4ms)  SELECT  1 AS one FROM "services" WHERE "services"."guid" = $1 LIMIT $2  [["guid", "15c11f39-e6cf-43a8-8e39-340cc9da4f51"], ["LIMIT", 1]]
  SQL (0.6ms)  INSERT INTO "services" ("name", "service_template_id", "options", "display", "created_at", "updated_at", "evm_owner_id", "miq_group_id", "tenant_id", "guid", "initiator", "lifecycle_state", "retired") VALUES ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13) RETURNING "id"  [["name", "drew"], ["service_template_id", 1], ["options", "---\n:dialog:\n  dialog_textarea_box_1: ''\n"], ["display", "t"], ["created_at", "2019-09-03 22:49:01.408211"], ["updated_at", "2019-09-03 22:49:01.408211"], ["evm_owner_id", 1], ["miq_group_id", 2], ["tenant_id", 1], ["guid", "15c11f39-e6cf-43a8-8e39-340cc9da4f51"], ["initiator", "user"], ["lifecycle_state", "unprovisioned"], ["retired", "f"]]
   (0.4ms)  COMMIT
   (0.1ms)  BEGIN
  SQL (0.5ms)  UPDATE "miq_request_tasks" SET "updated_on" = $1, "destination_id" = $2, "destination_type" = $3 WHERE "miq_request_tasks"."id" = $4  [["updated_on", "2019-09-03 22:49:01.417765"], ["destination_id", 10], ["destination_type", "Service"], ["id", 10]]
   (0.3ms)  COMMIT
  MiqApproval Load (0.4ms)  SELECT "miq_approvals".* FROM "miq_approvals" WHERE "miq_approvals"."miq_request_id" = $1  [["miq_request_id", 19]]
  MiqApproval Inst Including Associations (0.5ms - 1rows)
  ResourceAction Load (0.5ms)  SELECT "resource_actions".* FROM "resource_actions" WHERE "resource_actions"."resource_id" = $1 AND "resource_actions"."resource_type" = $2  [["resource_id", 1], ["resource_type", "ServiceTemplate"]]
  ResourceAction Inst Including Associations (31.1ms - 2rows)
  Zone Load (0.7ms)  SELECT  "zones".* FROM "zones" WHERE "zones"."id" = $1 LIMIT $2  [["id", 2], ["LIMIT", 1]]
  Zone Inst Including Associations (0.1ms - 1rows)
  Zone Load (0.3ms)  SELECT  "zones".* FROM "zones" WHERE "zones"."id" = $1 LIMIT $2  [["id", 1], ["LIMIT", 1]]
  Zone Inst Including Associations (0.1ms - 1rows)
   (0.1ms)  BEGIN
   (0.2ms)  SELECT "zones"."name" FROM "zones" WHERE ("zones"."id" BETWEEN $1 AND $2)  [["id", 0], ["id", 999999999999]]
  SQL (0.5ms)  INSERT INTO "miq_queue" ("priority", "method_name", "state", "created_on", "updated_on", "queue_name", "class_name", "args", "zone", "role", "msg_timeout", "tracking_label", "lock_version") VALUES ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13) RETURNING "id"  [["priority", 100], ["method_name", "deliver"], ["state", "ready"], ["created_on", "2019-09-03 22:49:01.473929"], ["updated_on", "2019-09-03 22:49:01.473929"], ["queue_name", "generic"], ["class_name", "MiqAeEngine"], ["args", "---\n- :object_type: ServiceTemplateProvisionTask\n  :object_id: 10\n  :namespace: Service/Provisioning/StateMachines\n  :class_name: ServiceProvision_Template\n  :instance_name: CatalogItemInitialization\n  :automate_message: create\n  :attrs:\n    dialog_textarea_box_1: ''\n    request: clone_to_service\n    :service_action: Provision\n    Service::Service: 10\n  :user_id: 1\n  :miq_group_id: 2\n  :tenant_id: 1\n"], ["zone", "default"], ["role", "automate"], ["msg_timeout", 600], ["tracking_label", "r19_service_template_provision_task_10"], ["lock_version", 0]]
   (1.0ms)  COMMIT
   (0.1ms)  BEGIN
  SQL (0.4ms)  UPDATE "miq_request_tasks" SET "options" = $1, "updated_on" = $2, "message" = $3 WHERE "miq_request_tasks"."id" = $4  [["options", "---\n:dialog:\n  dialog_textarea_box_1: ''\n  request: clone_to_service\n  :service_action: Provision\n  Service::Service: 10\n:workflow_settings:\n  :resource_action_id: 12\n  :dialog_id: 2\n:initiator: \n:src_id: 1\n:request_options:\n  :submit_workflow: true\n  :init_defaults: false\n:cart_state: ordered\n:requester_group: EvmGroup-super_administrator\n:executed_on_servers:\n- 1\n:delivered_on: 2019-09-03 22:49:00.840757000 Z\n:pass: 0\n"], ["updated_on", "2019-09-03 22:49:01.479774"], ["message", "Automation Starting"], ["id", 10]]
   (0.3ms)  COMMIT
  ServiceTemplateProvisionRequest Load (0.5ms)  SELECT  "miq_requests".* FROM "miq_requests" WHERE "miq_requests"."type" IN ('ServiceTemplateProvisionRequest', 'ServiceTemplateTransformationPlanRequest') AND "miq_requests"."id" = $1 LIMIT $2  [["id", 19], ["LIMIT", 1]]
  ServiceTemplateProvisionRequest Inst Including Associations (0.1ms - 1rows)
   (0.5ms)  SELECT COUNT(*) FROM "miq_request_tasks" WHERE "miq_request_tasks"."miq_request_id" = $1  [["miq_request_id", 19]]
  MiqRequestTask Load (0.2ms)  SELECT "miq_request_tasks".* FROM "miq_request_tasks" WHERE "miq_request_tasks"."miq_request_id" = $1  [["miq_request_id", 19]]
...

@d-m-u
Copy link
Author

d-m-u commented Sep 4, 2019 •
edited
Loading

tldr

right after line 182 on the rails c output above:

they both have

PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0

and then 2.5.5 has a delete, right before the approval state machine of a generic service order

2.5.4:

MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
  MiqAeInstance Load (1.2ms)  SELECT  "miq_ae_instances".* FROM "miq_ae_instances" WHERE (LOWER("miq_ae_instances"."name") ILIKE 'parse_provider_category') AND "miq_ae_instances"."class_id" = $1 ORDER BY "miq_ae_instances"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeInstance Inst Including Associations (0.1ms - 1rows)
  MiqAeMethod Load (0.3ms)  SELECT  "miq_ae_methods".* FROM "miq_ae_methods" WHERE (LOWER("miq_ae_methods"."name") ILIKE 'parse_provider_category') AND "miq_ae_methods"."class_id" = $1 ORDER BY "miq_ae_methods"."id" ASC LIMIT $2  [["class_id", 325], ["LIMIT", 1]]
  MiqAeMethod Inst Including Associations (0.1ms - 1rows)
  MiqAeField Load (0.4ms)  SELECT "miq_ae_fields".* FROM "miq_ae_fields" WHERE "miq_ae_fields"."method_id" = $1 ORDER BY "miq_ae_fields"."priority" ASC  [["method_id", 766]]
  MiqAeField Inst Including Associations (0.0ms - 0rows)
  MiqAeClass Load (0.3ms)  SELECT  "miq_ae_classes".* FROM "miq_ae_classes" WHERE "miq_ae_classes"."id" = $1 LIMIT $2  [["id", 325], ["LIMIT", 1]]
  MiqAeClass Inst Including Associations (0.1ms - 1rows)
  ...

2.5.5:

MiqAeNamespace Load (0.2ms)  SELECT  "miq_ae_namespaces".* FROM "miq_ae_namespaces" WHERE "miq_ae_namespaces"."id" = $1 LIMIT $2  [["id", 153], ["LIMIT", 1]]
  MiqAeNamespace Inst Including Associations (0.0ms - 1rows)
PostgreSQLAdapter#log_after_checkin, connection_pool: size: 5, connections: 1, in use: 0, waiting_in_queue: 0
PostgreSQLAdapter#log_after_checkout, connection_pool: size: 5, connections: 1, in use: 1, waiting_in_queue: 0
   (0.1ms)  BEGIN
  SQL (0.3ms)  DELETE FROM "miq_queue" WHERE "miq_queue"."id" = $1 AND "miq_queue"."lock_version" = $2  [["id", 93], ["lock_version", 0]]
   (1.1ms)  COMMIT

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment