(110.2ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (0.2ms)  select sqlite_version(*)
   (74.7ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
  ActiveRecord::SchemaMigration Load (0.2ms)  SELECT "schema_migrations".* FROM "schema_migrations"
Migrating to CreateUsersTable (20150406154440)
   (0.1ms)  begin transaction
   (0.7ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar) 
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150406154440"]]
   (96.1ms)  commit transaction
Migrating to CreateGroupsTable (20150517175135)
   (0.1ms)  begin transaction
DEPRECATION WARNING: `#timestamp` was called without specifying an option for `null`. In Rails 5, this behavior will change to `null: false`. You should manually specify `null: true` to prevent the behavior of your existing migrations from changing. (called from block in change at /home/work/Gems/paid_up/spec/dummy/db/migrate/20150517175135_create_groups_table.rb:7)
   (0.5ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150517175135"]]
   (103.8ms)  commit transaction
Migrating to CreateDoodadsTable (20150517175136)
   (0.1ms)  begin transaction
   (0.5ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.3ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150517175136"]]
   (102.9ms)  commit transaction
Migrating to AddDeviseToUsers (20150523010827)
   (0.1ms)  begin transaction
   (0.4ms)  ALTER TABLE "users" ADD "email" varchar DEFAULT '' NOT NULL
   (0.2ms)  ALTER TABLE "users" ADD "encrypted_password" varchar DEFAULT '' NOT NULL
   (0.2ms)  ALTER TABLE "users" ADD "reset_password_token" varchar
   (0.2ms)  ALTER TABLE "users" ADD "reset_password_sent_at" datetime
   (0.2ms)  ALTER TABLE "users" ADD "remember_created_at" datetime
   (0.2ms)  ALTER TABLE "users" ADD "sign_in_count" integer DEFAULT 0 NOT NULL
   (0.2ms)  ALTER TABLE "users" ADD "current_sign_in_at" datetime
   (0.2ms)  ALTER TABLE "users" ADD "last_sign_in_at" datetime
   (0.2ms)  ALTER TABLE "users" ADD "current_sign_in_ip" varchar
   (0.2ms)  ALTER TABLE "users" ADD "last_sign_in_ip" varchar
   (0.3ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (0.2ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010827"]]
   (82.9ms)  commit transaction
Migrating to RolifyCreateRoles (20150523010837)
   (0.1ms)  begin transaction
DEPRECATION WARNING: `#timestamp` was called without specifying an option for `null`. In Rails 5, this behavior will change to `null: false`. You should manually specify `null: true` to prevent the behavior of your existing migrations from changing. (called from block in change at /home/work/Gems/paid_up/spec/dummy/db/migrate/20150523010837_rolify_create_roles.rb:7)
   (0.3ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (0.2ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (0.2ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name' AND type='index'

   (0.2ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010837"]]
   (77.7ms)  commit transaction
Migrating to CreatePaidUpPlanFeatureSettingsTable (20150523010838)
   (0.1ms)  begin transaction
   (0.4ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer)
   (0.2ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'

   (0.2ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010838"]]
   (70.9ms)  commit transaction
Migrating to CreatePaidUpPlansTable (20150523010839)
   (0.1ms)  begin transaction
DEPRECATION WARNING: `#timestamp` was called without specifying an option for `null`. In Rails 5, this behavior will change to `null: false`. You should manually specify `null: true` to prevent the behavior of your existing migrations from changing. (called from block in change at /home/work/Gems/paid_up/spec/dummy/db/migrate/20150523010839_create_paid_up_plans_table.paid_up.rb:10)
   (0.4ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (0.5ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (0.3ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010839"]]
   (84.3ms)  commit transaction
Migrating to AddStripeIdColumnToUsers (20150523010840)
   (0.1ms)  begin transaction
   (0.4ms)  ALTER TABLE "users" ADD "stripe_id" varchar
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010840"]]
   (80.8ms)  commit transaction
  ActiveRecord::SchemaMigration Load (0.2ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_doodads_on_user_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_doodads_on_user_id' AND type='index'

   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_stripe_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_stripe_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_reset_password_token' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_reset_password_token' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_roles_on_user_id_and_role_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_roles_on_user_id_and_role_id' AND type='index'

   (79.8ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.1ms)  select sqlite_version(*)
   (75.2ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (98.3ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (98.6ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer) 
   (82.0ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (82.0ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (82.3ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (90.0ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (106.7ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (114.8ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (98.9ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (98.4ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (102.3ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (133.0ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (90.0ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (115.5ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (90.4ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (97.0ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (98.5ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.2ms)  SELECT version FROM "schema_migrations"
   (98.5ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (107.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (91.1ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (91.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (91.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (99.1ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (83.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (91.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
   (73.2ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.1ms)  select sqlite_version(*)
   (73.2ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (107.2ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (106.3ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer)
   (90.5ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (97.7ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (82.1ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (104.6ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (117.8ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (115.0ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (107.9ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (73.5ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (115.0ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (90.0ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (98.6ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (81.9ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (74.1ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (73.6ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (82.5ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.2ms)  SELECT version FROM "schema_migrations"
   (98.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (74.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (82.9ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (75.9ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (74.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (82.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (82.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (99.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (92.3ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (0.1ms)  select sqlite_version(*)
   (73.8ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
  ActiveRecord::SchemaMigration Load (0.2ms)  SELECT "schema_migrations".* FROM "schema_migrations"
Migrating to CreateUsersTable (20150406154440)
   (0.1ms)  begin transaction
   (0.2ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar) 
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150406154440"]]
   (85.4ms)  commit transaction
Migrating to CreateGroupsTable (20150517175135)
   (0.1ms)  begin transaction
DEPRECATION WARNING: `#timestamp` was called without specifying an option for `null`. In Rails 5, this behavior will change to `null: false`. You should manually specify `null: true` to prevent the behavior of your existing migrations from changing. (called from block in change at /home/work/Gems/paid_up/spec/dummy/db/migrate/20150517175135_create_groups_table.rb:7)
   (0.5ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150517175135"]]
   (95.0ms)  commit transaction
Migrating to CreateDoodadsTable (20150517175136)
   (0.1ms)  begin transaction
   (0.4ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.2ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150517175136"]]
   (104.1ms)  commit transaction
Migrating to AddDeviseToUsers (20150523010827)
   (0.1ms)  begin transaction
   (0.3ms)  ALTER TABLE "users" ADD "email" varchar DEFAULT '' NOT NULL
   (0.2ms)  ALTER TABLE "users" ADD "encrypted_password" varchar DEFAULT '' NOT NULL
   (0.2ms)  ALTER TABLE "users" ADD "reset_password_token" varchar
   (0.2ms)  ALTER TABLE "users" ADD "reset_password_sent_at" datetime
   (0.2ms)  ALTER TABLE "users" ADD "remember_created_at" datetime
   (0.3ms)  ALTER TABLE "users" ADD "sign_in_count" integer DEFAULT 0 NOT NULL
   (0.2ms)  ALTER TABLE "users" ADD "current_sign_in_at" datetime
   (0.3ms)  ALTER TABLE "users" ADD "last_sign_in_at" datetime
   (0.2ms)  ALTER TABLE "users" ADD "current_sign_in_ip" varchar
   (0.2ms)  ALTER TABLE "users" ADD "last_sign_in_ip" varchar
   (0.3ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (0.2ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010827"]]
   (99.3ms)  commit transaction
Migrating to RolifyCreateRoles (20150523010837)
   (0.1ms)  begin transaction
DEPRECATION WARNING: `#timestamp` was called without specifying an option for `null`. In Rails 5, this behavior will change to `null: false`. You should manually specify `null: true` to prevent the behavior of your existing migrations from changing. (called from block in change at /home/work/Gems/paid_up/spec/dummy/db/migrate/20150523010837_rolify_create_roles.rb:7)
   (0.5ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (0.3ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (0.3ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name' AND type='index'

   (0.3ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.3ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010837"]]
   (90.7ms)  commit transaction
Migrating to CreatePaidUpPlanFeatureSettingsTable (20150523010838)
   (0.1ms)  begin transaction
   (0.4ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer)
   (0.2ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'

   (0.2ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010838"]]
   (86.7ms)  commit transaction
Migrating to CreatePaidUpPlansTable (20150523010839)
   (0.1ms)  begin transaction
DEPRECATION WARNING: `#timestamp` was called without specifying an option for `null`. In Rails 5, this behavior will change to `null: false`. You should manually specify `null: true` to prevent the behavior of your existing migrations from changing. (called from block in change at /home/work/Gems/paid_up/spec/dummy/db/migrate/20150523010839_create_paid_up_plans_table.paid_up.rb:10)
   (0.4ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (0.3ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (0.2ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010839"]]
   (94.0ms)  commit transaction
Migrating to AddStripeIdColumnToUsers (20150523010840)
   (0.1ms)  begin transaction
   (0.4ms)  ALTER TABLE "users" ADD "stripe_id" varchar
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010840"]]
   (72.4ms)  commit transaction
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_doodads_on_user_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_doodads_on_user_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_stripe_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_stripe_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_reset_password_token' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_reset_password_token' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_roles_on_user_id_and_role_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_roles_on_user_id_and_role_id' AND type='index'

   (182.0ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.2ms)  select sqlite_version(*)
   (89.6ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (89.7ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (73.7ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer) 
   (81.4ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (90.5ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (81.7ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (124.0ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (89.9ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (108.4ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (90.5ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (115.0ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (96.0ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (90.7ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.3ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (97.6ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (82.2ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (82.4ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (79.5ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (106.9ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.2ms)  SELECT version FROM "schema_migrations"
   (90.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (99.3ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (100.5ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (115.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (115.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (99.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (91.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (91.1ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
   (73.1ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.1ms)  select sqlite_version(*)
   (73.8ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (73.7ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (73.9ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer)
   (74.0ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (73.6ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (74.0ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (91.7ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (73.7ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (73.9ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (90.7ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (97.8ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (73.5ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (74.2ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (73.1ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (90.7ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (74.3ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (73.7ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (82.5ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.1ms)  SELECT version FROM "schema_migrations"
   (82.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (75.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (74.7ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (99.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (82.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (82.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (82.7ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (74.5ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  begin transaction
  SQL (0.5ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Anonymous"], ["stripe_id", "anonymous-plan"], ["description", "What you can do without logging in."], ["sort_order", -1], ["created_at", "2015-05-23 23:15:48.308604"], ["updated_at", "2015-05-23 23:15:48.308604"]]
   (95.2ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Free"], ["stripe_id", "free-plan"], ["description", "Can't beat the price!"], ["sort_order", 0], ["created_at", "2015-05-23 23:15:49.137280"], ["updated_at", "2015-05-23 23:15:49.137280"]]
   (99.7ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "No Ads"], ["stripe_id", "no-ads-plan"], ["description", "No frills, just removes the ads."], ["sort_order", 1], ["created_at", "2015-05-23 23:15:50.020196"], ["updated_at", "2015-05-23 23:15:50.020196"]]
   (90.2ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Group Leader"], ["stripe_id", "group-leader-plan"], ["description", "For leaders of single groups, with configuration."], ["sort_order", 2], ["created_at", "2015-05-23 23:15:50.833210"], ["updated_at", "2015-05-23 23:15:50.833210"]]
   (101.5ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.5ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Professional"], ["stripe_id", "professional-plan"], ["description", "Designed for professionals with unlimited groups, a calendar and configuration."], ["sort_order", 3], ["created_at", "2015-05-23 23:15:51.597683"], ["updated_at", "2015-05-23 23:15:51.597683"]]
   (88.3ms)  commit transaction
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Free"]]
  PaidUp::Plan Load (0.3ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "No Ads"]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Group Leader"]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Professional"]]
   (76.7ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.2ms)  select sqlite_version(*)
   (74.4ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (90.8ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (233.8ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer) 
   (103.7ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (89.5ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (90.0ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (82.2ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (81.4ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (106.6ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (90.3ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (73.7ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (104.5ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (74.1ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (99.8ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (98.9ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (82.3ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (97.8ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (82.0ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.1ms)  SELECT version FROM "schema_migrations"
   (98.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (107.5ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (107.5ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (91.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (91.1ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (91.1ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (107.5ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (99.3ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
   (74.5ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.1ms)  select sqlite_version(*)
   (73.9ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (74.1ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (73.7ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer)
   (98.8ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (106.8ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (90.5ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (123.6ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (123.2ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (90.8ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (98.5ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (73.5ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (90.8ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (73.9ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (98.5ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (73.8ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (90.9ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (74.2ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (106.9ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.2ms)  SELECT version FROM "schema_migrations"
   (107.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (116.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (82.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (82.7ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (82.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (125.5ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (99.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (99.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  begin transaction
  SQL (0.2ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Anonymous"], ["stripe_id", "anonymous-plan"], ["description", "What you can do without logging in."], ["sort_order", -1], ["created_at", "2015-05-23 23:16:43.361531"], ["updated_at", "2015-05-23 23:16:43.361531"]]
   (86.6ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Free"], ["stripe_id", "free-plan"], ["description", "Can't beat the price!"], ["sort_order", 0], ["created_at", "2015-05-23 23:16:44.307257"], ["updated_at", "2015-05-23 23:16:44.307257"]]
   (104.9ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.2ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "No Ads"], ["stripe_id", "no-ads-plan"], ["description", "No frills, just removes the ads."], ["sort_order", 1], ["created_at", "2015-05-23 23:16:45.347388"], ["updated_at", "2015-05-23 23:16:45.347388"]]
   (103.7ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Group Leader"], ["stripe_id", "group-leader-plan"], ["description", "For leaders of single groups, with configuration."], ["sort_order", 2], ["created_at", "2015-05-23 23:16:46.123551"], ["updated_at", "2015-05-23 23:16:46.123551"]]
   (118.4ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Professional"], ["stripe_id", "professional-plan"], ["description", "Designed for professionals with unlimited groups, a calendar and configuration."], ["sort_order", 3], ["created_at", "2015-05-23 23:16:46.961167"], ["updated_at", "2015-05-23 23:16:46.961167"]]
   (80.5ms)  commit transaction
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Free"]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "No Ads"]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Group Leader"]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Professional"]]
   (0.1ms)  begin transaction
  SQL (0.2ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "ad_free"], ["plan_id", 3], ["setting", 1]]
   (101.7ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "ad_free"], ["plan_id", 4], ["setting", 1]]
   (97.5ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "groups"], ["plan_id", 4], ["setting", 1]]
   (87.9ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.5ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "doodads"], ["plan_id", 4], ["setting", 5]]
   (87.8ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "ad_free"], ["plan_id", 5], ["setting", 1]]
   (96.3ms)  commit transaction
   (0.3ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "groups"], ["plan_id", 5], ["setting", 999999999]]
   (96.0ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "doodads"], ["plan_id", 5], ["setting", 999999999]]
   (104.2ms)  commit transaction
   (110.0ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.2ms)  select sqlite_version(*)
   (72.8ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (73.2ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (82.1ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer) 
   (82.3ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.3ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (72.8ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (82.2ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (82.3ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (82.2ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (82.1ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (82.5ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (81.3ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (79.8ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (83.6ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (89.8ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (83.1ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (81.6ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (80.6ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (98.9ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.1ms)  SELECT version FROM "schema_migrations"
   (90.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (82.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (99.3ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (91.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (91.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (107.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (99.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (92.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
   (72.6ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.1ms)  select sqlite_version(*)
   (107.6ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (107.8ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (82.0ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer)
   (74.1ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (73.1ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (82.1ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (82.3ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (81.7ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (82.2ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (98.7ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (82.5ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (106.5ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (90.7ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (81.2ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (99.0ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (82.4ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (82.4ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (73.8ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.1ms)  SELECT version FROM "schema_migrations"
   (74.3ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (74.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (82.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (90.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (76.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (74.5ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (74.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (82.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.1ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Anonymous"], ["stripe_id", "anonymous-plan"], ["description", "What you can do without logging in."], ["sort_order", -1], ["created_at", "2015-05-23 23:17:26.720269"], ["updated_at", "2015-05-23 23:17:26.720269"]]
   (104.1ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Free"], ["stripe_id", "free-plan"], ["description", "Can't beat the price!"], ["sort_order", 0], ["created_at", "2015-05-23 23:17:27.672034"], ["updated_at", "2015-05-23 23:17:27.672034"]]
   (83.3ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "No Ads"], ["stripe_id", "no-ads-plan"], ["description", "No frills, just removes the ads."], ["sort_order", 1], ["created_at", "2015-05-23 23:17:28.663675"], ["updated_at", "2015-05-23 23:17:28.663675"]]
   (80.4ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Group Leader"], ["stripe_id", "group-leader-plan"], ["description", "For leaders of single groups, with configuration."], ["sort_order", 2], ["created_at", "2015-05-23 23:17:29.346198"], ["updated_at", "2015-05-23 23:17:29.346198"]]
   (82.3ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Professional"], ["stripe_id", "professional-plan"], ["description", "Designed for professionals with unlimited groups, a calendar and configuration."], ["sort_order", 3], ["created_at", "2015-05-23 23:17:30.314525"], ["updated_at", "2015-05-23 23:17:30.314525"]]
   (86.4ms)  commit transaction
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Free"]]
  PaidUp::Plan Load (0.3ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "No Ads"]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Group Leader"]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Professional"]]
   (0.0ms)  begin transaction
  SQL (0.2ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "ad_free"], ["plan_id", 3], ["setting", 1]]
   (72.3ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "ad_free"], ["plan_id", 4], ["setting", 1]]
   (87.8ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "groups"], ["plan_id", 4], ["setting", 1]]
   (71.6ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "doodads"], ["plan_id", 4], ["setting", 5]]
   (79.7ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "ad_free"], ["plan_id", 5], ["setting", 1]]
   (72.0ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "groups"], ["plan_id", 5], ["setting", 999999999]]
   (81.7ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.1ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "doodads"], ["plan_id", 5], ["setting", 999999999]]
   (81.6ms)  commit transaction


Started GET "/users/sign_in" for 127.0.0.1 at 2015-05-24 17:23:21 -0600
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
Processing by Devise::SessionsController#new as HTML
  Rendered /home/work/Gems/paid_up/app/views/devise/sessions/_new_form.html.haml (25.1ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/registrations/_new_form.html.haml (4.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.8ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.8ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/shared/_links.html.haml (6.8ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/sessions/new.html.haml within layouts/application (346.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.6ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert.html.haml (1.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (3.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (2.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.8ms)
Completed 200 OK in 1233ms (Views: 698.7ms | ActiveRecord: 0.4ms)


Started GET "/assets/application.self-4b8455232921b510ace0cb80aa5c87e9a90226e2921e5b44bcd0304e0862f293.css?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/affix.self-68d1a5161d04ca9fe1b9d9f4114d9426c7798bf90f2703a97aca35c8113469bb.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/button.self-37c62bff1d75f86f3348b8679873d5156d8b9938b62841038dca21690f4740f1.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/jquery.self-d03a5518f45df77341bdbe6201ba3bfa547ebba8ed64f0ea56bfa5f96ea7c074.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/alert.self-15ce09eba576e56db3edfd87accc0ff48823df915169e350b4fd97290f96aee1.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/jquery_ujs.self-8e98a7a072a6cee1372d19fff9ff3e6aa1e39a37d89d6f06861637d061113ee7.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/carousel.self-9aaab1a477b9c1156bab751cb8da47f77dace6da88eef8ae830e60f3cff3a8be.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/collapse.self-eeece00cd06a3d7cc071ab7845b549d4991edd0f0895e4be70fe40bac2fb5f4b.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/dropdown.self-a3998e7ca949c04cb86b5c635deb0abcc7a24dc02e81be66b8acfef02d811e45.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/modal.self-f2759e138605770e60526c00c6d86cbb3378da203641f9d6b204c9f0192b9267.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/scrollspy.self-5ea180afe4404f83fc97d997833f2edefd34475b0b5ddab310e27abc2bbd5f2f.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/tab.self-e1bba7115c90301056ee94c4716de2fcbe4498015def2dab9ff9879f339bd245.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/transition.self-7742dca5e6acf313fbb217811b48468282cddf1a9baea5c89ec92e367ef242cb.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/tooltip.self-c3b5c16f394ab9c0391db4431aac4f2d2ddf1bba4c5d3228ed343de05ecc8e83.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap/popover.self-2674d99c3ab0415dba0b958a80b3840f70ff6368b155d890306c0291be49453b.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/bootstrap-sprockets.self-fbfa5ad7d9aa0afe439ec4ff3883acc4cb92b62cb67c40d674320c9aa1d4642d.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started GET "/assets/application.self-3b8dabdc891efe46b9a144b400ad69e37d7e5876bdc39dee783419a69d7ca819.js?body=1" for 127.0.0.1 at 2015-05-24 17:23:23 -0600


Started POST "/users/sign_in" for 127.0.0.1 at 2015-05-24 17:23:25 -0600
Processing by Devise::SessionsController#create as HTML
  Parameters: {"utf8"=>"✓", "authenticity_token"=>"XV4dDg4jsgWstI82oS2u8fpCcwpgch2cXz7gic79zrKT56TFwa1BwpwUTniQVpK9ndYVoUY3PHU7E1wCIxXhkA==", "user"=>{"email"=>"webmaster@dogtraininglog.com", "password"=>"[FILTERED]", "remember_me"=>"0"}, "commit"=>"Sign In"}
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."email" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["email", "webmaster@dogtraininglog.com"]]
Completed 401 Unauthorized in 10ms (ActiveRecord: 0.2ms)
Processing by Devise::SessionsController#new as HTML
  Parameters: {"utf8"=>"✓", "authenticity_token"=>"XV4dDg4jsgWstI82oS2u8fpCcwpgch2cXz7gic79zrKT56TFwa1BwpwUTniQVpK9ndYVoUY3PHU7E1wCIxXhkA==", "user"=>{"email"=>"webmaster@dogtraininglog.com", "password"=>"[FILTERED]", "remember_me"=>"0"}, "commit"=>"Sign In"}
  Rendered /home/work/Gems/paid_up/app/views/devise/sessions/_new_form.html.haml (2.8ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/registrations/_new_form.html.haml (2.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/shared/_links.html.haml (4.8ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/sessions/new.html.haml within layouts/application (12.6ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (1.4ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.4ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 650ms (Views: 119.6ms | ActiveRecord: 0.0ms)


Started POST "/users" for 127.0.0.1 at 2015-05-24 17:23:34 -0600
Processing by Devise::RegistrationsController#create as HTML
  Parameters: {"utf8"=>"✓", "authenticity_token"=>"P4w/nBBiaoP4YVIavQs1AIi5Agzo5j3ZWI0h3Y9e3yLxNYZX3+yZRMjBk1SMcAlM7y1kp86jHDA8oJ1WYrbwAA==", "user"=>{"email"=>"webmaster@dogtraininglog.com", "password"=>"[FILTERED]", "password_confirmation"=>"[FILTERED]"}, "commit"=>"Sign Up"}
   (0.2ms)  begin transaction
  User Exists (0.2ms)  SELECT  1 AS one FROM "users" WHERE "users"."email" = 'webmaster@dogtraininglog.com' LIMIT 1
  SQL (0.3ms)  INSERT INTO "users" ("email", "encrypted_password") VALUES (?, ?)  [["email", "webmaster@dogtraininglog.com"], ["encrypted_password", "$2a$10$y/KwrxPclX5WECQ9l/H8M.z7PR0AJpiNQbqWrd6uQ9wopQVzkLXsW"]]
   (81.9ms)  commit transaction
   (0.5ms)  begin transaction
  SQL (0.3ms)  UPDATE "users" SET "last_sign_in_at" = ?, "current_sign_in_at" = ?, "last_sign_in_ip" = ?, "current_sign_in_ip" = ?, "sign_in_count" = ? WHERE "users"."id" = ?  [["last_sign_in_at", "2015-05-24 23:23:34.863259"], ["current_sign_in_at", "2015-05-24 23:23:34.863259"], ["last_sign_in_ip", "127.0.0.1"], ["current_sign_in_ip", "127.0.0.1"], ["sign_in_count", 1], ["id", 1]]
   (78.1ms)  commit transaction
Redirected to http://localhost:3000/subscriptions
Completed 302 Found in 748ms (ActiveRecord: 161.4ms)


Started GET "/subscriptions" for 127.0.0.1 at 2015-05-24 17:23:34 -0600
Processing by PaidUp::SubscriptionsController#index as HTML
  User Load (0.5ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
   (0.1ms)  begin transaction
  SQL (0.2ms)  UPDATE "users" SET "stripe_id" = ? WHERE "users"."id" = ?  [["stripe_id", "cus_6Ijvl57atu2UFY"], ["id", 1]]
   (101.0ms)  commit transaction
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_definition_list.html.haml (1.7ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "ad_free"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (1.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
   (0.2ms)  SELECT COUNT(*) FROM "groups" INNER JOIN "roles" ON "roles".resource_type IN ('Group') AND
                                    ("roles".resource_id IS NULL OR "roles".resource_id = "groups".id) WHERE ("roles".name IN ('owner') AND "roles".resource_type IN ('Group')) AND ("roles".id IN (SELECT "roles".id FROM "roles" INNER JOIN "users_roles" ON "roles"."id" = "users_roles"."role_id" WHERE "users_roles"."user_id" = 1 AND "roles"."name" = 'owner') AND ((resource_id = "groups".id) OR (resource_id IS NULL)))
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
   (0.1ms)  SELECT COUNT(*) FROM "doodads" WHERE "doodads"."user_id" = ?  [["user_id", "1"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_abilities_table.html.haml (4728.7ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (1.6ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/index.html.haml within layouts/application (5445.7ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (1.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.0ms)
Completed 200 OK in 7782ms (Views: 5513.1ms | ActiveRecord: 104.4ms)


Started GET "/plans" for 127.0.0.1 at 2015-05-24 17:23:51 -0600
Processing by PaidUp::PlansController#index as HTML
  Parameters: {"authenticity_token"=>"yGHuXGfRU896G8dzcJTrFGVET9jOvDlWEA44F6RnXFUG2FeXqF+gCEq7Bj1B79dYAtApc+j5GL90I4ScSY9zdw=="}
  PaidUp::Plan Load (0.2ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0)  ORDER BY sort_order ASC
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.8ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0)  ORDER BY sort_order ASC
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
   (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.5ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.2ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 3], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
   (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (1.4ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 5], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 5], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 5], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 5], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 5], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 5], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_table.html.haml (4863.3ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/plans/index.html.haml within layouts/application (10531.4ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.0ms)
Completed 200 OK in 10604ms (Views: 10600.7ms | ActiveRecord: 2.8ms)


Started GET "/plans/4/subscriptions/new" for 127.0.0.1 at 2015-05-24 17:24:03 -0600
Processing by PaidUp::SubscriptionsController#new as HTML
  Parameters: {"authenticity_token"=>"65AuUY3mnTWrluV9M2xmqTtlD/pfHVNw1gd9IlIlM5UlKZeaQmhu8ps2JDMCF1rlXPFpUXlYcpmyKsGpv80ctw==", "plan_id"=>"4"}
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."id" = ?  ORDER BY sort_order ASC LIMIT 1  [["id", 4]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  PaidUp::Plan Load (0.3ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0) AND (id IN (4))  ORDER BY sort_order ASC
   (0.2ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_table.html.haml (318.8ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/new.html.haml within layouts/application (1607.8ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 3228ms (Views: 1709.8ms | ActiveRecord: 1.4ms)


Started POST "/plans/4/subscriptions" for 127.0.0.1 at 2015-05-24 17:24:25 -0600
Processing by PaidUp::SubscriptionsController#create as HTML
  Parameters: {"utf8"=>"✓", "authenticity_token"=>"Qzna8vo3viRhwkhUcs23tqiMAqt6e3f0ENTOylnTN42NgGM5NblN41FiiRpDtov6zxhkAFw+Vh10+XJBtDsYrw==", "plan_id"=>"4", "card"=>"stripeToken", "stripeToken"=>"tok_1668SDDz4VtlhGogK5gLTVnR"}
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."id" = ?  ORDER BY sort_order ASC LIMIT 1  [["id", 4]]
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT 1  [["id", 1]]
  CACHE (0.0ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
Redirected to http://localhost:3000/subscriptions
Completed 302 Found in 4382ms (ActiveRecord: 0.5ms)


Started GET "/subscriptions" for 127.0.0.1 at 2015-05-24 17:24:30 -0600
Processing by PaidUp::SubscriptionsController#index as HTML
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_definition_list.html.haml (0.2ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
   (0.2ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.3ms)  SELECT COUNT(*) FROM "groups" INNER JOIN "roles" ON "roles".resource_type IN ('Group') AND
                                    ("roles".resource_id IS NULL OR "roles".resource_id = "groups".id) WHERE ("roles".name IN ('owner') AND "roles".resource_type IN ('Group')) AND ("roles".id IN (SELECT "roles".id FROM "roles" INNER JOIN "users_roles" ON "roles"."id" = "users_roles"."role_id" WHERE "users_roles"."user_id" = 1 AND "roles"."name" = 'owner') AND ((resource_id = "groups".id) OR (resource_id IS NULL)))
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
   (0.1ms)  SELECT COUNT(*) FROM "doodads" WHERE "doodads"."user_id" = ?  [["user_id", "1"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "groups" INNER JOIN "roles" ON "roles".resource_type IN ('Group') AND
                                    ("roles".resource_id IS NULL OR "roles".resource_id = "groups".id) WHERE ("roles".name IN ('owner') AND "roles".resource_type IN ('Group')) AND ("roles".id IN (SELECT "roles".id FROM "roles" INNER JOIN "users_roles" ON "roles"."id" = "users_roles"."role_id" WHERE "users_roles"."user_id" = 1 AND "roles"."name" = 'owner') AND ((resource_id = "groups".id) OR (resource_id IS NULL)))
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_abilities_table.html.haml (4747.0ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/index.html.haml within layouts/application (5523.6ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (1.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.0ms)
Completed 200 OK in 6088ms (Views: 5603.4ms | ActiveRecord: 1.9ms)


Started GET "/subscriptions" for 127.0.0.1 at 2015-05-24 17:27:04 -0600
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
Processing by PaidUp::SubscriptionsController#index as HTML
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_definition_list.html.haml (1.7ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.2ms)  SELECT COUNT(*) FROM "groups" INNER JOIN "roles" ON "roles".resource_type IN ('Group') AND
                                    ("roles".resource_id IS NULL OR "roles".resource_id = "groups".id) WHERE ("roles".name IN ('owner') AND "roles".resource_type IN ('Group')) AND ("roles".id IN (SELECT "roles".id FROM "roles" INNER JOIN "users_roles" ON "roles"."id" = "users_roles"."role_id" WHERE "users_roles"."user_id" = 1 AND "roles"."name" = 'owner') AND ((resource_id = "groups".id) OR (resource_id IS NULL)))
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
   (0.2ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
   (0.1ms)  SELECT COUNT(*) FROM "doodads" WHERE "doodads"."user_id" = ?  [["user_id", "1"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.8ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "groups" INNER JOIN "roles" ON "roles".resource_type IN ('Group') AND
                                    ("roles".resource_id IS NULL OR "roles".resource_id = "groups".id) WHERE ("roles".name IN ('owner') AND "roles".resource_type IN ('Group')) AND ("roles".id IN (SELECT "roles".id FROM "roles" INNER JOIN "users_roles" ON "roles"."id" = "users_roles"."role_id" WHERE "users_roles"."user_id" = 1 AND "roles"."name" = 'owner') AND ((resource_id = "groups".id) OR (resource_id IS NULL)))
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_abilities_table.html.haml (3573.0ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (2.9ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/index.html.haml within layouts/application (4228.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.7ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (0.8ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.8ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.8ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (1.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.5ms)
Completed 200 OK in 5104ms (Views: 4433.8ms | ActiveRecord: 3.3ms)


Started GET "/assets/application.self-4b8455232921b510ace0cb80aa5c87e9a90226e2921e5b44bcd0304e0862f293.css?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/jquery.self-d03a5518f45df77341bdbe6201ba3bfa547ebba8ed64f0ea56bfa5f96ea7c074.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/alert.self-15ce09eba576e56db3edfd87accc0ff48823df915169e350b4fd97290f96aee1.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/jquery_ujs.self-8e98a7a072a6cee1372d19fff9ff3e6aa1e39a37d89d6f06861637d061113ee7.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/affix.self-68d1a5161d04ca9fe1b9d9f4114d9426c7798bf90f2703a97aca35c8113469bb.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/button.self-37c62bff1d75f86f3348b8679873d5156d8b9938b62841038dca21690f4740f1.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/carousel.self-9aaab1a477b9c1156bab751cb8da47f77dace6da88eef8ae830e60f3cff3a8be.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/collapse.self-eeece00cd06a3d7cc071ab7845b549d4991edd0f0895e4be70fe40bac2fb5f4b.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/dropdown.self-a3998e7ca949c04cb86b5c635deb0abcc7a24dc02e81be66b8acfef02d811e45.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/modal.self-f2759e138605770e60526c00c6d86cbb3378da203641f9d6b204c9f0192b9267.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/tab.self-e1bba7115c90301056ee94c4716de2fcbe4498015def2dab9ff9879f339bd245.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/scrollspy.self-5ea180afe4404f83fc97d997833f2edefd34475b0b5ddab310e27abc2bbd5f2f.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/transition.self-7742dca5e6acf313fbb217811b48468282cddf1a9baea5c89ec92e367ef242cb.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/tooltip.self-c3b5c16f394ab9c0391db4431aac4f2d2ddf1bba4c5d3228ed343de05ecc8e83.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap/popover.self-2674d99c3ab0415dba0b958a80b3840f70ff6368b155d890306c0291be49453b.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/bootstrap-sprockets.self-fbfa5ad7d9aa0afe439ec4ff3883acc4cb92b62cb67c40d674320c9aa1d4642d.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600


Started GET "/assets/application.self-3b8dabdc891efe46b9a144b400ad69e37d7e5876bdc39dee783419a69d7ca819.js?body=1" for 127.0.0.1 at 2015-05-24 17:27:09 -0600
   (137.6ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (0.2ms)  select sqlite_version(*)
   (97.8ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
  ActiveRecord::SchemaMigration Load (0.2ms)  SELECT "schema_migrations".* FROM "schema_migrations"
Migrating to CreateUsersTable (20150406154440)
   (0.0ms)  begin transaction
   (0.2ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar) 
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150406154440"]]
   (102.1ms)  commit transaction
Migrating to CreateGroupsTable (20150517175135)
   (0.2ms)  begin transaction
DEPRECATION WARNING: `#timestamp` was called without specifying an option for `null`. In Rails 5, this behavior will change to `null: false`. You should manually specify `null: true` to prevent the behavior of your existing migrations from changing. (called from block in change at /home/work/Gems/paid_up/spec/dummy/db/migrate/20150517175135_create_groups_table.rb:7)
   (0.6ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150517175135"]]
   (102.6ms)  commit transaction
Migrating to CreateDoodadsTable (20150517175136)
   (0.2ms)  begin transaction
   (0.5ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.3ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150517175136"]]
   (102.6ms)  commit transaction
Migrating to AddDeviseToUsers (20150523010827)
   (0.1ms)  begin transaction
   (0.3ms)  ALTER TABLE "users" ADD "email" varchar DEFAULT '' NOT NULL
   (0.1ms)  ALTER TABLE "users" ADD "encrypted_password" varchar DEFAULT '' NOT NULL
   (0.1ms)  ALTER TABLE "users" ADD "reset_password_token" varchar
   (0.1ms)  ALTER TABLE "users" ADD "reset_password_sent_at" datetime
   (0.1ms)  ALTER TABLE "users" ADD "remember_created_at" datetime
   (0.1ms)  ALTER TABLE "users" ADD "sign_in_count" integer DEFAULT 0 NOT NULL
   (0.1ms)  ALTER TABLE "users" ADD "current_sign_in_at" datetime
   (0.1ms)  ALTER TABLE "users" ADD "last_sign_in_at" datetime
   (0.1ms)  ALTER TABLE "users" ADD "current_sign_in_ip" varchar
   (0.4ms)  ALTER TABLE "users" ADD "last_sign_in_ip" varchar
   (0.4ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (0.3ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010827"]]
   (85.0ms)  commit transaction
Migrating to RolifyCreateRoles (20150523010837)
   (0.1ms)  begin transaction
DEPRECATION WARNING: `#timestamp` was called without specifying an option for `null`. In Rails 5, this behavior will change to `null: false`. You should manually specify `null: true` to prevent the behavior of your existing migrations from changing. (called from block in change at /home/work/Gems/paid_up/spec/dummy/db/migrate/20150523010837_rolify_create_roles.rb:7)
   (0.5ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (0.3ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (0.3ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name' AND type='index'

   (0.3ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.3ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010837"]]
   (115.4ms)  commit transaction
Migrating to CreatePaidUpFeaturesPlansTable (20150523010838)
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
Migrating to CreatePaidUpPlanFeatureSettingsTable (20150523010838)
   (0.1ms)  begin transaction
   (0.3ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer) 
   (0.0ms)  select sqlite_version(*)
   (0.1ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'

   (0.1ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
  SQL (0.1ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010838"]]
   (109.8ms)  commit transaction
Migrating to CreatePaidUpPlansTable (20150523010839)
   (0.2ms)  begin transaction
DEPRECATION WARNING: `#timestamp` was called without specifying an option for `null`. In Rails 5, this behavior will change to `null: false`. You should manually specify `null: true` to prevent the behavior of your existing migrations from changing. (called from block in change at /home/work/Gems/paid_up/spec/dummy/db/migrate/20150523010839_create_paid_up_plans_table.paid_up.rb:10)
   (0.6ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (0.4ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (0.3ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010839"]]
   (116.7ms)  commit transaction
Migrating to AddStripeIdColumnToUsers (20150523010840)
   (0.2ms)  begin transaction
   (0.7ms)  ALTER TABLE "users" ADD "stripe_id" varchar
  SQL (0.2ms)  INSERT INTO "schema_migrations" ("version") VALUES (?)  [["version", "20150523010840"]]
   (105.0ms)  commit transaction
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_doodads_on_user_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_doodads_on_user_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_plan_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_stripe_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_stripe_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_reset_password_token' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_reset_password_token' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (0.1ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_roles_on_user_id_and_role_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_roles_on_user_id_and_role_id' AND type='index'

   (77.3ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.2ms)  select sqlite_version(*)
   (89.5ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (99.0ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (106.6ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer) 
   (100.8ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.3ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (170.3ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (98.2ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (82.4ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (98.0ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (106.3ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (132.4ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (106.6ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (104.4ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (90.5ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (106.0ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (82.1ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (98.7ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (96.0ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (123.5ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.2ms)  SELECT version FROM "schema_migrations"
   (123.5ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (123.9ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (117.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (99.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (99.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (99.1ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (115.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (99.3ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
   (79.7ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.3ms)  select sqlite_version(*)
   (72.6ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (99.0ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (98.0ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer) 
   (98.9ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.3ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (122.6ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (92.0ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (82.4ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (73.6ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (361.9ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (73.4ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (89.7ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (98.4ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (106.3ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (106.5ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (98.2ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (98.8ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (98.9ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (83.6ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.1ms)  SELECT version FROM "schema_migrations"
   (107.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (107.4ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (91.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (107.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (91.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (107.1ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (107.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (91.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')
  ActiveRecord::SchemaMigration Load (0.2ms)  SELECT "schema_migrations".* FROM "schema_migrations"
   (0.2ms)  begin transaction
  SQL (0.5ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Anonymous"], ["stripe_id", "anonymous-plan"], ["description", "What you can do without logging in."], ["sort_order", -1], ["created_at", "2015-05-25 00:20:49.462108"], ["updated_at", "2015-05-25 00:20:49.462108"]]
   (110.9ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Free"], ["stripe_id", "free-plan"], ["description", "Can't beat the price!"], ["sort_order", 0], ["created_at", "2015-05-25 00:20:50.200206"], ["updated_at", "2015-05-25 00:20:50.200206"]]
   (90.6ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "No Ads"], ["stripe_id", "no-ads-plan"], ["description", "No frills, just removes the ads."], ["sort_order", 1], ["created_at", "2015-05-25 00:20:50.911412"], ["updated_at", "2015-05-25 00:20:50.911412"]]
   (88.4ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Group Leader"], ["stripe_id", "group-leader-plan"], ["description", "For leaders of single groups, with configuration."], ["sort_order", 2], ["created_at", "2015-05-25 00:20:51.575111"], ["updated_at", "2015-05-25 00:20:51.575111"]]
   (472.6ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plans" ("name", "stripe_id", "description", "sort_order", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?, ?)  [["name", "Professional"], ["stripe_id", "professional-plan"], ["description", "Designed for professionals with unlimited groups, a calendar and configuration."], ["sort_order", 3], ["created_at", "2015-05-25 00:20:52.704442"], ["updated_at", "2015-05-25 00:20:52.704442"]]
   (101.7ms)  commit transaction
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Free"]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "No Ads"]]
  PaidUp::Plan Load (0.3ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Group Leader"]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."name" = ?  ORDER BY sort_order ASC LIMIT 1  [["name", "Professional"]]
   (0.0ms)  begin transaction
  SQL (0.2ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "ad_free"], ["plan_id", 3], ["setting", 1]]
   (108.1ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "ad_free"], ["plan_id", 4], ["setting", 1]]
   (87.9ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "groups"], ["plan_id", 4], ["setting", 1]]
   (112.7ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.4ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "doodads"], ["plan_id", 4], ["setting", 5]]
   (120.8ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "ad_free"], ["plan_id", 5], ["setting", 1]]
   (146.2ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.2ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "groups"], ["plan_id", 5], ["setting", 999999999]]
   (121.9ms)  commit transaction
   (0.2ms)  begin transaction
  SQL (0.3ms)  INSERT INTO "paid_up_plan_feature_settings" ("feature", "plan_id", "setting") VALUES (?, ?, ?)  [["feature", "doodads"], ["plan_id", 5], ["setting", 999999999]]
   (112.5ms)  commit transaction
   (118.7ms)  CREATE TABLE "doodads" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "user_id" varchar, "name" varchar, "description" text) 
   (0.2ms)  select sqlite_version(*)
   (74.2ms)  CREATE  INDEX "index_doodads_on_user_id" ON "doodads" ("user_id")
   (106.4ms)  CREATE TABLE "groups" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "description" text, "created_at" datetime, "updated_at" datetime) 
   (106.5ms)  CREATE TABLE "paid_up_plan_feature_settings" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "plan_id" integer, "feature" varchar, "setting" integer) 
   (106.3ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_feature" ON "paid_up_plan_feature_settings" ("feature")
   (0.3ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plan_feature_settings_on_feature' AND type='index'

   (105.4ms)  CREATE  INDEX "index_paid_up_plan_feature_settings_on_plan_id" ON "paid_up_plan_feature_settings" ("plan_id")
   (106.1ms)  CREATE TABLE "paid_up_plans" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "stripe_id" varchar, "name" varchar, "description" text, "sort_order" integer, "created_at" datetime, "updated_at" datetime) 
   (106.2ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_name" ON "paid_up_plans" ("name")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_paid_up_plans_on_name' AND type='index'

   (97.3ms)  CREATE UNIQUE INDEX "index_paid_up_plans_on_stripe_id" ON "paid_up_plans" ("stripe_id")
   (81.3ms)  CREATE TABLE "roles" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "resource_id" integer, "resource_type" varchar, "created_at" datetime, "updated_at" datetime) 
   (98.9ms)  CREATE  INDEX "index_roles_on_name_and_resource_type_and_resource_id" ON "roles" ("name", "resource_type", "resource_id")
   (0.2ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_roles_on_name_and_resource_type_and_resource_id' AND type='index'

   (97.9ms)  CREATE  INDEX "index_roles_on_name" ON "roles" ("name")
   (119.3ms)  CREATE TABLE "users" ("id" INTEGER PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar, "email" varchar DEFAULT '' NOT NULL, "encrypted_password" varchar DEFAULT '' NOT NULL, "reset_password_token" varchar, "reset_password_sent_at" datetime, "remember_created_at" datetime, "sign_in_count" integer DEFAULT 0 NOT NULL, "current_sign_in_at" datetime, "last_sign_in_at" datetime, "current_sign_in_ip" varchar, "last_sign_in_ip" varchar, "stripe_id" varchar) 
   (98.9ms)  CREATE UNIQUE INDEX "index_users_on_email" ON "users" ("email")
   (0.3ms)              SELECT sql
            FROM sqlite_master
            WHERE name='index_users_on_email' AND type='index'
            UNION ALL
            SELECT sql
            FROM sqlite_temp_master
            WHERE name='index_users_on_email' AND type='index'

   (155.2ms)  CREATE UNIQUE INDEX "index_users_on_reset_password_token" ON "users" ("reset_password_token")
   (98.3ms)  CREATE TABLE "users_roles" ("user_id" integer, "role_id" integer) 
   (73.3ms)  CREATE  INDEX "index_users_roles_on_user_id_and_role_id" ON "users_roles" ("user_id", "role_id")
   (71.1ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL) 
   (81.6ms)  CREATE UNIQUE INDEX "unique_schema_migrations" ON "schema_migrations" ("version")
   (0.3ms)  SELECT version FROM "schema_migrations"
   (98.1ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010840')
   (82.8ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010838')
   (83.0ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010839')
   (99.3ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175136')
   (74.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150517175135')
   (83.1ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150406154440')
   (84.2ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010837')
   (82.6ms)  INSERT INTO "schema_migrations" (version) VALUES ('20150523010827')


Started GET "/subscriptions" for 127.0.0.1 at 2015-05-24 18:23:36 -0600
  ActiveRecord::SchemaMigration Load (0.1ms)  SELECT "schema_migrations".* FROM "schema_migrations"
Processing by PaidUp::SubscriptionsController#index as HTML
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
Completed 401 Unauthorized in 325ms (ActiveRecord: 0.6ms)


Started GET "/users/sign_in" for 127.0.0.1 at 2015-05-24 18:23:36 -0600
Processing by Devise::SessionsController#new as HTML
  Rendered /home/work/Gems/paid_up/app/views/devise/sessions/_new_form.html.haml (9.9ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/registrations/_new_form.html.haml (4.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.8ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.7ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/shared/_links.html.haml (6.8ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/sessions/new.html.haml within layouts/application (29.0ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.7ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert.html.haml (1.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (3.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (1.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.5ms)
Completed 200 OK in 576ms (Views: 209.3ms | ActiveRecord: 0.0ms)


Started GET "/assets/application.self-4b8455232921b510ace0cb80aa5c87e9a90226e2921e5b44bcd0304e0862f293.css?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/jquery.self-d03a5518f45df77341bdbe6201ba3bfa547ebba8ed64f0ea56bfa5f96ea7c074.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/alert.self-15ce09eba576e56db3edfd87accc0ff48823df915169e350b4fd97290f96aee1.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/affix.self-68d1a5161d04ca9fe1b9d9f4114d9426c7798bf90f2703a97aca35c8113469bb.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/carousel.self-9aaab1a477b9c1156bab751cb8da47f77dace6da88eef8ae830e60f3cff3a8be.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/button.self-37c62bff1d75f86f3348b8679873d5156d8b9938b62841038dca21690f4740f1.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/jquery_ujs.self-8e98a7a072a6cee1372d19fff9ff3e6aa1e39a37d89d6f06861637d061113ee7.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/collapse.self-eeece00cd06a3d7cc071ab7845b549d4991edd0f0895e4be70fe40bac2fb5f4b.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/dropdown.self-a3998e7ca949c04cb86b5c635deb0abcc7a24dc02e81be66b8acfef02d811e45.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/modal.self-f2759e138605770e60526c00c6d86cbb3378da203641f9d6b204c9f0192b9267.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/scrollspy.self-5ea180afe4404f83fc97d997833f2edefd34475b0b5ddab310e27abc2bbd5f2f.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/tab.self-e1bba7115c90301056ee94c4716de2fcbe4498015def2dab9ff9879f339bd245.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/transition.self-7742dca5e6acf313fbb217811b48468282cddf1a9baea5c89ec92e367ef242cb.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/tooltip.self-c3b5c16f394ab9c0391db4431aac4f2d2ddf1bba4c5d3228ed343de05ecc8e83.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap/popover.self-2674d99c3ab0415dba0b958a80b3840f70ff6368b155d890306c0291be49453b.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/bootstrap-sprockets.self-fbfa5ad7d9aa0afe439ec4ff3883acc4cb92b62cb67c40d674320c9aa1d4642d.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started GET "/assets/application.self-3b8dabdc891efe46b9a144b400ad69e37d7e5876bdc39dee783419a69d7ca819.js?body=1" for 127.0.0.1 at 2015-05-24 18:23:37 -0600


Started POST "/users/sign_in" for 127.0.0.1 at 2015-05-24 18:23:39 -0600
Processing by Devise::SessionsController#create as HTML
  Parameters: {"utf8"=>"✓", "authenticity_token"=>"QY+JBjh0+lHqtwaWrr0wm4neqBpNduWRSf5hFJ/asGmPNjDN9/oJltoXx9ifxgzX7krOsWszxHgt092fcjKfSw==", "user"=>{"email"=>"webmaster@dogtraininglog.com", "password"=>"[FILTERED]", "remember_me"=>"0"}, "commit"=>"Sign In"}
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."email" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["email", "webmaster@dogtraininglog.com"]]
Completed 401 Unauthorized in 3ms (ActiveRecord: 0.3ms)
Processing by Devise::SessionsController#new as HTML
  Parameters: {"utf8"=>"✓", "authenticity_token"=>"QY+JBjh0+lHqtwaWrr0wm4neqBpNduWRSf5hFJ/asGmPNjDN9/oJltoXx9ifxgzX7krOsWszxHgt092fcjKfSw==", "user"=>{"email"=>"webmaster@dogtraininglog.com", "password"=>"[FILTERED]", "remember_me"=>"0"}, "commit"=>"Sign In"}
  Rendered /home/work/Gems/paid_up/app/views/devise/sessions/_new_form.html.haml (3.2ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/registrations/_new_form.html.haml (1.7ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/shared/_links.html.haml (3.8ms)
  Rendered /home/work/Gems/paid_up/app/views/devise/sessions/new.html.haml within layouts/application (11.6ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.7ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (1.5ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.5ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.5ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.4ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 575ms (Views: 119.7ms | ActiveRecord: 0.0ms)


Started POST "/users" for 127.0.0.1 at 2015-05-24 18:23:46 -0600
Processing by Devise::RegistrationsController#create as HTML
  Parameters: {"utf8"=>"✓", "authenticity_token"=>"+XHjibjfLkD/7eDCAG9IW7WEQOn+68hu+qI5cf1Fr8w3yFpCd1Hdh89NIYwxFHQX0hAmQtiu6Yeej4X6EK2A7g==", "user"=>{"email"=>"webmaster@dogtraininglog.com", "password"=>"[FILTERED]", "password_confirmation"=>"[FILTERED]"}, "commit"=>"Sign Up"}
   (0.2ms)  begin transaction
  User Exists (0.4ms)  SELECT  1 AS one FROM "users" WHERE "users"."email" = 'webmaster@dogtraininglog.com' LIMIT 1
  SQL (0.2ms)  INSERT INTO "users" ("email", "encrypted_password") VALUES (?, ?)  [["email", "webmaster@dogtraininglog.com"], ["encrypted_password", "$2a$10$q2DYRIURLFdbbrhlowXF4eczlkSLocMIRWGlyX7QH35SHKhKi8mxK"]]
   (86.4ms)  commit transaction
   (0.1ms)  begin transaction
  SQL (0.3ms)  UPDATE "users" SET "last_sign_in_at" = ?, "current_sign_in_at" = ?, "last_sign_in_ip" = ?, "current_sign_in_ip" = ?, "sign_in_count" = ? WHERE "users"."id" = ?  [["last_sign_in_at", "2015-05-25 00:23:47.460623"], ["current_sign_in_at", "2015-05-25 00:23:47.460623"], ["last_sign_in_ip", "127.0.0.1"], ["current_sign_in_ip", "127.0.0.1"], ["sign_in_count", 1], ["id", 1]]
   (103.1ms)  commit transaction
Redirected to http://localhost:3000/subscriptions
Completed 302 Found in 631ms (ActiveRecord: 190.5ms)


Started GET "/subscriptions" for 127.0.0.1 at 2015-05-24 18:23:47 -0600
Processing by PaidUp::SubscriptionsController#index as HTML
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
   (0.1ms)  begin transaction
  SQL (0.3ms)  UPDATE "users" SET "stripe_id" = ? WHERE "users"."id" = ?  [["stripe_id", "cus_6IktnWpVYjVFzX"], ["id", 1]]
   (112.8ms)  commit transaction
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_definition_list.html.haml (3.2ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "ad_free"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (1.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
   (0.2ms)  SELECT COUNT(*) FROM "groups" INNER JOIN "roles" ON "roles".resource_type IN ('Group') AND
                                    ("roles".resource_id IS NULL OR "roles".resource_id = "groups".id) WHERE ("roles".name IN ('owner') AND "roles".resource_type IN ('Group')) AND ("roles".id IN (SELECT "roles".id FROM "roles" INNER JOIN "users_roles" ON "roles"."id" = "users_roles"."role_id" WHERE "users_roles"."user_id" = 1 AND "roles"."name" = 'owner') AND ((resource_id = "groups".id) OR (resource_id IS NULL)))
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
   (0.2ms)  SELECT COUNT(*) FROM "doodads" WHERE "doodads"."user_id" = ?  [["user_id", "1"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_abilities_table.html.haml (3983.8ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (1.2ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/index.html.haml within layouts/application (4809.6ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (1.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 7323ms (Views: 4882.2ms | ActiveRecord: 116.2ms)


Started GET "/plans" for 127.0.0.1 at 2015-05-24 18:23:58 -0600
Processing by PaidUp::PlansController#index as HTML
  Parameters: {"authenticity_token"=>"a0wws0ZbxyZLoKh7xAbC+810wzgngdLbi4MCiPNMnDml9Yl4idU04XsAaTX1ff63quClkwHE8zLvrr4DHqSzGw=="}
  PaidUp::Plan Load (0.2ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0)  ORDER BY sort_order ASC
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.5ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.5ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0)  ORDER BY sort_order ASC
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.5ms)
   (0.2ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.2ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 3], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.7ms)
   (0.3ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 5], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 5], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 5], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 5], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 5], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 5], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_table.html.haml (4571.5ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/plans/index.html.haml within layouts/application (9429.5ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 9506ms (Views: 9502.1ms | ActiveRecord: 3.1ms)


Started GET "/plans/4/subscriptions/new" for 127.0.0.1 at 2015-05-24 18:24:08 -0600
Processing by PaidUp::SubscriptionsController#new as HTML
  Parameters: {"authenticity_token"=>"QstvitTdx9WYhMMn/6CVBwi/DdVq5gi9dRGlVdojkwOMctZBG1M0EqgkAmnO26lLbytrfkyjKVQRPBneN8u8IQ==", "plan_id"=>"4"}
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."id" = ?  ORDER BY sort_order ASC LIMIT 1  [["id", 4]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  PaidUp::Plan Load (0.2ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0) AND (id IN (4))  ORDER BY sort_order ASC
   (0.2ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_table.html.haml (299.4ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/new.html.haml within layouts/application (1434.7ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.9ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 2759ms (Views: 1539.4ms | ActiveRecord: 1.2ms)


Started GET "/plans/4/subscriptions/new" for 127.0.0.1 at 2015-05-24 18:25:27 -0600
Processing by PaidUp::SubscriptionsController#new as HTML
  Parameters: {"authenticity_token"=>"QstvitTdx9WYhMMn/6CVBwi/DdVq5gi9dRGlVdojkwOMctZBG1M0EqgkAmnO26lLbytrfkyjKVQRPBneN8u8IQ==", "plan_id"=>"4"}
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."id" = ?  ORDER BY sort_order ASC LIMIT 1  [["id", 4]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  PaidUp::Plan Load (0.2ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0) AND (id IN (4))  ORDER BY sort_order ASC
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_table.html.haml (431.8ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/new.html.haml within layouts/application (1572.7ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 3142ms (Views: 1660.2ms | ActiveRecord: 1.3ms)


Started GET "/assets/application.self-4b8455232921b510ace0cb80aa5c87e9a90226e2921e5b44bcd0304e0862f293.css?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/button.self-37c62bff1d75f86f3348b8679873d5156d8b9938b62841038dca21690f4740f1.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/jquery.self-d03a5518f45df77341bdbe6201ba3bfa547ebba8ed64f0ea56bfa5f96ea7c074.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/alert.self-15ce09eba576e56db3edfd87accc0ff48823df915169e350b4fd97290f96aee1.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/affix.self-68d1a5161d04ca9fe1b9d9f4114d9426c7798bf90f2703a97aca35c8113469bb.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/jquery_ujs.self-8e98a7a072a6cee1372d19fff9ff3e6aa1e39a37d89d6f06861637d061113ee7.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/carousel.self-9aaab1a477b9c1156bab751cb8da47f77dace6da88eef8ae830e60f3cff3a8be.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/collapse.self-eeece00cd06a3d7cc071ab7845b549d4991edd0f0895e4be70fe40bac2fb5f4b.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/dropdown.self-a3998e7ca949c04cb86b5c635deb0abcc7a24dc02e81be66b8acfef02d811e45.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/modal.self-f2759e138605770e60526c00c6d86cbb3378da203641f9d6b204c9f0192b9267.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/scrollspy.self-5ea180afe4404f83fc97d997833f2edefd34475b0b5ddab310e27abc2bbd5f2f.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/tab.self-e1bba7115c90301056ee94c4716de2fcbe4498015def2dab9ff9879f339bd245.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/transition.self-7742dca5e6acf313fbb217811b48468282cddf1a9baea5c89ec92e367ef242cb.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/popover.self-2674d99c3ab0415dba0b958a80b3840f70ff6368b155d890306c0291be49453b.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap/tooltip.self-c3b5c16f394ab9c0391db4431aac4f2d2ddf1bba4c5d3228ed343de05ecc8e83.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/bootstrap-sprockets.self-fbfa5ad7d9aa0afe439ec4ff3883acc4cb92b62cb67c40d674320c9aa1d4642d.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/assets/application.self-3b8dabdc891efe46b9a144b400ad69e37d7e5876bdc39dee783419a69d7ca819.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:30 -0600


Started GET "/plans/4/subscriptions/new" for 127.0.0.1 at 2015-05-24 18:25:56 -0600
Processing by PaidUp::SubscriptionsController#new as HTML
  Parameters: {"authenticity_token"=>"QstvitTdx9WYhMMn/6CVBwi/DdVq5gi9dRGlVdojkwOMctZBG1M0EqgkAmnO26lLbytrfkyjKVQRPBneN8u8IQ==", "plan_id"=>"4"}
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."id" = ?  ORDER BY sort_order ASC LIMIT 1  [["id", 4]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "free-plan"]]
  PaidUp::Plan Load (0.2ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0) AND (id IN (4))  ORDER BY sort_order ASC
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_table.html.haml (365.6ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/new.html.haml within layouts/application (1602.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 3194ms (Views: 1672.6ms | ActiveRecord: 1.3ms)


Started GET "/assets/application.self-4b8455232921b510ace0cb80aa5c87e9a90226e2921e5b44bcd0304e0862f293.css?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/alert.self-15ce09eba576e56db3edfd87accc0ff48823df915169e350b4fd97290f96aee1.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/jquery_ujs.self-8e98a7a072a6cee1372d19fff9ff3e6aa1e39a37d89d6f06861637d061113ee7.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/affix.self-68d1a5161d04ca9fe1b9d9f4114d9426c7798bf90f2703a97aca35c8113469bb.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/jquery.self-d03a5518f45df77341bdbe6201ba3bfa547ebba8ed64f0ea56bfa5f96ea7c074.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/button.self-37c62bff1d75f86f3348b8679873d5156d8b9938b62841038dca21690f4740f1.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/carousel.self-9aaab1a477b9c1156bab751cb8da47f77dace6da88eef8ae830e60f3cff3a8be.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/collapse.self-eeece00cd06a3d7cc071ab7845b549d4991edd0f0895e4be70fe40bac2fb5f4b.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/dropdown.self-a3998e7ca949c04cb86b5c635deb0abcc7a24dc02e81be66b8acfef02d811e45.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/modal.self-f2759e138605770e60526c00c6d86cbb3378da203641f9d6b204c9f0192b9267.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/scrollspy.self-5ea180afe4404f83fc97d997833f2edefd34475b0b5ddab310e27abc2bbd5f2f.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/tab.self-e1bba7115c90301056ee94c4716de2fcbe4498015def2dab9ff9879f339bd245.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/transition.self-7742dca5e6acf313fbb217811b48468282cddf1a9baea5c89ec92e367ef242cb.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/tooltip.self-c3b5c16f394ab9c0391db4431aac4f2d2ddf1bba4c5d3228ed343de05ecc8e83.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap/popover.self-2674d99c3ab0415dba0b958a80b3840f70ff6368b155d890306c0291be49453b.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/bootstrap-sprockets.self-fbfa5ad7d9aa0afe439ec4ff3883acc4cb92b62cb67c40d674320c9aa1d4642d.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started GET "/assets/application.self-3b8dabdc891efe46b9a144b400ad69e37d7e5876bdc39dee783419a69d7ca819.js?body=1" for 127.0.0.1 at 2015-05-24 18:25:59 -0600


Started POST "/plans/4/subscriptions" for 127.0.0.1 at 2015-05-24 18:26:29 -0600
Processing by PaidUp::SubscriptionsController#create as HTML
  Parameters: {"utf8"=>"✓", "authenticity_token"=>"kCvYy6eoiMzLEPnJ7QIjiC8XJFw5jV9AIs5E4XhkCjRekmEAaCZ7C/uwOIfceR/ESINC9x/IfqlG4/hqlYwlFg==", "plan_id"=>"4", "card"=>"stripeToken", "stripeToken"=>"tok_1669QHDz4VtlhGog61rnfPba"}
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."id" = ?  ORDER BY sort_order ASC LIMIT 1  [["id", 4]]
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT 1  [["id", 1]]
  CACHE (0.0ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
Redirected to http://localhost:3000/subscriptions
Completed 302 Found in 4601ms (ActiveRecord: 0.5ms)


Started GET "/subscriptions" for 127.0.0.1 at 2015-05-24 18:26:34 -0600
Processing by PaidUp::SubscriptionsController#index as HTML
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_definition_list.html.haml (0.2ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.3ms)  SELECT COUNT(*) FROM "groups" INNER JOIN "roles" ON "roles".resource_type IN ('Group') AND
                                    ("roles".resource_id IS NULL OR "roles".resource_id = "groups".id) WHERE ("roles".name IN ('owner') AND "roles".resource_type IN ('Group')) AND ("roles".id IN (SELECT "roles".id FROM "roles" INNER JOIN "users_roles" ON "roles"."id" = "users_roles"."role_id" WHERE "users_roles"."user_id" = 1 AND "roles"."name" = 'owner') AND ((resource_id = "groups".id) OR (resource_id IS NULL)))
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
   (0.2ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
   (0.1ms)  SELECT COUNT(*) FROM "doodads" WHERE "doodads"."user_id" = ?  [["user_id", "1"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "groups" INNER JOIN "roles" ON "roles".resource_type IN ('Group') AND
                                    ("roles".resource_id IS NULL OR "roles".resource_id = "groups".id) WHERE ("roles".name IN ('owner') AND "roles".resource_type IN ('Group')) AND ("roles".id IN (SELECT "roles".id FROM "roles" INNER JOIN "users_roles" ON "roles"."id" = "users_roles"."role_id" WHERE "users_roles"."user_id" = 1 AND "roles"."name" = 'owner') AND ((resource_id = "groups".id) OR (resource_id IS NULL)))
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_abilities_table.html.haml (3717.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.5ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/index.html.haml within layouts/application (4438.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (1.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 4917ms (Views: 4504.8ms | ActiveRecord: 1.9ms)


Started GET "/plans" for 127.0.0.1 at 2015-05-24 18:26:45 -0600
Processing by PaidUp::PlansController#index as HTML
  Parameters: {"authenticity_token"=>"x2gkhAzXhguRCwbyUjIeySVWaelAE0rd/bZQvSH2UiwJ0Z1Pw1l1zKGrx7xjSSKFQsIPQmZWazSZm+w2zB59Dg=="}
  PaidUp::Plan Load (0.3ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0)  ORDER BY sort_order ASC
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.6ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT "paid_up_plans".* FROM "paid_up_plans" WHERE (sort_order >=  0)  ORDER BY sort_order ASC
   (0.2ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.3ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 2], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 3], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
   (0.2ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.5ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 4], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.3ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 5], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 5], ["feature", "ad_free"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 5], ["feature", "groups"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 5], ["feature", "groups"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 5], ["feature", "doodads"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 5], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.4ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_table.html.haml (5954.8ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/plans/index.html.haml within layouts/application (12749.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.1ms)
Completed 200 OK in 12820ms (Views: 12816.3ms | ActiveRecord: 2.9ms)


Started GET "/plans/3/subscriptions/new" for 127.0.0.1 at 2015-05-24 18:27:09 -0600
Processing by PaidUp::SubscriptionsController#new as HTML
  Parameters: {"authenticity_token"=>"bAvJ2bAHat9FRm5YsgNCk1h+lvKoN00tj9/n33vMTrSisnASf4mZGHXmrxaDeH7fP+rwWY5ybMTr8ltUliRhlg==", "plan_id"=>"3"}
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."id" = ?  ORDER BY sort_order ASC LIMIT 1  [["id", 3]]
  PaidUp::Plan Load (0.2ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "group-leader-plan"]]
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
Redirected to http://localhost:3000/subscriptions
Completed 302 Found in 2844ms (ActiveRecord: 0.8ms)


Started GET "/subscriptions" for 127.0.0.1 at 2015-05-24 18:27:12 -0600
Processing by PaidUp::SubscriptionsController#index as HTML
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ?  ORDER BY "users"."id" ASC LIMIT 1  [["id", 1]]
  PaidUp::Plan Load (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_definition_list.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "ad_free"]]
  PaidUp::PlanFeatureSetting Load (0.1ms)  SELECT  "paid_up_plan_feature_settings".* FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  ORDER BY "paid_up_plan_feature_settings"."id" ASC LIMIT 1  [["plan_id", 3], ["feature", "ad_free"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
   (0.2ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
   (0.1ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.1ms)
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
   (1.3ms)  SELECT COUNT(*) FROM "groups" INNER JOIN "roles" ON "roles".resource_type IN ('Group') AND
                                    ("roles".resource_id IS NULL OR "roles".resource_id = "groups".id) WHERE ("roles".name IN ('owner') AND "roles".resource_type IN ('Group')) AND ("roles".id IN (SELECT "roles".id FROM "roles" INNER JOIN "users_roles" ON "roles"."id" = "users_roles"."role_id" WHERE "users_roles"."user_id" = 1 AND "roles"."name" = 'owner') AND ((resource_id = "groups".id) OR (resource_id IS NULL)))
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "groups"]]
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon.html.haml (0.2ms)
  CACHE (0.1ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
   (0.1ms)  SELECT COUNT(*) FROM "doodads" WHERE "doodads"."user_id" = ?  [["user_id", "1"]]
  CACHE (0.0ms)  SELECT  "paid_up_plans".* FROM "paid_up_plans" WHERE "paid_up_plans"."stripe_id" = ?  ORDER BY sort_order ASC LIMIT 1  [["stripe_id", "no-ads-plan"]]
  CACHE (0.0ms)  SELECT COUNT(*) FROM "paid_up_plan_feature_settings" WHERE "paid_up_plan_feature_settings"."plan_id" = ? AND "paid_up_plan_feature_settings"."feature" = ?  [["plan_id", 3], ["feature", "doodads"]]
  Rendered /home/work/Gems/paid_up/app/views/paid_up/features/_abilities_table.html.haml (3793.0ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_icon_button_to.html.haml (0.5ms)
  Rendered /home/work/Gems/paid_up/app/views/paid_up/subscriptions/index.html.haml within layouts/application (4412.9ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_head_css.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_alert_flash_messages.html.haml (1.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_item.html.haml (0.1ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_nav_list.html.haml (0.3ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_widgets.html.haml (0.2ms)
  Rendered /home/work/.rvm/gems/ruby-2.2.1@paid_up/gems/bootstrap_leather-0.5.7/app/views/bootstrap_leather/_footer_javascript.html.haml (0.0ms)
Completed 200 OK in 4877ms (Views: 4544.0ms | ActiveRecord: 2.6ms)