(1.2ms)  SELECT sqlite_version(*)
   (1.0ms)  SELECT sqlite_version(*)
   (0.0ms)  SELECT sqlite_version(*)
   (0.1ms)  SELECT sqlite_version(*)
   (0.1ms)  DROP TABLE IF EXISTS "users"
   (0.8ms)  CREATE TABLE "users" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "email" varchar, "active" boolean DEFAULT 0 NOT NULL, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL)
   (0.8ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)
   (0.1ms)  SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC
   (0.7ms)  INSERT INTO "schema_migrations" (version) VALUES (20190912211120)
   (0.7ms)  CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL)
  ActiveRecord::InternalMetadata Load (0.2ms)  SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.0ms)  begin transaction
  ActiveRecord::InternalMetadata Create (0.3ms)  INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["key", "environment"], ["value", "test"], ["created_at", "2020-08-05 14:43:07.372534"], ["updated_at", "2020-08-05 14:43:07.372534"]]
   (0.4ms)  commit transaction
  ActiveRecord::InternalMetadata Load (0.1ms)  SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
  ActiveRecord::InternalMetadata Load (0.1ms)  SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "schema_sha1"], ["LIMIT", 1]]
   (0.0ms)  begin transaction
  ActiveRecord::InternalMetadata Create (0.2ms)  INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["key", "schema_sha1"], ["value", "2b01fa59c4c74162736a2cb624389712cfa71fdb"], ["created_at", "2020-08-05 14:43:07.375161"], ["updated_at", "2020-08-05 14:43:07.375161"]]
   (0.3ms)  commit transaction
   (0.1ms)  SELECT sqlite_version(*)
   (0.3ms)  SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC
   (0.1ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  SAVEPOINT active_record_1
  User Create (0.3ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 14:43:07"], ["updated_at", "2020-08-05 14:43:07"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  SAVEPOINT active_record_1
  User Create (0.3ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 14:43:07"], ["updated_at", "2020-08-05 14:43:07"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  User Create (0.3ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 14:43:07"], ["updated_at", "2020-08-05 14:43:07"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  SAVEPOINT active_record_1
  User Create (0.3ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 14:43:07"], ["updated_at", "2020-08-05 14:43:07"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  SAVEPOINT active_record_1
  User Create (0.2ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 14:43:07"], ["updated_at", "2020-08-05 14:43:07"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
   (0.2ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  SAVEPOINT active_record_1
  User Create (0.2ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 14:43:07"], ["updated_at", "2020-08-05 14:43:07"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
   (0.3ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (1.2ms)  SELECT sqlite_version(*)
   (0.9ms)  SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ?  [["key", "schema_sha1"]]
   (0.0ms)  SELECT sqlite_version(*)
   (0.4ms)  SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC
   (0.1ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.9ms)  SELECT sqlite_version(*)
   (0.1ms)  SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ?  [["key", "schema_sha1"]]
   (0.0ms)  SELECT sqlite_version(*)
   (0.1ms)  SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC
   (0.1ms)  begin transaction
   (0.0ms)  SAVEPOINT active_record_1
  User Create (1.8ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 16:02:08.135486"], ["updated_at", "2020-08-05 16:02:08.135486"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
Started GET "/hello_world" for 127.0.0.1 at 2020-08-05 12:02:09 -0400
Processing by ApplicationController#hello_world as HTML
  User Load (0.2ms)  SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ?  [["email", "test@nulogy.com"], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 3)
Completed 200 OK in 21ms (Views: 4.3ms | ActiveRecord: 0.2ms | Allocations: 3827)
   (0.3ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  User Create (0.3ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 16:02:09.947505"], ["updated_at", "2020-08-05 16:02:09.947505"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
Started GET "/hello_world" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by ApplicationController#hello_world as HTML
Redirected to http://127.0.0.1:57568/nulogy_sso/login
Filter chain halted as :authenticate_sso_user rendered or redirected
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 345)
Started GET "/nulogy_sso/login" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by NulogySSO::AuthenticationController#login as HTML
Redirected to http://localhost:1080/authorize?audience=mock_audience&client_id=mock_client_id&redirect_uri=mock_login_uri%3Forigin%3Dhttp%253A%252F%252F127.0.0.1%253A57568%252Fhello_world&response_type=code&scope=openid+email
Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 331)
   (0.3ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  User Create (0.3ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 16:02:10.383111"], ["updated_at", "2020-08-05 16:02:10.383111"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
Started GET "/hello_world" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by ApplicationController#hello_world as HTML
Redirected to http://127.0.0.1:57568/nulogy_sso/login
Filter chain halted as :authenticate_sso_user rendered or redirected
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 493)
Started GET "/nulogy_sso/login" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by NulogySSO::AuthenticationController#login as HTML
Redirected to http://localhost:1080/authorize?audience=mock_audience&client_id=mock_client_id&redirect_uri=mock_login_uri%3Forigin%3Dhttp%253A%252F%252F127.0.0.1%253A57568%252Fhello_world&response_type=code&scope=openid+email
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 462)
   (2.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  User Create (0.4ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 16:02:10.766596"], ["updated_at", "2020-08-05 16:02:10.766596"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
Started GET "/hello_world" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by ApplicationController#hello_world as HTML
Redirected to http://127.0.0.1:57568/nulogy_sso/login
Filter chain halted as :authenticate_sso_user rendered or redirected
Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 324)
Started GET "/nulogy_sso/login" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by NulogySSO::AuthenticationController#login as HTML
Redirected to http://localhost:1080/authorize?audience=mock_audience&client_id=mock_client_id&redirect_uri=mock_login_uri%3Forigin%3Dhttp%253A%252F%252F127.0.0.1%253A57568%252Fhello_world&response_type=code&scope=openid+email
Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 293)
Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F127.0.0.1%3A57568%2Fhello_world" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML
  Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://127.0.0.1:57568/hello_world"}
  User Load (0.1ms)  SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ?  [["email", "test@nulogy.com"], ["LIMIT", 1]]
Redirected to http://127.0.0.1:57568/hello_world
Completed 302 Found in 13ms (ActiveRecord: 0.1ms | Allocations: 3031)
Started GET "/hello_world" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by ApplicationController#hello_world as HTML
  User Load (0.1ms)  SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ?  [["email", "test@nulogy.com"], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 717)
   (0.4ms)  rollback transaction
   (0.0ms)  begin transaction
Started GET "/hello_world" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by ApplicationController#hello_world as HTML
Redirected to http://127.0.0.1:57568/nulogy_sso/login
Filter chain halted as :authenticate_sso_user rendered or redirected
Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 324)
Started GET "/nulogy_sso/login" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by NulogySSO::AuthenticationController#login as HTML
Redirected to http://localhost:1080/authorize?audience=mock_audience&client_id=mock_client_id&redirect_uri=mock_login_uri%3Forigin%3Dhttp%253A%252F%252F127.0.0.1%253A57568%252Fhello_world&response_type=code&scope=openid+email
Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 297)
Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F127.0.0.1%3A57568%2Fhello_world" for 127.0.0.1 at 2020-08-05 12:02:10 -0400
Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML
  Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://127.0.0.1:57568/hello_world"}
  User Load (0.1ms)  SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ?  [["email", "test@nulogy.com"], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 12ms (Views: 0.4ms | ActiveRecord: 0.1ms | Allocations: 1876)
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  SAVEPOINT active_record_1
  User Create (0.3ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 16:02:11.182823"], ["updated_at", "2020-08-05 16:02:11.182823"]]
   (0.1ms)  RELEASE SAVEPOINT active_record_1
Started GET "/hello_world" for 127.0.0.1 at 2020-08-05 12:02:11 -0400
Processing by ApplicationController#hello_world as HTML
Redirected to http://127.0.0.1:57568/nulogy_sso/login
Filter chain halted as :authenticate_sso_user rendered or redirected
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 324)
Started GET "/nulogy_sso/login" for 127.0.0.1 at 2020-08-05 12:02:11 -0400
Processing by NulogySSO::AuthenticationController#login as HTML
Redirected to http://localhost:1080/authorize?audience=mock_audience&client_id=mock_client_id&redirect_uri=mock_login_uri%3Forigin%3Dhttp%253A%252F%252F127.0.0.1%253A57568%252Fhello_world&response_type=code&scope=openid+email
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 293)
Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F127.0.0.1%3A57568%2Fhello_world" for 127.0.0.1 at 2020-08-05 12:02:11 -0400
Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML
  Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://127.0.0.1:57568/hello_world"}
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 10ms (Views: 0.4ms | ActiveRecord: 0.0ms | Allocations: 1400)
   (0.3ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.0ms)  SAVEPOINT active_record_1
  User Create (0.2ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 16:02:11.382509"], ["updated_at", "2020-08-05 16:02:11.382509"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
Started GET "/api_endpoint" for 127.0.0.1 at 2020-08-05 12:02:11 -0400
Processing by ApiController#api_endpoint as HTML
Redirected to http://127.0.0.1:57568/nulogy_sso/login
Filter chain halted as :authenticate_sso_user rendered or redirected
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 357)
Started GET "/nulogy_sso/login" for 127.0.0.1 at 2020-08-05 12:02:11 -0400
Processing by NulogySSO::AuthenticationController#login as HTML
Redirected to http://localhost:1080/authorize?audience=mock_audience&client_id=mock_client_id&redirect_uri=mock_login_uri%3Forigin%3Dhttp%253A%252F%252F127.0.0.1%253A57568%252Fapi_endpoint&response_type=code&scope=openid+email
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 298)
   (0.3ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  SAVEPOINT active_record_1
  User Create (0.3ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 16:02:11.685272"], ["updated_at", "2020-08-05 16:02:11.685272"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
Started GET "/api_endpoint" for 127.0.0.1 at 2020-08-05 12:02:11 -0400
Processing by ApiController#api_endpoint as HTML
Redirected to http://127.0.0.1:57568/nulogy_sso/login
Filter chain halted as :authenticate_sso_user rendered or redirected
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 484)
Started GET "/nulogy_sso/login" for 127.0.0.1 at 2020-08-05 12:02:11 -0400
Processing by NulogySSO::AuthenticationController#login as HTML
Redirected to http://localhost:1080/authorize?audience=mock_audience&client_id=mock_client_id&redirect_uri=mock_login_uri%3Forigin%3Dhttp%253A%252F%252F127.0.0.1%253A57568%252Fapi_endpoint&response_type=code&scope=openid+email
Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 456)
   (0.3ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  SAVEPOINT active_record_1
  User Create (0.3ms)  INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?)  [["email", "test@nulogy.com"], ["created_at", "2020-08-05 16:02:11.949245"], ["updated_at", "2020-08-05 16:02:11.949245"]]
   (0.0ms)  RELEASE SAVEPOINT active_record_1
Started GET "/api_endpoint" for 127.0.0.1 at 2020-08-05 12:02:12 -0400
Processing by ApiController#api_endpoint as HTML
  User Load (0.1ms)  SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ?  [["email", "test@nulogy.com"], ["LIMIT", 1]]
Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.1ms | Allocations: 601)
   (0.3ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.1ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.0ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction
   (0.0ms)  begin transaction
   (0.1ms)  rollback transaction