(2.0ms) SELECT sqlite_version(*)  (0.7ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.0ms) SELECT sqlite_version(*)  (0.3ms) 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.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.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.0ms) SAVEPOINT active_record_1 User Create (1.0ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 21:52:51.882366"], ["updated_at", "2021-04-08 21:52:51.882366"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-08 17:52:56 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:61560/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 71ms (ActiveRecord: 0.0ms | Allocations: 2367) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 17:52:56 -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%252F192.168.0.189%253A61560%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 519)  (0.4ms) rollback transaction  (0.1ms) 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", "2021-04-08 21:52:57.016543"], ["updated_at", "2021-04-08 21:52:57.016543"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-08 17:52:57 -0400 Processing by ApiController#api_endpoint as HTML User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Completed 200 OK in 2ms (Views: 0.2ms | ActiveRecord: 0.2ms | Allocations: 828)  (1.0ms) rollback transaction  (0.1ms) 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", "2021-04-08 21:52:57.767823"], ["updated_at", "2021-04-08 21:52:57.767823"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-08 17:52:57 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:61560/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 315) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 17:52:57 -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%252F192.168.0.189%253A61560%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 2ms (ActiveRecord: 0.0ms | Allocations: 291)  (0.5ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 User Create (0.6ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 21:52:58.692265"], ["updated_at", "2021-04-08 21:52:58.692265"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 17:52:58 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61560/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 372) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 17:52:58 -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%252F192.168.0.189%253A61560%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%2F192.168.0.189%3A61560%2Fhello_world" for 192.168.0.189 at 2021-04-08 17:52:58 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:61560/hello_world"} User Load (1.2ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Redirected to http://192.168.0.189:61560/hello_world Completed 302 Found in 42ms (ActiveRecord: 1.2ms | Allocations: 3091) Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 17:52:58 -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: 3) Completed 200 OK in 12ms (Views: 10.1ms | ActiveRecord: 0.1ms | Allocations: 1845)  (1.0ms) rollback transaction  (0.0ms) begin transaction Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 17:52:59 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61560/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 192.168.0.189 at 2021-04-08 17:52:59 -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%252F192.168.0.189%253A61560%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 291) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A61560%2Fhello_world" for 192.168.0.189 at 2021-04-08 17:52:59 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:61560/hello_world"} 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: 1) Completed 200 OK in 19ms (Views: 0.8ms | ActiveRecord: 0.2ms | Allocations: 1924)  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 21:53:00.008240"], ["updated_at", "2021-04-08 21:53:00.008240"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 17:53:00 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61560/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 192.168.0.189 at 2021-04-08 17:53:00 -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%252F192.168.0.189%253A61560%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 295) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A61560%2Fhello_world" for 192.168.0.189 at 2021-04-08 17:53:00 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:61560/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: 1438)  (1.0ms) 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", "2021-04-08 21:53:00.467454"], ["updated_at", "2021-04-08 21:53:00.467454"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 17:53:00 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61560/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 492) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 17:53:00 -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%252F192.168.0.189%253A61560%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 454)  (0.4ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 21:53:01.136836"], ["updated_at", "2021-04-08 21:53:01.136836"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 17:53:01 -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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms | Allocations: 602)  (0.6ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 21:53:01.792877"], ["updated_at", "2021-04-08 21:53:01.792877"]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 17:53:01 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61560/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 192.168.0.189 at 2021-04-08 17:53:01 -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%252F192.168.0.189%253A61560%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 287)  (1.1ms) rollback transaction  (0.1ms) 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  (2.3ms) SELECT sqlite_version(*)  (0.6ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC  (1.9ms) SELECT sqlite_version(*)  (0.7ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) SAVEPOINT active_record_1 User Create (0.9ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 22:00:03"], ["updated_at", "2021-04-08 22:00:03"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:61822/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 7ms (ActiveRecord: 0.0ms | Allocations: 334) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:00:03 -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%252F192.168.0.189%253A61822%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 347) TRANSACTION (0.4ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-08 22:00:03"], ["updated_at", "2021-04-08 22:00:03"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by ApiController#api_endpoint as HTML User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Completed 200 OK in 18ms (Views: 0.2ms | ActiveRecord: 0.2ms | Allocations: 2586) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-08 22:00:03"], ["updated_at", "2021-04-08 22:00:03"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:61822/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 2ms (ActiveRecord: 0.0ms | Allocations: 394) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:00:03 -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%252F192.168.0.189%253A61822%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 447) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-08 22:00:03"], ["updated_at", "2021-04-08 22:00:03"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61822/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 2ms (ActiveRecord: 0.0ms | Allocations: 290) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:00:03 -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%252F192.168.0.189%253A61822%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 297) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A61822%2Fhello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:61822/hello_world"} Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 3) Completed 200 OK in 44ms (Views: 6.3ms | ActiveRecord: 0.0ms | Allocations: 3790) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.6ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 22:00:03"], ["updated_at", "2021-04-08 22:00:03"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61822/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 242) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:00:03 -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%252F192.168.0.189%253A61822%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 292) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A61822%2Fhello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:61822/hello_world"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Redirected to http://192.168.0.189:61822/hello_world Completed 302 Found in 17ms (ActiveRecord: 0.1ms | Allocations: 1844) Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -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 2ms (Views: 0.7ms | ActiveRecord: 0.1ms | Allocations: 824) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61822/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 242) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:00:03 -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%252F192.168.0.189%253A61822%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 2ms (ActiveRecord: 0.0ms | Allocations: 293) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A61822%2Fhello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:61822/hello_world"} 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: 1) Completed 200 OK in 12ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 1731) TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.9ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 22:00:03"], ["updated_at", "2021-04-08 22:00:03"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61822/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 242) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:00:03 -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%252F192.168.0.189%253A61822%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 291) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-08 22:00:03"], ["updated_at", "2021-04-08 22:00:03"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:61822/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 404) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:00:03 -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%252F192.168.0.189%253A61822%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 443) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-08 22:00:03"], ["updated_at", "2021-04-08 22:00:03"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:00:03 -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.3ms | ActiveRecord: 0.1ms | Allocations: 586) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction  (2.0ms) SELECT sqlite_version(*)  (0.6ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.2ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (2.0ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 22:14:24.503071"], ["updated_at", "2021-04-08 22:14:24.503071"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:14:25 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:62208/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 7ms (ActiveRecord: 0.0ms | Allocations: 365) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:14:25 -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%252F192.168.0.189%253A62208%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 333) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A62208%2Fhello_world" for 192.168.0.189 at 2021-04-08 18:14:25 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:62208/hello_world"} Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 3) Completed 200 OK in 42ms (Views: 22.2ms | ActiveRecord: 0.0ms | Allocations: 3789) TRANSACTION (0.5ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-08 22:14:26.191298"], ["updated_at", "2021-04-08 22:14:26.191298"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:14:26 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:62208/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 238) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:14:26 -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%252F192.168.0.189%253A62208%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 292) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A62208%2Fhello_world" for 192.168.0.189 at 2021-04-08 18:14:26 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:62208/hello_world"} User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Redirected to http://192.168.0.189:62208/hello_world Completed 302 Found in 40ms (ActiveRecord: 0.3ms | Allocations: 3827) Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:14:26 -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 6ms (Views: 1.8ms | ActiveRecord: 0.1ms | Allocations: 824) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:14:26 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:62208/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 238) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:14:26 -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%252F192.168.0.189%253A62208%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 285) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A62208%2Fhello_world" for 192.168.0.189 at 2021-04-08 18:14:26 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:62208/hello_world"} 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: 1) Completed 200 OK in 19ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 1721) TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 22:14:27.201472"], ["updated_at", "2021-04-08 22:14:27.201472"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-08 18:14:27 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:62208/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 429) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:14:27 -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%252F192.168.0.189%253A62208%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 452) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.9ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 22:14:27.912687"], ["updated_at", "2021-04-08 22:14:27.912687"]] TRANSACTION (1.4ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-08 18:14:28 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:62208/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 229) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:14:28 -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%252F192.168.0.189%253A62208%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 283) TRANSACTION (0.7ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (1.2ms) SAVEPOINT active_record_1 User Create (0.4ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 22:14:28.543588"], ["updated_at", "2021-04-08 22:14:28.543588"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-08 18:14:28 -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 2ms (Views: 0.6ms | ActiveRecord: 0.1ms | Allocations: 574) TRANSACTION (0.7ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-08 22:14:29.163092"], ["updated_at", "2021-04-08 22:14:29.163092"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:14:29 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:62208/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 398) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:14:29 -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%252F192.168.0.189%253A62208%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 446) TRANSACTION (0.7ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.9ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-08 22:14:29.853691"], ["updated_at", "2021-04-08 22:14:29.853691"]] TRANSACTION (0.2ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:14:30 -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 2ms (Views: 0.4ms | ActiveRecord: 0.1ms | Allocations: 578) TRANSACTION (1.5ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-08 22:14:30.495965"], ["updated_at", "2021-04-08 22:14:30.495965"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-08 18:14:30 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:62208/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 238) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-08 18:14:30 -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%252F192.168.0.189%253A62208%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 293) TRANSACTION (0.5ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction  (1.7ms) SELECT sqlite_version(*)  (0.6ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC  (1.9ms) SELECT sqlite_version(*)  (0.7ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.4ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC  (1.7ms) SELECT sqlite_version(*)  (0.2ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.2ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC  (1.5ms) 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 TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (1.0ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:42:22.660877"], ["updated_at", "2021-04-13 19:42:22.660877"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.4ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-13 19:42:24.455025"], ["updated_at", "2021-04-13 19:42:24.455025"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.8ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-13 19:42:24.560725"], ["updated_at", "2021-04-13 19:42:24.560725"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-13 19:42:24.620909"], ["updated_at", "2021-04-13 19:42:24.620909"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) SAVEPOINT active_record_1 User Create (0.4ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:42:24.681661"], ["updated_at", "2021-04-13 19:42:24.681661"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-13 19:42:24.737665"], ["updated_at", "2021-04-13 19:42:24.737665"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction  (1.9ms) SELECT sqlite_version(*)  (0.7ms) 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 TRANSACTION (0.3ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) SAVEPOINT active_record_1 User Create (0.9ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:45:10"], ["updated_at", "2021-04-13 19:45:10"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.6ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.7ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:45:10"], ["updated_at", "2021-04-13 19:45:10"]] TRANSACTION (0.5ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-13 19:45:10"], ["updated_at", "2021-04-13 19:45:10"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.2ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:45:10"], ["updated_at", "2021-04-13 19:45:10"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.5ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:45:10"], ["updated_at", "2021-04-13 19:45:10"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.0ms) rollback transaction TRANSACTION (7.5ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:45:10"], ["updated_at", "2021-04-13 19:45:10"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.2ms) SAVEPOINT active_record_1 User Create (1.0ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:45:10"], ["updated_at", "2021-04-13 19:45:10"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:45:10"], ["updated_at", "2021-04-13 19:45:10"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.7ms) rollback transaction  (2.1ms) SELECT sqlite_version(*)  (0.6ms) 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 TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.8ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:50:54"], ["updated_at", "2021-04-13 19:50:54"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.4ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.7ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:50:54"], ["updated_at", "2021-04-13 19:50:54"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-13 19:50:54"], ["updated_at", "2021-04-13 19:50:54"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-13 19:50:54"], ["updated_at", "2021-04-13 19:50:54"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.6ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-13 19:50:54"], ["updated_at", "2021-04-13 19:50:54"]] TRANSACTION (0.6ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.5ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-13 19:50:54"], ["updated_at", "2021-04-13 19:50:54"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.6ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-13 19:50:54"], ["updated_at", "2021-04-13 19:50:54"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.4ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-13 19:50:54"], ["updated_at", "2021-04-13 19:50:54"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.5ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction  (1.4ms) SELECT sqlite_version(*)  (0.2ms) 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 TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.6ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:51:12.004040"], ["updated_at", "2021-04-13 19:51:12.004040"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (1.0ms) rollback transaction  (1.5ms) 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 TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:53:01.481788"], ["updated_at", "2021-04-13 19:53:01.481788"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.5ms) rollback transaction  (1.5ms) 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 TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.7ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-13 19:53:56.791075"], ["updated_at", "2021-04-13 19:53:56.791075"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (2.3ms) rollback transaction  (2.2ms) SELECT sqlite_version(*)  (0.6ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.4ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (2.6ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:44:57.535531"], ["updated_at", "2021-04-14 15:44:57.535531"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:44:59 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63670/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 5ms (ActiveRecord: 0.0ms | Allocations: 687) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:44:59 -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%252F192.168.0.189%253A63670%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 487) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A63670%2Fhello_world" for 192.168.0.189 at 2021-04-14 11:45:00 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:63670/hello_world"} Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 8) Completed 200 OK in 44ms (Views: 5.3ms | ActiveRecord: 0.0ms | Allocations: 4515) TRANSACTION (0.9ms) rollback transaction TRANSACTION (0.0ms) begin transaction Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:45:00 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63670/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 272) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:45:00 -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%252F192.168.0.189%253A63670%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 298) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A63670%2Fhello_world" for 192.168.0.189 at 2021-04-14 11:45:00 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:63670/hello_world"} 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: 1) Completed 200 OK in 44ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 4574) TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:45:01.266191"], ["updated_at", "2021-04-14 15:45:01.266191"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:45:07 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63670/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 272) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:45:07 -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%252F192.168.0.189%253A63670%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 303) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A63670%2Fhello_world" for 192.168.0.189 at 2021-04-14 11:45:07 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:63670/hello_world"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Redirected to http://192.168.0.189:63670/hello_world Completed 302 Found in 21ms (ActiveRecord: 0.1ms | Allocations: 1960) Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:45:07 -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: 2) Completed 200 OK in 2ms (Views: 0.9ms | ActiveRecord: 0.1ms | Allocations: 931) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:45:07.646169"], ["updated_at", "2021-04-14 15:45:07.646169"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:45:07 -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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms | Allocations: 677) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:45:08.216837"], ["updated_at", "2021-04-14 15:45:08.216837"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:45:08 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63670/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 271) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:45:08 -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%252F192.168.0.189%253A63670%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 297) TRANSACTION (1.2ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:45:08.849331"], ["updated_at", "2021-04-14 15:45:08.849331"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:45:09 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63670/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 455) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:45:09 -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%252F192.168.0.189%253A63670%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 445) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) SAVEPOINT active_record_1 User Create (0.4ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:45:09.536658"], ["updated_at", "2021-04-14 15:45:09.536658"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-14 11:45:09 -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 2ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 858) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:45:10.096594"], ["updated_at", "2021-04-14 15:45:10.096594"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-14 11:45:10 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:63670/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 274) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:45: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%252F192.168.0.189%253A63670%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 308) TRANSACTION (1.2ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.8ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:45:10.732018"], ["updated_at", "2021-04-14 15:45:10.732018"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-14 11:45:10 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:63670/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 446) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:45: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%252F192.168.0.189%253A63670%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 438) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction  (1.9ms) SELECT sqlite_version(*)  (0.5ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) SAVEPOINT active_record_1 User Create (0.8ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:46:16"], ["updated_at", "2021-04-14 15:46:16"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by ApiController#api_endpoint as HTML User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Completed 200 OK in 24ms (Views: 0.3ms | ActiveRecord: 0.2ms | Allocations: 7252) TRANSACTION (0.4ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-14 15:46:16"], ["updated_at", "2021-04-14 15:46:16"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:63850/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 391) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:46:16 -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%252F192.168.0.189%253A63850%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 496) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-14 15:46:16"], ["updated_at", "2021-04-14 15:46:16"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:63850/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 453) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:46:16 -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%252F192.168.0.189%253A63850%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 448) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63850/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 363) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:46:16 -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%252F192.168.0.189%253A63850%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 314) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A63850%2Fhello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:63850/hello_world"} 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: 8) Completed 200 OK in 29ms (Views: 4.9ms | ActiveRecord: 0.2ms | Allocations: 4363) TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:46:16"], ["updated_at", "2021-04-14 15:46:16"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63850/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 275) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:46:16 -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%252F192.168.0.189%253A63850%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 299) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A63850%2Fhello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:63850/hello_world"} Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 12ms (Views: 0.4ms | ActiveRecord: 0.0ms | Allocations: 1501) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.9ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:46:16"], ["updated_at", "2021-04-14 15:46:16"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63850/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 275) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:46:16 -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%252F192.168.0.189%253A63850%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 304) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A63850%2Fhello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:63850/hello_world"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Redirected to http://192.168.0.189:63850/hello_world Completed 302 Found in 11ms (ActiveRecord: 0.1ms | Allocations: 1852) Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -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: 2) Completed 200 OK in 2ms (Views: 0.8ms | ActiveRecord: 0.1ms | Allocations: 931) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-14 15:46:16"], ["updated_at", "2021-04-14 15:46:16"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63850/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 275) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:46:16 -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%252F192.168.0.189%253A63850%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 312) TRANSACTION (1.2ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-14 15:46:16"], ["updated_at", "2021-04-14 15:46:16"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms | Allocations: 683) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:46:16"], ["updated_at", "2021-04-14 15:46:16"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:46:16 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:63850/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 459) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:46:16 -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%252F192.168.0.189%253A63850%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 448) TRANSACTION (1.1ms) rollback transaction  (1.6ms) SELECT sqlite_version(*)  (0.2ms) 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 TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:47:05"], ["updated_at", "2021-04-14 15:47:05"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:64015/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 4ms (ActiveRecord: 0.0ms | Allocations: 689) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:47:05 -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%252F192.168.0.189%253A64015%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 498) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-14 15:47:05"], ["updated_at", "2021-04-14 15:47:05"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -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: 8) Completed 200 OK in 20ms (Views: 4.5ms | ActiveRecord: 0.2ms | Allocations: 4653) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:47:05"], ["updated_at", "2021-04-14 15:47:05"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:64015/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 463) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:47:05 -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%252F192.168.0.189%253A64015%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 455) TRANSACTION (1.2ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:47:05"], ["updated_at", "2021-04-14 15:47:05"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:64015/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 12ms (ActiveRecord: 0.0ms | Allocations: 275) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:47:05 -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%252F192.168.0.189%253A64015%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 303) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A64015%2Fhello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:64015/hello_world"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Redirected to http://192.168.0.189:64015/hello_world Completed 302 Found in 16ms (ActiveRecord: 0.1ms | Allocations: 3367) Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms | Allocations: 730) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:64015/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 276) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:47:05 -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%252F192.168.0.189%253A64015%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 306) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A64015%2Fhello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:64015/hello_world"} User Load (0.3ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? LIMIT ? [["email", "test@nulogy.com"], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 2) Completed 200 OK in 20ms (Views: 2.0ms | ActiveRecord: 0.3ms | Allocations: 2070) TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.5ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:47:05"], ["updated_at", "2021-04-14 15:47:05"]] TRANSACTION (0.1ms) RELEASE SAVEPOINT active_record_1 Started GET "/hello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by ApplicationController#hello_world as HTML Redirected to http://192.168.0.189:64015/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 275) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:47:05 -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%252F192.168.0.189%253A64015%252Fhello_world&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 306) Started GET "/nulogy_sso/verify_authentication_code?code=FAKE_CODE&origin=http%3A%2F%2F192.168.0.189%3A64015%2Fhello_world" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by NulogySSO::AuthenticationController#verify_authentication_code as HTML Parameters: {"code"=>"FAKE_CODE", "origin"=>"http://192.168.0.189:64015/hello_world"} Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 11ms (Views: 0.5ms | ActiveRecord: 0.0ms | Allocations: 1490) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-14 15:47:05"], ["updated_at", "2021-04-14 15:47:05"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:64015/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 532) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:47:05 -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%252F192.168.0.189%253A64015%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 450) TRANSACTION (1.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (0.6ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-14 15:47:05"], ["updated_at", "2021-04-14 15:47:05"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-14 11:47:05 -0400 Processing by ApiController#api_endpoint as HTML Redirected to http://192.168.0.189:64015/nulogy_sso/login Filter chain halted as :authenticate_sso_user rendered or redirected Completed 302 Found in 1ms (ActiveRecord: 0.0ms | Allocations: 266) Started GET "/nulogy_sso/login" for 192.168.0.189 at 2021-04-14 11:47:05 -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%252F192.168.0.189%253A64015%252Fapi_endpoint&response_type=code&scope=openid+email Completed 302 Found in 0ms (ActiveRecord: 0.0ms | Allocations: 306) TRANSACTION (1.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction 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", "2021-04-14 15:47:05"], ["updated_at", "2021-04-14 15:47:05"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 Started GET "/api_endpoint" for 192.168.0.189 at 2021-04-14 11:47:05 -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 2ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 726) TRANSACTION (1.3ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.1ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction  (1.8ms) SELECT sqlite_version(*)  (0.6ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.3ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC TRANSACTION (0.1ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.1ms) SAVEPOINT active_record_1 User Create (1.2ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-04-15 17:02:45.494834"], ["updated_at", "2021-04-15 17:02:45.494834"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.5ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-15 17:02:45.502119"], ["updated_at", "2021-04-15 17:02:45.502119"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.4ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-15 17:02:45.505920"], ["updated_at", "2021-04-15 17:02:45.505920"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.4ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-15 17:02:45.515983"], ["updated_at", "2021-04-15 17:02:45.515983"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.3ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-15 17:02:45.519685"], ["updated_at", "2021-04-15 17:02:45.519685"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.4ms) rollback transaction TRANSACTION (0.0ms) begin transaction 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", "2021-04-15 17:02:45.523672"], ["updated_at", "2021-04-15 17:02:45.523672"]] TRANSACTION (0.0ms) RELEASE SAVEPOINT active_record_1 TRANSACTION (0.3ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction TRANSACTION (0.0ms) begin transaction TRANSACTION (0.0ms) rollback transaction  (2.0ms) SELECT sqlite_version(*)  (0.6ms) SELECT "ar_internal_metadata"."value" FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? [["key", "schema_sha1"]]  (0.1ms) SELECT sqlite_version(*)  (0.4ms) SELECT "schema_migrations"."version" FROM "schema_migrations" ORDER BY "schema_migrations"."version" ASC  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 User Create (0.9ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-10-27 17:49:08.026078"], ["updated_at", "2021-10-27 17:49:08.026078"]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.6ms) 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", "2021-10-27 17:49:08.035468"], ["updated_at", "2021-10-27 17:49:08.035468"]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) 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", "2021-10-27 17:49:08.039673"], ["updated_at", "2021-10-27 17:49:08.039673"]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.5ms) 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", "2021-10-27 17:49:08.051200"], ["updated_at", "2021-10-27 17:49:08.051200"]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) 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", "2021-10-27 17:49:08.054856"], ["updated_at", "2021-10-27 17:49:08.054856"]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 User Create (0.4ms) INSERT INTO "users" ("email", "created_at", "updated_at") VALUES (?, ?, ?) [["email", "test@nulogy.com"], ["created_at", "2021-10-27 17:49:08.058760"], ["updated_at", "2021-10-27 17:49:08.058760"]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.4ms) 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) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction