(0.1ms) DROP TABLE IF EXISTS "users"  (0.9ms) SELECT sqlite_version(*)  (8.1ms) CREATE TABLE "users" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar NOT NULL, "uid" varchar NOT NULL, "email" varchar NOT NULL, "remotely_signed_out" boolean, "permissions" text, "organisation_slug" varchar, "organisation_content_id" varchar, "disabled" boolean DEFAULT 'f')  (37.2ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL) ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.0ms) begin transaction ActiveRecord::InternalMetadata Create (0.2ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2020-10-26 14:50:59.688219"], ["updated_at", "2020-10-26 14:50:59.688219"]]  (6.0ms) commit transaction  (6.0ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "dummyapiuser@domain.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Dummy API user created by gds-sso"], ["uid", "1000"], ["email", "dummyapiuser@domain.com"], ["permissions", "---\n- signin\n"]]  (4.1ms) commit transaction  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "permissions" = ? WHERE "users"."id" = ? [["permissions", "---\n- signin\n- extra_permission\n"], ["id", 1]]  (6.0ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "asd"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "user@example.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions", "organisation_slug", "organisation_content_id", "disabled") VALUES (?, ?, ?, ?, ?, ?, ?) [["name", "A Name"], ["uid", "asd"], ["email", "user@example.com"], ["permissions", "---\n- signin\n"], ["organisation_slug", "hmrc"], ["organisation_content_id", "67a2b78d-eee3-45b3-80e2-792e7f71cecc"], ["disabled", nil]]  (4.9ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "asd"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 4ms (ActiveRecord: 0.0ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Started GET "/auth/gds/callback?code=qlgWGND49FA4hmi1xxwtSmNDpxMu8JdbWneB08p7_qU&state=eae923f804055f5089297e4d477619f6307606c3c9445704" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"qlgWGND49FA4hmi1xxwtSmNDpxMu8JdbWneB08p7_qU", "state"=>"eae923f804055f5089297e4d477619f6307606c3c9445704"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "test@example-client.com"], ["LIMIT", 1]]  (0.1ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Test User"], ["uid", "integration-uid"], ["email", "test@example-client.com"], ["permissions", "---\n- signin\n"]]  (8.9ms) commit transaction  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", "f"], ["id", 3]]  (5.4ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 18ms (ActiveRecord: 14.9ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 3ms (Views: 1.9ms | ActiveRecord: 0.2ms) Started GET "/" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#index as HTML Rendering text template Rendered text template (0.0ms) Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#this_requires_signin_permission as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Started GET "/auth/gds/callback?code=N3zgRiV63CM3n82JL5m4VJybtab5jqD9T8rdOUfOjfQ&state=44c677e6a122e188c1897be58e35ca209ce417e5a7a5b5d0" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"N3zgRiV63CM3n82JL5m4VJybtab5jqD9T8rdOUfOjfQ", "state"=>"44c677e6a122e188c1897be58e35ca209ce417e5a7a5b5d0"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/this_requires_signin_permission Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#this_requires_signin_permission as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#this_requires_signin_permission as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Started GET "/auth/gds/callback?code=QvJd4HB0b8Qg-S6I1iAFJ4yp6HTUHa-BoZ1x1dud5J8&state=0e6eb994121ee71c83cb083646a8529a53e979e1262f8742" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"QvJd4HB0b8Qg-S6I1iAFJ4yp6HTUHa-BoZ1x1dud5J8", "state"=>"0e6eb994121ee71c83cb083646a8529a53e979e1262f8742"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/this_requires_signin_permission Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#this_requires_signin_permission as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Started GET "/auth/gds/callback?code=t3e9CdalZQIChrxQZWpw0C4kcAIpf4dCS7G1hh65b3o&state=38ba7880dfb04b0b344a5cc201d904c51afc8bcfaf4444cf" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"t3e9CdalZQIChrxQZWpw0C4kcAIpf4dCS7G1hh65b3o", "state"=>"38ba7880dfb04b0b344a5cc201d904c51afc8bcfaf4444cf"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Started GET "/auth/gds/callback?code=2YP0egP8EK9BjzxAFLSlCetha4Lpip5E3_0-aCoT2vw&state=320c0056d754ab4a4a58c33809306ce636054ce148fd3f62" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"2YP0egP8EK9BjzxAFLSlCetha4Lpip5E3_0-aCoT2vw", "state"=>"320c0056d754ab4a4a58c33809306ce636054ce148fd3f62"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Started GET "/auth/gds/callback?code=hLgxcit_ayAHov_NJbBYdr6FHspd3885lUbCY_kbD_A&state=90adbdefeb4f7bd88b3c95756a7bbda0f791377ed8063fcd" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"hLgxcit_ayAHov_NJbBYdr6FHspd3885lUbCY_kbD_A", "state"=>"90adbdefeb4f7bd88b3c95756a7bbda0f791377ed8063fcd"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:00 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-27 10:56:00 +0000 Started GET "/auth/gds/callback?code=L9OOGVZzZXA-A8yjejj5D-a6TMKqHAU0R2KzZpDfA-Y&state=940afe6d5b93445643da282f1a71fcd431d61585e8a93515" for 127.0.0.1 at 2020-10-27 10:56:00 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"L9OOGVZzZXA-A8yjejj5D-a6TMKqHAU0R2KzZpDfA-Y", "state"=>"940afe6d5b93445643da282f1a71fcd431d61585e8a93515"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:00 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Started GET "/auth/gds/callback?code=5ic6sOKhJSYDURBYZoFJHexP5G2bfXJjIV79xqDNFJ4&state=80aabeb7042e4b14007db50f5bbedc7f49363322faeb15c9" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"5ic6sOKhJSYDURBYZoFJHexP5G2bfXJjIV79xqDNFJ4", "state"=>"80aabeb7042e4b14007db50f5bbedc7f49363322faeb15c9"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:46:00 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:00 +0000 Started GET "/auth/gds/callback?code=5unc36p8g7n2ZSWxVmKzQJqtfQHU46Ize10_86EatxE&state=e66c42d4403f0a73e63f057037a7b9a79659047a0281a7c5" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"5unc36p8g7n2ZSWxVmKzQJqtfQHU46Ize10_86EatxE", "state"=>"e66c42d4403f0a73e63f057037a7b9a79659047a0281a7c5"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:01 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-27 10:56:01 +0000 Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Started GET "/auth/gds/callback?code=9DCTU1hpKPN8xOmtADg00cg7HVlQHEgSIs_qH8_8eQg&state=ba7a01e8e51a2c83e7c233d7757fce6ecc8d35737dca4067" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"9DCTU1hpKPN8xOmtADg00cg7HVlQHEgSIs_qH8_8eQg", "state"=>"ba7a01e8e51a2c83e7c233d7757fce6ecc8d35737dca4067"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 3ms (ActiveRecord: 0.3ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "test@example-client.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", "t"], ["id", 3]]  (6.6ms) commit transaction Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Authenticating with gds_sso strategy Completed in 1ms (ActiveRecord: 0.1ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Started GET "/auth/gds/callback?code=eXlvYYRe2IqnS8eEnMRI7woLmtLres33w6NsssIPWfM&state=553b4734786e1342b14853bfac88a9c543fbe33785db4164" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"eXlvYYRe2IqnS8eEnMRI7woLmtLres33w6NsssIPWfM", "state"=>"553b4734786e1342b14853bfac88a9c543fbe33785db4164"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.1ms) commit transaction  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", "f"], ["id", 3]]  (4.5ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 7ms (ActiveRecord: 4.9ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by ExampleController#restricted as JSON User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "disabled" = ? WHERE "users"."id" = ? [["disabled", nil], ["id", 3]]  (6.5ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Rendering text template Rendered text template (0.0ms) Completed 200 OK in 21ms (Views: 0.2ms | ActiveRecord: 7.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by ExampleController#restricted as JSON Completed in 12ms (ActiveRecord: 0.0ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by ExampleController#this_requires_signin_permission as JSON User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Rendering text template Rendered text template (0.0ms) Completed 200 OK in 5ms (Views: 0.2ms | ActiveRecord: 0.4ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:01 +0000 Processing by ExampleController#restricted as JSON Completed in 7ms (ActiveRecord: 0.0ms)  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d36751"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (7.0ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d37112"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (6.0ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"a1s2d36751"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "a1s2d36751"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", "t"], ["id", 4]]  (4.6ms) commit transaction Completed 200 OK in 6ms (ActiveRecord: 4.9ms) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 4], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d39451"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (9.7ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d38478"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (15.0ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"nonexistent-user"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "nonexistent-user"], ["LIMIT", 1]] Completed 200 OK in 1ms (ActiveRecord: 0.1ms)  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d36453"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (4.2ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d3600"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (4.5ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"a1s2d36453"} Rendering /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Rendered /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (0.2ms) Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Completed 403 Forbidden in 3ms (Views: 3.1ms | ActiveRecord: 0.0ms)  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d37723"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.4ms) commit transaction  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d36478"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (4.2ms) commit transaction Processing by Api::UserController#update as HTML Parameters: {"uid"=>"a1s2d37723"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "a1s2d37723"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "email" = ?, "name" = ?, "permissions" = ?, "organisation_slug" = ?, "organisation_content_id" = ? WHERE "users"."id" = ? [["email", "user@domain.com"], ["name", "Joshua Marshall"], ["permissions", "---\n- signin\n- new permission\n"], ["organisation_slug", "justice-league"], ["organisation_content_id", "aae1319e-5788-4677-998c-f1a53af528d0"], ["id", 10]]  (4.2ms) commit transaction Completed 200 OK in 6ms (ActiveRecord: 4.5ms) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 10], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d31835"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.2ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d34167"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (4.0ms) commit transaction Processing by Api::UserController#update as HTML Parameters: {"uid"=>"a1s2d31835"} Rendering /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Rendered /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (0.1ms) Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Completed 403 Forbidden in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms)  (1.0ms) SELECT sqlite_version(*)  (0.0ms) SELECT sqlite_version(*)  (0.1ms) DROP TABLE IF EXISTS "users"  (8.5ms) CREATE TABLE "users" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar NOT NULL, "uid" varchar NOT NULL, "email" varchar NOT NULL, "remotely_signed_out" boolean, "permissions" text, "organisation_slug" varchar, "organisation_content_id" varchar, "disabled" boolean DEFAULT 0)  (6.2ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.0ms) begin transaction ActiveRecord::InternalMetadata Create (0.2ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2020-10-26 14:51:09.395508"], ["updated_at", "2020-10-26 14:51:09.395508"]]  (5.1ms) commit transaction  (4.2ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.1ms) SELECT sqlite_version(*)  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d3521"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (8.8ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d31612"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (9.4ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"a1s2d3521"} Rendering /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Rendered /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (Duration: 0.5ms | Allocations: 266) Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Completed 403 Forbidden in 5ms (Views: 3.6ms | ActiveRecord: 0.0ms | Allocations: 3270)  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d35772"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.7ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d39235"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (7.3ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"a1s2d35772"} User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "a1s2d35772"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", 1], ["id", 3]]  (7.3ms) commit transaction Completed 200 OK in 10ms (ActiveRecord: 7.7ms | Allocations: 1138) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 3], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d35156"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (7.9ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d34813"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (5.1ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"nonexistent-user"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "nonexistent-user"], ["LIMIT", 1]] Completed 200 OK in 1ms (ActiveRecord: 0.1ms | Allocations: 521)  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d34405"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.0ms) commit transaction  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d3951"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (7.0ms) commit transaction Processing by Api::UserController#update as HTML Parameters: {"uid"=>"a1s2d34405"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "a1s2d34405"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "email" = ?, "name" = ?, "permissions" = ?, "organisation_slug" = ?, "organisation_content_id" = ? WHERE "users"."id" = ? [["email", "user@domain.com"], ["name", "Joshua Marshall"], ["permissions", "---\n- signin\n- new permission\n"], ["organisation_slug", "justice-league"], ["organisation_content_id", "aae1319e-5788-4677-998c-f1a53af528d0"], ["id", 7]]  (6.3ms) commit transaction Completed 200 OK in 8ms (ActiveRecord: 6.6ms | Allocations: 1284) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 7], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d34335"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (9.8ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d39368"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (7.4ms) commit transaction Processing by Api::UserController#update as HTML Parameters: {"uid"=>"a1s2d34335"} Rendering /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Rendered /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (Duration: 0.1ms | Allocations: 56) Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Completed 403 Forbidden in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms | Allocations: 514) User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "dummyapiuser@domain.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Dummy API user created by gds-sso"], ["uid", "3749"], ["email", "dummyapiuser@domain.com"], ["permissions", "---\n- signin\n"]]  (11.3ms) commit transaction  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "permissions" = ? WHERE "users"."id" = ? [["permissions", "---\n- signin\n- extra_permission\n"], ["id", 11]]  (13.2ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "asd"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "user@example.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions", "organisation_slug", "organisation_content_id", "disabled") VALUES (?, ?, ?, ?, ?, ?, ?) [["name", "A Name"], ["uid", "asd"], ["email", "user@example.com"], ["permissions", "---\n- signin\n"], ["organisation_slug", "hmrc"], ["organisation_content_id", "67a2b78d-eee3-45b3-80e2-792e7f71cecc"], ["disabled", nil]]  (6.9ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "asd"], ["LIMIT", 1]] Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#this_requires_signin_permission as HTML Authenticating with gds_sso strategy Completed in 4ms (ActiveRecord: 0.0ms | Allocations: 161) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=CcfEoM1MpM4hcEeZL5WZUmoWjkNxiM0Oa1L7rWJLKBs&state=156fe842e93761b639e9a74480699111a72a9be4a4fc1e17" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"CcfEoM1MpM4hcEeZL5WZUmoWjkNxiM0Oa1L7rWJLKBs", "state"=>"156fe842e93761b639e9a74480699111a72a9be4a4fc1e17"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "test@example-client.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Test User"], ["uid", "integration-uid"], ["email", "test@example-client.com"], ["permissions", "---\n- signin\n"]]  (5.3ms) commit transaction  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", 0], ["id", 13]]  (4.2ms) commit transaction Redirected to http://www.example-client.com/this_requires_signin_permission Completed 302 Found in 13ms (ActiveRecord: 10.1ms | Allocations: 1593) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#this_requires_signin_permission as HTML User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 2ms (Views: 0.8ms | ActiveRecord: 0.2ms | Allocations: 1054) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#this_requires_signin_permission as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 112) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=P_GEjdhe51Awp_rBVMBHcqmzzX64bBiYtbzHlOCLd1A&state=a3e67583ddbdc93e9a05d777b9f8f47211da874e1bf08a89" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"P_GEjdhe51Awp_rBVMBHcqmzzX64bBiYtbzHlOCLd1A", "state"=>"a3e67583ddbdc93e9a05d777b9f8f47211da874e1bf08a89"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/this_requires_signin_permission Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1019) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#this_requires_signin_permission as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 709) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 112) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=vw71a-deJx87P19bBjLXxD9r3B3vPQRW7movQkxVyg4&state=f086f068f227e08a5a6c23086c801305a3bf33eacae3c724" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"vw71a-deJx87P19bBjLXxD9r3B3vPQRW7movQkxVyg4", "state"=>"f086f068f227e08a5a6c23086c801305a3bf33eacae3c724"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1015) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 706) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 112) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=gKSQCO3T605t6Bd3ty3fTGxwcWnUBnKKQ0WS_qsE9Sg&state=85c6bc6df2137b74f313bcab3e7fe9a031c3961c98396d4b" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"gKSQCO3T605t6Bd3ty3fTGxwcWnUBnKKQ0WS_qsE9Sg", "state"=>"85c6bc6df2137b74f313bcab3e7fe9a031c3961c98396d4b"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1015) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 706) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 112) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=BnFkDrfN9Z2hyimBvnmSqcPfovbOQyFvaIG9cohTHfM&state=ebc1316410d9746f3da5be6009e776a648c23afa996ed286" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"BnFkDrfN9Z2hyimBvnmSqcPfovbOQyFvaIG9cohTHfM", "state"=>"ebc1316410d9746f3da5be6009e776a648c23afa996ed286"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1015) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 706) Started GET "/" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#index as HTML Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 153) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 112) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=APoibDDfGWp4YOYOb-mlhAGZ4ddhM99vujOTRaNe8fM&state=31b2dd657ea3fb2f658a27903cd6731ae4b5a1b5e4a31a35" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"APoibDDfGWp4YOYOb-mlhAGZ4ddhM99vujOTRaNe8fM", "state"=>"31b2dd657ea3fb2f658a27903cd6731ae4b5a1b5e4a31a35"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1019) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 709) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 498) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-27 10:56:10 +0000 Started GET "/auth/gds/callback?code=7K72nCsn8CzZstyeITpn5_tqeSamkl20rDOAnjEAvhE&state=39077c375107ad6496606d3943549c493bdc6ed4fd38c558" for 127.0.0.1 at 2020-10-27 10:56:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"7K72nCsn8CzZstyeITpn5_tqeSamkl20rDOAnjEAvhE", "state"=>"39077c375107ad6496606d3943549c493bdc6ed4fd38c558"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1217) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 931) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 112) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=IT_etADQWF1aJWi9LF93q_RAkpAR81y52ZPecBrS4RE&state=4cdc532d5e0d6b31f8dd68839f3ab032ebf1396f521219a0" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"IT_etADQWF1aJWi9LF93q_RAkpAR81y52ZPecBrS4RE", "state"=>"4cdc532d5e0d6b31f8dd68839f3ab032ebf1396f521219a0"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1015) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 706) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 498) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-27 10:56:10 +0000 Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 112) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=O4wvIT3_l7tiliaqAACJZ20qbUqq_pVNmbXZ7LQb-nw&state=8485936984b0a357019f3679e1a0c3cb257b9125fe85d1c4" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"O4wvIT3_l7tiliaqAACJZ20qbUqq_pVNmbXZ7LQb-nw", "state"=>"8485936984b0a357019f3679e1a0c3cb257b9125fe85d1c4"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1015) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 706) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:46:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 929) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 112) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=h5AvSzO9-B-1cin-UDSU-p1dIsEbFKeD7lweCROjrho&state=9e8442a545bde8802ef556f04e1c8fdde3db5c75819d3b4c" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"h5AvSzO9-B-1cin-UDSU-p1dIsEbFKeD7lweCROjrho", "state"=>"9e8442a545bde8802ef556f04e1c8fdde3db5c75819d3b4c"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1019) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 708) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "test@example-client.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", 1], ["id", 13]]  (4.1ms) commit transaction Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Authenticating with gds_sso strategy Completed in 1ms (ActiveRecord: 0.1ms | Allocations: 574) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Started GET "/auth/gds/callback?code=B40rOBHLorgUVjtPFZSVFMxf4QnH7ryN4p6tUQR1vtg&state=c6e381ce1a1c9c84ae78a3e18e447ad294b0ed65cf25130f" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"B40rOBHLorgUVjtPFZSVFMxf4QnH7ryN4p6tUQR1vtg", "state"=>"c6e381ce1a1c9c84ae78a3e18e447ad294b0ed65cf25130f"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.1ms) begin transaction User Update (0.2ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", 0], ["id", 13]]  (5.2ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 8ms (ActiveRecord: 5.5ms | Allocations: 1225) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 704) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as JSON Completed in 14ms (ActiveRecord: 0.0ms | Allocations: 2116) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as JSON Completed in 7ms (ActiveRecord: 0.0ms | Allocations: 1871) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as JSON User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.1ms) begin transaction User Update (0.2ms) UPDATE "users" SET "disabled" = ? WHERE "users"."id" = ? [["disabled", nil], ["id", 13]]  (5.2ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 11ms (Views: 0.2ms | ActiveRecord: 5.7ms | Allocations: 3631) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#this_requires_signin_permission as JSON User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 5ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 3319) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:10 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 114)  (0.1ms) DROP TABLE IF EXISTS "users"  (0.9ms) SELECT sqlite_version(*)  (17.8ms) CREATE TABLE "users" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar NOT NULL, "uid" varchar NOT NULL, "email" varchar NOT NULL, "remotely_signed_out" boolean, "permissions" text, "organisation_slug" varchar, "organisation_content_id" varchar, "disabled" boolean DEFAULT 'f')  (8.8ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime NOT NULL, "updated_at" datetime NOT NULL) ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.0ms) begin transaction ActiveRecord::InternalMetadata Create (0.2ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2020-10-26 14:51:15.152563"], ["updated_at", "2020-10-26 14:51:15.152563"]]  (5.6ms) commit transaction  (5.9ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "dummyapiuser@domain.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (1.0ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Dummy API user created by gds-sso"], ["uid", "2686"], ["email", "dummyapiuser@domain.com"], ["permissions", "---\n- signin\n"]]  (5.2ms) commit transaction  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "permissions" = ? WHERE "users"."id" = ? [["permissions", "---\n- signin\n- extra_permission\n"], ["id", 1]]  (4.2ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d31161"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (4.7ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d34234"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (5.1ms) commit transaction Processing by Api::UserController#update as HTML Parameters: {"uid"=>"a1s2d31161"} Rendering /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Rendered /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (1.2ms) Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Completed 403 Forbidden in 7ms (Views: 6.6ms | ActiveRecord: 0.0ms)  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d32959"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.7ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d39467"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (5.8ms) commit transaction Processing by Api::UserController#update as HTML Parameters: {"uid"=>"a1s2d32959"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "a1s2d32959"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "email" = ?, "name" = ?, "permissions" = ?, "organisation_slug" = ?, "organisation_content_id" = ? WHERE "users"."id" = ? [["email", "user@domain.com"], ["name", "Joshua Marshall"], ["permissions", "---\n- signin\n- new permission\n"], ["organisation_slug", "justice-league"], ["organisation_content_id", "aae1319e-5788-4677-998c-f1a53af528d0"], ["id", 4]]  (5.1ms) commit transaction Completed 200 OK in 9ms (ActiveRecord: 5.4ms) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 4], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d34570"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (4.1ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d39231"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (4.2ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"a1s2d34570"} Rendering /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Rendered /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (0.1ms) Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Completed 403 Forbidden in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms)  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d314"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.1ms) commit transaction  (1.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d35325"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (5.1ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"a1s2d314"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "a1s2d314"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", "t"], ["id", 8]]  (5.1ms) commit transaction Completed 200 OK in 7ms (ActiveRecord: 5.3ms) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 8], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d37331"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (4.8ms) commit transaction  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d3677"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (5.4ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"nonexistent-user"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "nonexistent-user"], ["LIMIT", 1]] Completed 200 OK in 2ms (ActiveRecord: 0.1ms) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "asd"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "user@example.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions", "organisation_slug", "organisation_content_id", "disabled") VALUES (?, ?, ?, ?, ?, ?, ?) [["name", "A Name"], ["uid", "asd"], ["email", "user@example.com"], ["permissions", "---\n- signin\n"], ["organisation_slug", "hmrc"], ["organisation_content_id", "67a2b78d-eee3-45b3-80e2-792e7f71cecc"], ["disabled", nil]]  (4.6ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "asd"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:15 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 4ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:15 +0000 Started GET "/auth/gds/callback?code=Q9Xym_iQS39k93lYjTQmU_M46Qh7yfKef1UwVv273B8&state=a888e7930f94b3210e33ee38ef27f2f6c59989749a591104" for 127.0.0.1 at 2020-10-26 14:51:15 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"Q9Xym_iQS39k93lYjTQmU_M46Qh7yfKef1UwVv273B8", "state"=>"a888e7930f94b3210e33ee38ef27f2f6c59989749a591104"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "test@example-client.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Test User"], ["uid", "integration-uid"], ["email", "test@example-client.com"], ["permissions", "---\n- signin\n"]]  (5.1ms) commit transaction  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", "f"], ["id", 13]]  (4.2ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 13ms (ActiveRecord: 9.9ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:15 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 2ms (Views: 0.7ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:15 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:15 +0000 Started GET "/auth/gds/callback?code=sseENFS7d6YBWzqiG7fpzVhhedUEcvD5PY7jKg9lHzo&state=135f1b3de39cee89529dcae630868efd0e66c62948fe6fad" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"sseENFS7d6YBWzqiG7fpzVhhedUEcvD5PY7jKg9lHzo", "state"=>"135f1b3de39cee89529dcae630868efd0e66c62948fe6fad"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#this_requires_signin_permission as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Started GET "/auth/gds/callback?code=QID8NNYuBDTecvsjSiD-nYSJchn1zEou8UuDw9wfo5g&state=d42a09f463c9f9256d77595275b83baf607fc9f326a95764" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"QID8NNYuBDTecvsjSiD-nYSJchn1zEou8UuDw9wfo5g", "state"=>"d42a09f463c9f9256d77595275b83baf607fc9f326a95764"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/this_requires_signin_permission Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#this_requires_signin_permission as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#this_requires_signin_permission as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Started GET "/auth/gds/callback?code=fRqwvJN7sEVyv-ak_G85JDH9aG72tj15sEfXqHMEVNo&state=4da89b2888a3812c467f79184df679a0fca58af49dbf8bef" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"fRqwvJN7sEVyv-ak_G85JDH9aG72tj15sEfXqHMEVNo", "state"=>"4da89b2888a3812c467f79184df679a0fca58af49dbf8bef"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/this_requires_signin_permission Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#this_requires_signin_permission as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#index as HTML Rendering text template Rendered text template (0.0ms) Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Started GET "/auth/gds/callback?code=0my6EyJ18tST4xHWoxB70-9MIbZe4Y96U3eQuNLxMWA&state=b5a0ee528dcf895b4ca52d06b2e1a77b2d1b03913e412e65" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"0my6EyJ18tST4xHWoxB70-9MIbZe4Y96U3eQuNLxMWA", "state"=>"b5a0ee528dcf895b4ca52d06b2e1a77b2d1b03913e412e65"} Authenticating with gds_sso strategy User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.1ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 3ms (ActiveRecord: 0.3ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Started GET "/auth/gds/callback?code=PMYkbsLpF3HdAt-tuGm2z7SZ9ql0Unbkm6JytGmT7k0&state=c5c3d7f8011877996153f7d94658f795bb7909298c18a0f6" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"PMYkbsLpF3HdAt-tuGm2z7SZ9ql0Unbkm6JytGmT7k0", "state"=>"c5c3d7f8011877996153f7d94658f795bb7909298c18a0f6"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.1ms) begin transaction  (0.1ms) commit transaction  (0.0ms) begin transaction  (0.1ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 3ms (ActiveRecord: 0.4ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 2ms (Views: 0.3ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:16 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 1ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-27 10:56:16 +0000 Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Started GET "/auth/gds/callback?code=Q_rMbceGZWd_zjBYuOFHd6NKfHBcHGgwVDcXV6UZI48&state=e65b340ea778029e7fbfe50f2b416bd2ddeee9460b159533" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"Q_rMbceGZWd_zjBYuOFHd6NKfHBcHGgwVDcXV6UZI48", "state"=>"e65b340ea778029e7fbfe50f2b416bd2ddeee9460b159533"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.1ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 3ms (ActiveRecord: 0.3ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:16 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-27 10:56:16 +0000 Started GET "/auth/gds/callback?code=F1Azm4-OYL03Kgox-DnlpMRjNDOIEOXswvjl5JRie0U&state=857bddb98bab5f7375cfe3c884d90a4d4afbef44c9b6b702" for 127.0.0.1 at 2020-10-27 10:56:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"F1Azm4-OYL03Kgox-DnlpMRjNDOIEOXswvjl5JRie0U", "state"=>"857bddb98bab5f7375cfe3c884d90a4d4afbef44c9b6b702"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Started GET "/auth/gds/callback?code=V5VabBRBYodhQsV5Yk7m5jo6ks4vj5FDHrZTlHnxBZ4&state=7756d177cb339841b9f792b67023ba169c9d08181456fb96" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"V5VabBRBYodhQsV5Yk7m5jo6ks4vj5FDHrZTlHnxBZ4", "state"=>"7756d177cb339841b9f792b67023ba169c9d08181456fb96"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:46:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Started GET "/auth/gds/callback?code=Zv6qwzvfJxFkdQi6YdvVmQOnasH1DwSBT581CaKM3JI&state=c70be6f7328e80c7b899b0fcd0601cdcc7c1b57542ddc96f" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"Zv6qwzvfJxFkdQi6YdvVmQOnasH1DwSBT581CaKM3JI", "state"=>"c70be6f7328e80c7b899b0fcd0601cdcc7c1b57542ddc96f"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.2ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "test@example-client.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", "t"], ["id", 13]]  (12.8ms) commit transaction Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Authenticating with gds_sso strategy Completed in 1ms (ActiveRecord: 0.1ms) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Started GET "/auth/gds/callback?code=sc6BrE3I9al5FWLj-6W1Kimm3naOqZo-zhU3eDLeno0&state=e49cc60c6430580f953d5de40f1a7f3263d66e0d707adbbe" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"sc6BrE3I9al5FWLj-6W1Kimm3naOqZo-zhU3eDLeno0", "state"=>"e49cc60c6430580f953d5de40f1a7f3263d66e0d707adbbe"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", "f"], ["id", 13]]  (6.5ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 9ms (ActiveRecord: 6.9ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", "f"], ["LIMIT", 1]] Rendering text template Rendered text template (0.0ms) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as JSON Completed in 9ms (ActiveRecord: 0.0ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as JSON User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "disabled" = ? WHERE "users"."id" = ? [["disabled", nil], ["id", 13]]  (6.0ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.1ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Rendering text template Rendered text template (0.0ms) Completed 200 OK in 13ms (Views: 0.2ms | ActiveRecord: 6.7ms) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#this_requires_signin_permission as JSON User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction  (0.0ms) commit transaction  (0.0ms) begin transaction  (0.0ms) commit transaction Rendering text template Rendered text template (0.0ms) Completed 200 OK in 6ms (Views: 0.2ms | ActiveRecord: 0.4ms) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:16 +0000 Processing by ExampleController#restricted as JSON Completed in 13ms (ActiveRecord: 0.0ms)  (1.0ms) SELECT sqlite_version(*)  (0.0ms) SELECT sqlite_version(*)  (0.1ms) DROP TABLE IF EXISTS "users"  (7.4ms) CREATE TABLE "users" ("id" integer PRIMARY KEY AUTOINCREMENT NOT NULL, "name" varchar NOT NULL, "uid" varchar NOT NULL, "email" varchar NOT NULL, "remotely_signed_out" boolean, "permissions" text, "organisation_slug" varchar, "organisation_content_id" varchar, "disabled" boolean DEFAULT 0)  (7.1ms) CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL) ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.0ms) begin transaction ActiveRecord::InternalMetadata Create (0.1ms) INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?) [["key", "environment"], ["value", "test"], ["created_at", "2020-10-26 14:51:22.380901"], ["updated_at", "2020-10-26 14:51:22.380901"]]  (5.6ms) commit transaction  (5.6ms) CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY) ActiveRecord::InternalMetadata Load (0.1ms) SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ? [["key", "environment"], ["LIMIT", 1]]  (0.1ms) SELECT sqlite_version(*) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "dummyapiuser@domain.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Dummy API user created by gds-sso"], ["uid", "9790"], ["email", "dummyapiuser@domain.com"], ["permissions", "---\n- signin\n"]]  (5.0ms) commit transaction  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "permissions" = ? WHERE "users"."id" = ? [["permissions", "---\n- signin\n- extra_permission\n"], ["id", 1]]  (4.9ms) commit transaction Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:22 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 4ms (ActiveRecord: 0.0ms | Allocations: 161) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:22 +0000 Started GET "/auth/gds/callback?code=Sfl1PZlW0kvV7mxi_j7nipAK5PmuIIFupkzKfPqBx6M&state=25e69dbc146d2fd2aace060b8f407032b56e7c4445737afb" for 127.0.0.1 at 2020-10-26 14:51:22 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"Sfl1PZlW0kvV7mxi_j7nipAK5PmuIIFupkzKfPqBx6M", "state"=>"25e69dbc146d2fd2aace060b8f407032b56e7c4445737afb"} Authenticating with gds_sso strategy User Load (0.2ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "test@example-client.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Test User"], ["uid", "integration-uid"], ["email", "test@example-client.com"], ["permissions", "---\n- signin\n"]]  (5.3ms) commit transaction  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", 0], ["id", 2]]  (4.1ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 13ms (ActiveRecord: 10.1ms | Allocations: 1492) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 3) Completed 200 OK in 3ms (Views: 2.0ms | ActiveRecord: 0.1ms | Allocations: 1544) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 102) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Started GET "/auth/gds/callback?code=Y3O9Fs0VsVJtPNkQc-dpwaBjtkIiJhtNT5m_zdnF9uo&state=0ce4a4943aaf5319577dedf86e7f0cd1fefef2d247625374" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"Y3O9Fs0VsVJtPNkQc-dpwaBjtkIiJhtNT5m_zdnF9uo", "state"=>"0ce4a4943aaf5319577dedf86e7f0cd1fefef2d247625374"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 913) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 652) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 102) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Started GET "/auth/gds/callback?code=tBgDLG95eMGQSrnP0Qmbr546Wju9WyzjR5chjYSHocE&state=02d28e934929f9a54cab1f6b7fe669e40800080c4525eabc" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"tBgDLG95eMGQSrnP0Qmbr546Wju9WyzjR5chjYSHocE", "state"=>"02d28e934929f9a54cab1f6b7fe669e40800080c4525eabc"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 914) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 2ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 655) Started GET "/" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#index as HTML Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms | Allocations: 137) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#this_requires_signin_permission as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 102) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Started GET "/auth/gds/callback?code=4Fy6FyFR9O4Mjg0kZCgjY-Pd-pHsJ8pZ_B-XAEA_jyY&state=fba83e14267eb214b07ddf5ed4ff10b3999734143bbbbcb3" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"4Fy6FyFR9O4Mjg0kZCgjY-Pd-pHsJ8pZ_B-XAEA_jyY", "state"=>"fba83e14267eb214b07ddf5ed4ff10b3999734143bbbbcb3"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/this_requires_signin_permission Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 912) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#this_requires_signin_permission as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 652) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#this_requires_signin_permission as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 102) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Started GET "/auth/gds/callback?code=Tf2X4Sb305VGS9ToUsyWoGwTMBPGT9v0421F6VARSHA&state=b5909fbb28df9acc65aca35853d83ac869485afc9dadf098" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"Tf2X4Sb305VGS9ToUsyWoGwTMBPGT9v0421F6VARSHA", "state"=>"b5909fbb28df9acc65aca35853d83ac869485afc9dadf098"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/this_requires_signin_permission Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 912) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#this_requires_signin_permission as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 652) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 102) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Started GET "/auth/gds/callback?code=BDMiqmAhSUmubuek5f3fz92ekVUqSTd-igF_aeIbzbo&state=808869a9cd86784f5074bff81cc774ce9c98eea912a2d8c3" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"BDMiqmAhSUmubuek5f3fz92ekVUqSTd-igF_aeIbzbo", "state"=>"808869a9cd86784f5074bff81cc774ce9c98eea912a2d8c3"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 912) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 652) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "test@example-client.com"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.2ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", 1], ["id", 2]]  (4.5ms) commit transaction Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Authenticating with gds_sso strategy Completed in 1ms (ActiveRecord: 0.1ms | Allocations: 532) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Started GET "/auth/gds/callback?code=ULrH80fYJMjbZhFg7SM9pY9dwSD3DucnSyNFeNaOpNs&state=2b102c56bfc92b3e9d3abe9f8fb1d6fb85b982a5d9030ac8" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"ULrH80fYJMjbZhFg7SM9pY9dwSD3DucnSyNFeNaOpNs", "state"=>"2b102c56bfc92b3e9d3abe9f8fb1d6fb85b982a5d9030ac8"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", 0], ["id", 2]]  (4.3ms) commit transaction Redirected to http://www.example-client.com/restricted Completed 302 Found in 7ms (ActiveRecord: 4.5ms | Allocations: 1103) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 650) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 102) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Started GET "/auth/gds/callback?code=1ii4HmDcFSY4Wbj9v79MrIVmenNGY7SxuyGLkaLYswA&state=40b1fbad4bc5be3f73a23dc92e5dc7a289db3d4cf2607dc2" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"1ii4HmDcFSY4Wbj9v79MrIVmenNGY7SxuyGLkaLYswA", "state"=>"40b1fbad4bc5be3f73a23dc92e5dc7a289db3d4cf2607dc2"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 912) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 652) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:23 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 479) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-27 10:56:23 +0000 Started GET "/auth/gds/callback?code=CCRzd3loz6THeyHMYh5OoCj84uRMei8okdG6yBP7MUE&state=9ca840e746f47c54ae507bbafca211b0fd575c163c9f3435" for 127.0.0.1 at 2020-10-27 10:56:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"CCRzd3loz6THeyHMYh5OoCj84uRMei8okdG6yBP7MUE", "state"=>"9ca840e746f47c54ae507bbafca211b0fd575c163c9f3435"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 1115) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 879) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 102) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Started GET "/auth/gds/callback?code=b8s-kXAuetGtMPf0qd-evTQQebvXsS6juUttnUlj_KI&state=091092ef775a27a53d074af06150387698a9565ea7256204" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"b8s-kXAuetGtMPf0qd-evTQQebvXsS6juUttnUlj_KI", "state"=>"091092ef775a27a53d074af06150387698a9565ea7256204"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 912) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 653) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:56:23 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 1ms (ActiveRecord: 0.0ms | Allocations: 479) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-27 10:56:23 +0000 Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 102) Started GET "/auth/gds" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Started GET "/auth/gds/callback?code=Pt3AQ4pm5niA5TlkSaosLd1ov8PreanA86IVVfHd9H8&state=92f7f73fd253c2f094bd594446565a95b5d4fdb8b46afbea" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by AuthenticationsController#callback as HTML Parameters: {"code"=>"Pt3AQ4pm5niA5TlkSaosLd1ov8PreanA86IVVfHd9H8", "state"=>"92f7f73fd253c2f094bd594446565a95b5d4fdb8b46afbea"} Authenticating with gds_sso strategy User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Redirected to http://www.example-client.com/restricted Completed 302 Found in 2ms (ActiveRecord: 0.1ms | Allocations: 912) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 652) Started GET "/restricted" for 127.0.0.1 at 2020-10-27 10:46:23 +0000 Processing by ExampleController#restricted as HTML User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? AND "users"."remotely_signed_out" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["remotely_signed_out", 0], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms | Allocations: 875) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as JSON Completed in 12ms (ActiveRecord: 0.0ms | Allocations: 1955) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as JSON User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]]  (0.1ms) begin transaction User Update (0.2ms) UPDATE "users" SET "disabled" = ? WHERE "users"."id" = ? [["disabled", nil], ["id", 2]]  (5.7ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 13ms (Views: 0.3ms | ActiveRecord: 6.1ms | Allocations: 3311) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as JSON Completed in 7ms (ActiveRecord: 0.0ms | Allocations: 1776) Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#this_requires_signin_permission as JSON User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] CACHE User Load (0.0ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "integration-uid"], ["LIMIT", 1]] Rendering text template Rendered text template (Duration: 0.0ms | Allocations: 1) Completed 200 OK in 7ms (Views: 0.3ms | ActiveRecord: 0.2ms | Allocations: 3020) Started GET "/restricted" for 127.0.0.1 at 2020-10-26 14:51:23 +0000 Processing by ExampleController#restricted as HTML Authenticating with gds_sso strategy Completed in 0ms (ActiveRecord: 0.0ms | Allocations: 104) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "asd"], ["LIMIT", 1]] User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ? [["email", "user@example.com"], ["LIMIT", 1]]  (0.1ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions", "organisation_slug", "organisation_content_id", "disabled") VALUES (?, ?, ?, ?, ?, ?, ?) [["name", "A Name"], ["uid", "asd"], ["email", "user@example.com"], ["permissions", "---\n- signin\n"], ["organisation_slug", "hmrc"], ["organisation_content_id", "67a2b78d-eee3-45b3-80e2-792e7f71cecc"], ["disabled", nil]]  (5.7ms) commit transaction User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "asd"], ["LIMIT", 1]]  (0.1ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d35050"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (4.3ms) commit transaction  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d38503"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (5.8ms) commit transaction Processing by Api::UserController#update as HTML Parameters: {"uid"=>"a1s2d35050"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "a1s2d35050"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "email" = ?, "name" = ?, "permissions" = ?, "organisation_slug" = ?, "organisation_content_id" = ? WHERE "users"."id" = ? [["email", "user@domain.com"], ["name", "Joshua Marshall"], ["permissions", "---\n- signin\n- new permission\n"], ["organisation_slug", "justice-league"], ["organisation_content_id", "aae1319e-5788-4677-998c-f1a53af528d0"], ["id", 4]]  (5.1ms) commit transaction Completed 200 OK in 7ms (ActiveRecord: 5.4ms | Allocations: 1266) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 4], ["LIMIT", 1]]  (0.1ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d34112"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.2ms) commit transaction  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d36543"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (3.7ms) commit transaction Processing by Api::UserController#update as HTML Parameters: {"uid"=>"a1s2d34112"} Rendering /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Rendered /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (Duration: 0.5ms | Allocations: 192) Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Completed 403 Forbidden in 3ms (Views: 2.3ms | ActiveRecord: 0.0ms | Allocations: 1353)  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d33647"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.1ms) commit transaction  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d38442"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (4.0ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"a1s2d33647"} Rendering /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Rendered /var/lib/jenkins/workspace/gds-sso_master/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (Duration: 0.1ms | Allocations: 46) Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. Completed 403 Forbidden in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms | Allocations: 508)  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d3570"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.0ms) commit transaction  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d33250"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (3.9ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"a1s2d3570"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "a1s2d3570"], ["LIMIT", 1]]  (0.0ms) begin transaction User Update (0.1ms) UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ? [["remotely_signed_out", 1], ["id", 10]]  (5.2ms) commit transaction Completed 200 OK in 7ms (ActiveRecord: 5.5ms | Allocations: 882) User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ? [["id", 10], ["LIMIT", 1]]  (0.0ms) begin transaction User Create (0.2ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "Moshua Jarshall"], ["uid", "a1s2d39749"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]  (5.0ms) commit transaction  (0.0ms) begin transaction User Create (0.1ms) INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?) [["name", "SSO Push user"], ["uid", "a1s2d38771"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]  (3.8ms) commit transaction Processing by Api::UserController#reauth as HTML Parameters: {"uid"=>"nonexistent-user"} User Load (0.1ms) SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ? [["uid", "nonexistent-user"], ["LIMIT", 1]] Completed 200 OK in 1ms (ActiveRecord: 0.1ms | Allocations: 525)