(0.1ms)  DROP TABLE IF EXISTS "users"
   (1.1ms)  SELECT sqlite_version(*)
   (6.0ms)  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')
   (5.4ms)  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.2ms)  SELECT  "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  ActiveRecord::InternalMetadata Create (0.3ms)  INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["key", "environment"], ["value", "test"], ["created_at", "2020-07-28 10:10:58.659218"], ["updated_at", "2020-07-28 10:10:58.659218"]]
   (5.4ms)  commit transaction
   (4.2ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)
  ActiveRecord::InternalMetadata Load (0.2ms)  SELECT  "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
   (0.0ms)  commit transaction
  User Load (0.2ms)  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.3ms)  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.2ms)  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.1ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d33188"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.4ms)  commit transaction
   (0.0ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d32885"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.4ms)  commit transaction
Processing by Api::UserController#reauth as HTML
  Parameters: {"uid"=>"a1s2d33188"}
  Rendering /var/lib/jenkins/workspace/gds-sso_master-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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 7ms (Views: 6.9ms | ActiveRecord: 0.0ms)
   (0.0ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d39666"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.0ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d35794"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (4.3ms)  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.1ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d32544"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.3ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d3705"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.3ms)  commit transaction
Processing by Api::UserController#reauth as HTML
  Parameters: {"uid"=>"a1s2d32544"}
  User Load (11.3ms)  SELECT  "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "a1s2d32544"], ["LIMIT", 1]]
   (0.5ms)  begin transaction
  User Update (0.2ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", "t"], ["id", 6]]
   (4.0ms)  commit transaction
Completed 200 OK in 18ms (ActiveRecord: 16.0ms)
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 6], ["LIMIT", 1]]
   (0.0ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d32525"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.6ms)  commit transaction
   (0.0ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d38924"], ["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"=>"a1s2d32525"}
  Rendering /var/lib/jenkins/workspace/gds-sso_master-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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 1ms (Views: 0.6ms | ActiveRecord: 0.0ms)
   (0.1ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d33880"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.4ms)  commit transaction
   (0.0ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d31256"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.3ms)  commit transaction
Processing by Api::UserController#update as HTML
  Parameters: {"uid"=>"a1s2d33880"}
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "a1s2d33880"], ["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.4ms)  commit transaction
Completed 200 OK in 7ms (ActiveRecord: 4.8ms)
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 10], ["LIMIT", 1]]
  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.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Dummy API user created by gds-sso"], ["uid", "4114"], ["email", "dummyapiuser@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.6ms)  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", 12]]
   (4.5ms)  commit transaction
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:10:59 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 5ms (ActiveRecord: 0.0ms)
Started GET "/" for 127.0.0.1 at 2020-07-28 10:10:59 +0000
Processing by ExampleController#index as HTML
  Rendering text template
  Rendered text template (0.0ms)
Completed 200 OK in 2ms (Views: 2.2ms | ActiveRecord: 0.0ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:10:59 +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-07-28 10:10:59 +0000
Started GET "/auth/gds/callback?code=XIrk0A1OcJZLjwOKsphEfEP_i18paaY1G6XKN85PNoA&state=a663c9e16160f9fd0c5ed4cb4ded2075230c98d848c828cc" for 127.0.0.1 at 2020-07-28 10:11:00 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"XIrk0A1OcJZLjwOKsphEfEP_i18paaY1G6XKN85PNoA", "state"=>"a663c9e16160f9fd0c5ed4cb4ded2075230c98d848c828cc"}
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.8ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Test User"], ["uid", "integration-uid"], ["email", "test@example-client.com"], ["permissions", "---\n- signin\n"]]
   (8.4ms)  commit transaction
   (0.1ms)  begin transaction
  User Update (0.2ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", "f"], ["id", 13]]
   (3.3ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 17ms (ActiveRecord: 13.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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 2ms (Views: 0.3ms | ActiveRecord: 0.2ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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-07-28 10:11:00 +0000
Started GET "/auth/gds/callback?code=WD6GHKCg2CceSXQtOxpX5uX5Q52y3tLAYG4nJAjNBVw&state=64085a336fb8d8da6d3e32b6b7085cda856a0fb03fcaac46" for 127.0.0.1 at 2020-07-28 10:11:00 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"WD6GHKCg2CceSXQtOxpX5uX5Q52y3tLAYG4nJAjNBVw", "state"=>"64085a336fb8d8da6d3e32b6b7085cda856a0fb03fcaac46"}
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.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 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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-07-28 10:11:00 +0000
Started GET "/auth/gds/callback?code=iwwQkvHOLQi9vgrkLGQiTUuM8AIMHyyDj57VPjM2zoQ&state=e3f1de3579ed6acfb19a247be93dadfaa4c02116c49718f6" for 127.0.0.1 at 2020-07-28 10:11:00 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"iwwQkvHOLQi9vgrkLGQiTUuM8AIMHyyDj57VPjM2zoQ", "state"=>"e3f1de3579ed6acfb19a247be93dadfaa4c02116c49718f6"}
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.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 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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 "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11: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-07-28 10:11:00 +0000
Started GET "/auth/gds/callback?code=5fq0RKvlzv0B9qQsCEyhdd88h2JBCkiUtBUSdtGLpZE&state=8b5bf5abbd9de238e72dfe27d5122e14bcf1d9babe86c6f0" for 127.0.0.1 at 2020-07-28 10:11:00 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"5fq0RKvlzv0B9qQsCEyhdd88h2JBCkiUtBUSdtGLpZE", "state"=>"8b5bf5abbd9de238e72dfe27d5122e14bcf1d9babe86c6f0"}
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.1ms)  begin transaction
   (0.1ms)  commit transaction
   (0.1ms)  begin transaction
   (0.0ms)  commit transaction
Redirected to http://www.example-client.com/this_requires_signin_permission
Completed 302 Found in 3ms (ActiveRecord: 0.4ms)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11: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.3ms | ActiveRecord: 0.1ms)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11: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-07-28 10:11:00 +0000
Started GET "/auth/gds/callback?code=Y6UMFRXTtBvR0Xt7fmZMoYz7B_qLyB85fCAauwmGJVo&state=b9b2e0f203aa00fbc610c7399455b8e87c5b4ce94369d959" for 127.0.0.1 at 2020-07-28 10:11:01 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"Y6UMFRXTtBvR0Xt7fmZMoYz7B_qLyB85fCAauwmGJVo", "state"=>"b9b2e0f203aa00fbc610c7399455b8e87c5b4ce94369d959"}
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.1ms)  begin transaction
   (0.1ms)  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 3ms (ActiveRecord: 0.4ms)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:01 +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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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-07-28 10:11:01 +0000
Started GET "/auth/gds/callback?code=fteVPA642fJHI-UDcDBpcDoij_VODquH_w4dUBvrCk4&state=33f56805f43198fbf151d75bd5e80ea858af449826755474" for 127.0.0.1 at 2020-07-28 10:11:01 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"fteVPA642fJHI-UDcDBpcDoij_VODquH_w4dUBvrCk4", "state"=>"33f56805f43198fbf151d75bd5e80ea858af449826755474"}
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.1ms)  begin transaction
   (0.4ms)  commit transaction
   (0.0ms)  begin transaction
   (0.1ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 4ms (ActiveRecord: 0.8ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16: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-07-29 06:16:01 +0000
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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-07-28 10:11:01 +0000
Started GET "/auth/gds/callback?code=I4g-SNUSGUIlrh0zevm1P9RkqZeEuUMGZ5IM5K6nfKc&state=3a1c7bfa60afd57a7f1eba98d4ec07a5391094b798b42295" for 127.0.0.1 at 2020-07-28 10:11:01 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"I4g-SNUSGUIlrh0zevm1P9RkqZeEuUMGZ5IM5K6nfKc", "state"=>"3a1c7bfa60afd57a7f1eba98d4ec07a5391094b798b42295"}
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.1ms)  begin transaction
   (0.1ms)  commit transaction
   (0.1ms)  begin transaction
   (0.1ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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.3ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16: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-07-29 06:16:01 +0000
Started GET "/auth/gds/callback?code=uuYU7T9saWv_WHz16C37qVCQx1yhVm9TpQYrcxkYmFc&state=3a809c5e8ef281168837d2ffc744a23c5d863085b40a1c7b" for 127.0.0.1 at 2020-07-29 06:16:01 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"uuYU7T9saWv_WHz16C37qVCQx1yhVm9TpQYrcxkYmFc", "state"=>"3a809c5e8ef281168837d2ffc744a23c5d863085b40a1c7b"}
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.1ms)  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-07-29 06:16: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.3ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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-07-28 10:11:01 +0000
Started GET "/auth/gds/callback?code=0KvD-U3mbOr7IQCo9xGjz5XFI2ISiBQ3-Fkard2qMss&state=03fcadf877cb14fd2d698e2a7259325f1db0a61891e8ef38" for 127.0.0.1 at 2020-07-28 10:11:01 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"0KvD-U3mbOr7IQCo9xGjz5XFI2ISiBQ3-Fkard2qMss", "state"=>"03fcadf877cb14fd2d698e2a7259325f1db0a61891e8ef38"}
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.2ms)  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 4ms (ActiveRecord: 0.5ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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.3ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:06: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.3ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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-07-28 10:11:01 +0000
Started GET "/auth/gds/callback?code=lkMut4J9C-GGDBDF29-8lz6yZNhdI26xdK5sgAvBLaA&state=282d995502a62b1832b6f0220fd3996160caa61ac071b576" for 127.0.0.1 at 2020-07-28 10:11:01 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"lkMut4J9C-GGDBDF29-8lz6yZNhdI26xdK5sgAvBLaA", "state"=>"282d995502a62b1832b6f0220fd3996160caa61ac071b576"}
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.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 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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.3ms | ActiveRecord: 0.1ms)
  User Load (0.2ms)  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 Update (0.3ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", "t"], ["id", 13]]
   (7.1ms)  commit transaction
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:02 +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-07-28 10:11:02 +0000
Started GET "/auth/gds/callback?code=0tod4KmKNl5dbJ9u5nGbam9VmIFAdW_tp6OAdsA1xr8&state=b577b9071423563943a94e5a61a755689c8818337e0e72c6" for 127.0.0.1 at 2020-07-28 10:11:02 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"0tod4KmKNl5dbJ9u5nGbam9VmIFAdW_tp6OAdsA1xr8", "state"=>"b577b9071423563943a94e5a61a755689c8818337e0e72c6"}
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.1ms)  begin transaction
   (0.0ms)  commit transaction
   (0.0ms)  begin transaction
  User Update (0.3ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", "f"], ["id", 13]]
   (6.0ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 10ms (ActiveRecord: 6.6ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:02 +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 2ms (Views: 0.4ms | ActiveRecord: 0.2ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:02 +0000
Processing by ExampleController#restricted as JSON
  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
  User Update (0.2ms)  UPDATE "users" SET "disabled" = ? WHERE "users"."id" = ?  [["disabled", nil], ["id", 13]]
   (3.9ms)  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 55ms (Views: 0.3ms | ActiveRecord: 4.8ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:02 +0000
Processing by ExampleController#restricted as JSON
Completed   in 11ms (ActiveRecord: 0.0ms)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:02 +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.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
  CACHE User Load (0.0ms)  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.0ms)  commit transaction
  Rendering text template
  Rendered text template (0.0ms)
Completed 200 OK in 50ms (Views: 0.4ms | ActiveRecord: 0.7ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:02 +0000
Processing by ExampleController#restricted as JSON
Completed   in 17ms (ActiveRecord: 0.0ms)
   (1.4ms)  SELECT sqlite_version(*)
   (0.1ms)  SELECT sqlite_version(*)
   (0.2ms)  DROP TABLE IF EXISTS "users"
   (9.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)
   (4.4ms)  CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL)
  ActiveRecord::InternalMetadata Load (0.2ms)  SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  ActiveRecord::InternalMetadata Create (0.3ms)  INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["key", "environment"], ["value", "test"], ["created_at", "2020-07-28 10:11:11.696416"], ["updated_at", "2020-07-28 10:11:11.696416"]]
   (4.7ms)  commit transaction
   (3.3ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)
  ActiveRecord::InternalMetadata Load (0.2ms)  SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (1.4ms)  SELECT sqlite_version(*)
  User Load (0.2ms)  SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "asd"], ["LIMIT", 1]]
  User Load (0.2ms)  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.3ms)  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.0ms)  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.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d37378"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (11.3ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d31593"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (12.2ms)  commit transaction
Processing by Api::UserController#update as HTML
  Parameters: {"uid"=>"a1s2d37378"}
  Rendering /var/lib/jenkins/workspace/gds-sso_master-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (Duration: 0.6ms | Allocations: 265)
  Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary.
Completed 403 Forbidden in 8ms (Views: 5.3ms | ActiveRecord: 0.0ms | Allocations: 3265)
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d33604"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.5ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d38516"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.2ms)  commit transaction
Processing by Api::UserController#update as HTML
  Parameters: {"uid"=>"a1s2d33604"}
  User Load (0.1ms)  SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "a1s2d33604"], ["LIMIT", 1]]
   (0.1ms)  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", 4]]
   (3.1ms)  commit transaction
Completed 200 OK in 6ms (ActiveRecord: 3.5ms | Allocations: 1313)
  User Load (0.2ms)  SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 4], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d31894"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.5ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d36660"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.2ms)  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: 522)
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d36194"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.1ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d32577"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.5ms)  commit transaction
Processing by Api::UserController#reauth as HTML
  Parameters: {"uid"=>"a1s2d36194"}
  Rendering /var/lib/jenkins/workspace/gds-sso_master-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (Duration: 0.2ms | Allocations: 56)
  Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary.
Completed 403 Forbidden in 2ms (Views: 1.1ms | ActiveRecord: 0.0ms | Allocations: 514)
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d31351"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.2ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d32956"], ["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"=>"a1s2d31351"}
  User Load (0.1ms)  SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "a1s2d31351"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  User Update (0.2ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", 1], ["id", 10]]
   (3.2ms)  commit transaction
Completed 200 OK in 6ms (ActiveRecord: 3.6ms | Allocations: 928)
  User Load (0.1ms)  SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 10], ["LIMIT", 1]]
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:12 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 4ms (ActiveRecord: 0.0ms | Allocations: 162)
Started GET "/" for 127.0.0.1 at 2020-07-28 10:11:12 +0000
Processing by ExampleController#index as HTML
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 1ms (Views: 1.0ms | ActiveRecord: 0.0ms | Allocations: 475)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:12 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 115)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:12 +0000
Started GET "/auth/gds/callback?code=aFjAs4wwnCYsih8y8lFaG-GVdG2n1uHgaHiDbqvWGRA&state=105756fa9458618cf24e279350e44cb409bd6b25b1ee0d79" for 127.0.0.1 at 2020-07-28 10:11:12 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"aFjAs4wwnCYsih8y8lFaG-GVdG2n1uHgaHiDbqvWGRA", "state"=>"105756fa9458618cf24e279350e44cb409bd6b25b1ee0d79"}
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.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Test User"], ["uid", "integration-uid"], ["email", "test@example-client.com"], ["permissions", "---\n- signin\n"]]
   (4.7ms)  commit transaction
   (0.1ms)  begin transaction
  User Update (0.2ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", 0], ["id", 12]]
   (4.4ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 14ms (ActiveRecord: 10.0ms | Allocations: 1592)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:12 +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", 0], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 2ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 731)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:12 +0000
Processing by ExampleController#this_requires_signin_permission as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 113)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:12 +0000
Started GET "/auth/gds/callback?code=UoqQuZKYHON4cJVzd03wNP0Fzj09csPTYFNMMJ6B7hA&state=230f7021c6c21a0e3ae9a32d44d76634f4b5eada178a37e8" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"UoqQuZKYHON4cJVzd03wNP0Fzj09csPTYFNMMJ6B7hA", "state"=>"230f7021c6c21a0e3ae9a32d44d76634f4b5eada178a37e8"}
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]]
Redirected to http://www.example-client.com/this_requires_signin_permission
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 1016)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:13 +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 2ms (Views: 0.4ms | ActiveRecord: 0.1ms | Allocations: 706)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by ExampleController#this_requires_signin_permission as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 113)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Started GET "/auth/gds/callback?code=70nwMjegd4XlaKu7HNf13FrTkz5W_rk7F6FreB_m2r8&state=5ef9993d6f09d4d162928d9173a1d5d3c570c16c49f8eee5" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"70nwMjegd4XlaKu7HNf13FrTkz5W_rk7F6FreB_m2r8", "state"=>"5ef9993d6f09d4d162928d9173a1d5d3c570c16c49f8eee5"}
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]]
Redirected to http://www.example-client.com/this_requires_signin_permission
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 1016)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:13 +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.4ms | ActiveRecord: 0.2ms | Allocations: 706)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 113)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Started GET "/auth/gds/callback?code=dTqlS-0Xq0T9HdEE0WoDRhvKL5K9-rfP6kF3c5gEAWo&state=c492c8c76e68cc3470317e8c77fbd009db706a37995d3447" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"dTqlS-0Xq0T9HdEE0WoDRhvKL5K9-rfP6kF3c5gEAWo", "state"=>"c492c8c76e68cc3470317e8c77fbd009db706a37995d3447"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 1016)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:13 +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.4ms | ActiveRecord: 0.1ms | Allocations: 706)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 113)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Started GET "/auth/gds/callback?code=98uew1cTav8MSNecc31YVR8YDtu9OIeaOJb2qEomgD8&state=bab999a3c0d2952fd4f34d3ae7df1bd3cce905daaea0adc0" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"98uew1cTav8MSNecc31YVR8YDtu9OIeaOJb2qEomgD8", "state"=>"bab999a3c0d2952fd4f34d3ae7df1bd3cce905daaea0adc0"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 1016)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:13 +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.3ms | ActiveRecord: 0.1ms | Allocations: 706)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 113)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Started GET "/auth/gds/callback?code=eZufCW2sAKLT6PdExAMXK9drlUiuP8wALWbeeTdqbIY&state=533e795cc72dd064e022b56bee0acf097fdeb74cc209cd00" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"eZufCW2sAKLT6PdExAMXK9drlUiuP8wALWbeeTdqbIY", "state"=>"533e795cc72dd064e022b56bee0acf097fdeb74cc209cd00"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 1020)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:13 +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.3ms | ActiveRecord: 0.1ms | Allocations: 709)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:06:13 +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.3ms | ActiveRecord: 0.1ms | Allocations: 929)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:13 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 113)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Started GET "/auth/gds/callback?code=5Unedqs9r-W_C-K_4WgsScBjKNJpiS31nHl3fzP7OMs&state=83d3e8d784318705bce7d00f45e6a4eb68039fa61e3e5141" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"5Unedqs9r-W_C-K_4WgsScBjKNJpiS31nHl3fzP7OMs", "state"=>"83d3e8d784318705bce7d00f45e6a4eb68039fa61e3e5141"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 1016)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:14 +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.4ms | ActiveRecord: 0.1ms | Allocations: 706)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16:14 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 1ms (ActiveRecord: 0.0ms | Allocations: 499)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-29 06:16:14 +0000
Started GET "/auth/gds/callback?code=xMQ-HoOr0S2m7lo11s9LSrZjM7OcnnWvhEzjHLAo9yI&state=f75c8a45dfff87d84935f9c9775eb862203bf372a0158ecc" for 127.0.0.1 at 2020-07-29 06:16:14 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"xMQ-HoOr0S2m7lo11s9LSrZjM7OcnnWvhEzjHLAo9yI", "state"=>"f75c8a45dfff87d84935f9c9775eb862203bf372a0158ecc"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 1218)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16:14 +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", 0], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 2ms (Views: 0.3ms | ActiveRecord: 0.2ms | Allocations: 931)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 113)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Started GET "/auth/gds/callback?code=Agxoyv8mKmmRsiQnuIFYhe5fSfvO2G3qkzq_TL5SRfI&state=408db98efb6da62cb34c6f41e01cb5715e72ac856538e8fe" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"Agxoyv8mKmmRsiQnuIFYhe5fSfvO2G3qkzq_TL5SRfI", "state"=>"408db98efb6da62cb34c6f41e01cb5715e72ac856538e8fe"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 1016)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:14 +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", 0], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 2ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 706)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16:14 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 1ms (ActiveRecord: 0.0ms | Allocations: 499)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-29 06:16:14 +0000
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 113)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Started GET "/auth/gds/callback?code=JAilgkkSwSh7mM0JcpjQ_pHblu106hx6MxAA_a3NVzM&state=9e438e307f854d56ff9e71f8662eeb4781f366b28276370c" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"JAilgkkSwSh7mM0JcpjQ_pHblu106hx6MxAA_a3NVzM", "state"=>"9e438e307f854d56ff9e71f8662eeb4781f366b28276370c"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 1016)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:14 +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.3ms | ActiveRecord: 0.1ms | Allocations: 706)
  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 Update (0.3ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", 1], ["id", 12]]
   (4.9ms)  commit transaction
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:14 +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: 575)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Started GET "/auth/gds/callback?code=0CKodWNlhfpLIzo7X1jEXf3Vp2MlJhPeMvagU2OhLqU&state=6dc239a4a1e50e667c93ec1a9ff694589798cd05bcc3a671" for 127.0.0.1 at 2020-07-28 10:11:14 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"0CKodWNlhfpLIzo7X1jEXf3Vp2MlJhPeMvagU2OhLqU", "state"=>"6dc239a4a1e50e667c93ec1a9ff694589798cd05bcc3a671"}
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.1ms)  begin transaction
  User Update (0.3ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", 0], ["id", 12]]
   (5.0ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 9ms (ActiveRecord: 5.6ms | Allocations: 1226)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:15 +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", 0], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.1ms | Allocations: 1)
Completed 200 OK in 2ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 704)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:15 +0000
Processing by ExampleController#this_requires_signin_permission as JSON
  User Load (0.2ms)  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.3ms)  UPDATE "users" SET "disabled" = ? WHERE "users"."id" = ?  [["disabled", nil], ["id", 12]]
   (3.4ms)  commit transaction
  User Load (0.2ms)  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 96ms (Views: 0.3ms | ActiveRecord: 4.2ms | Allocations: 7451)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:15 +0000
Processing by ExampleController#restricted as JSON
  User Load (0.2ms)  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 87ms (Views: 0.3ms | ActiveRecord: 0.3ms | Allocations: 6804)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:15 +0000
Processing by ExampleController#restricted as JSON
Completed   in 31ms (ActiveRecord: 0.0ms | Allocations: 1852)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:15 +0000
Processing by ExampleController#restricted as JSON
Completed   in 16ms (ActiveRecord: 0.0ms | Allocations: 1958)
  User Load (0.2ms)  SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ?  [["email", "dummyapiuser@domain.com"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Dummy API user created by gds-sso"], ["uid", "2568"], ["email", "dummyapiuser@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.2ms)  commit transaction
   (0.1ms)  begin transaction
  User Update (0.2ms)  UPDATE "users" SET "permissions" = ? WHERE "users"."id" = ?  [["permissions", "---\n- signin\n- extra_permission\n"], ["id", 13]]
   (4.0ms)  commit transaction
   (0.1ms)  DROP TABLE IF EXISTS "users"
   (1.4ms)  SELECT sqlite_version(*)
   (4.0ms)  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')
   (4.9ms)  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.2ms)  SELECT  "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.1ms)  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-07-28 10:11:20.563604"], ["updated_at", "2020-07-28 10:11:20.563604"]]
   (4.7ms)  commit transaction
   (3.3ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)
  ActiveRecord::InternalMetadata Load (0.2ms)  SELECT  "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.0ms)  begin transaction
   (0.0ms)  commit transaction
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ?  [["email", "dummyapiuser@domain.com"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Dummy API user created by gds-sso"], ["uid", "567"], ["email", "dummyapiuser@domain.com"], ["permissions", "---\n- signin\n"]]
   (18.5ms)  commit transaction
   (0.1ms)  begin transaction
  User Update (0.3ms)  UPDATE "users" SET "permissions" = ? WHERE "users"."id" = ?  [["permissions", "---\n- signin\n- extra_permission\n"], ["id", 1]]
   (29.7ms)  commit transaction
  User Load (0.3ms)  SELECT  "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "asd"], ["LIMIT", 1]]
  User Load (0.2ms)  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.3ms)  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]]
   (14.3ms)  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.1ms)  commit transaction
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:21 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 5ms (ActiveRecord: 0.0ms)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:21 +0000
Processing by ExampleController#this_requires_signin_permission as JSON
  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.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions", "disabled") VALUES (?, ?, ?, ?, ?)  [["name", "Test User"], ["uid", "integration-uid"], ["email", "test@example-client.com"], ["permissions", "---\n- signin\n"], ["disabled", nil]]
   (5.8ms)  commit transaction
  User Load (0.2ms)  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
  CACHE User Load (0.0ms)  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
  CACHE User Load (0.0ms)  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
  User Update (0.3ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", "f"], ["id", 3]]
   (16.6ms)  commit transaction
  Rendering text template
  Rendered text template (0.0ms)
Completed 200 OK in 104ms (Views: 3.5ms | ActiveRecord: 24.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:21 +0000
Processing by ExampleController#restricted as JSON
Completed   in 18ms (ActiveRecord: 0.0ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:21 +0000
Processing by ExampleController#restricted as JSON
Completed   in 23ms (ActiveRecord: 0.0ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:21 +0000
Processing by ExampleController#restricted as JSON
  User Load (0.2ms)  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
  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.1ms)  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.1ms)  begin transaction
   (0.2ms)  commit transaction
   (0.0ms)  begin transaction
   (0.0ms)  commit transaction
  Rendering text template
  Rendered text template (0.0ms)
Completed 200 OK in 59ms (Views: 0.4ms | ActiveRecord: 0.9ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:21 +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-07-28 10:11:21 +0000
Started GET "/auth/gds/callback?code=P_LklHe2gx1uTfoC98GPH4WLvzyBTLts-VPXCS2FP7Y&state=f84bb098719835d98b54b321d635174357451dcf2d7fb07c" for 127.0.0.1 at 2020-07-28 10:11:22 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"P_LklHe2gx1uTfoC98GPH4WLvzyBTLts-VPXCS2FP7Y", "state"=>"f84bb098719835d98b54b321d635174357451dcf2d7fb07c"}
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.1ms)  begin transaction
  User Update (0.3ms)  UPDATE "users" SET "disabled" = ? WHERE "users"."id" = ?  [["disabled", "f"], ["id", 3]]
   (14.9ms)  commit transaction
   (0.0ms)  begin transaction
   (0.0ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 20ms (ActiveRecord: 15.5ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:22 +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 2ms (Views: 0.3ms | ActiveRecord: 0.2ms)
Started GET "/" for 127.0.0.1 at 2020-07-28 10:11:22 +0000
Processing by ExampleController#index as HTML
  Rendering text template
  Rendered text template (0.0ms)
Completed 200 OK in 0ms (Views: 0.3ms | ActiveRecord: 0.0ms)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:22 +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-07-28 10:11:22 +0000
Started GET "/auth/gds/callback?code=ZN0r75qfZtwcnW6sVRGBHXb0qIrOaa3n93X6wzYWnSc&state=bb5593d63fd3eda7b2055ad46376964c8772acef1320a183" for 127.0.0.1 at 2020-07-28 10:11:22 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"ZN0r75qfZtwcnW6sVRGBHXb0qIrOaa3n93X6wzYWnSc", "state"=>"bb5593d63fd3eda7b2055ad46376964c8772acef1320a183"}
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.1ms)  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 4ms (ActiveRecord: 0.4ms)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:22 +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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:22 +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-07-28 10:11:22 +0000
Started GET "/auth/gds/callback?code=XtsuQ4QET_S_LEFerL4SktH8OuB2fop_qw4VQPvNw6c&state=5a09c642a67e0fbe4c5ca5ccf72f98dd5b4c377fe563f392" for 127.0.0.1 at 2020-07-28 10:11:22 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"XtsuQ4QET_S_LEFerL4SktH8OuB2fop_qw4VQPvNw6c", "state"=>"5a09c642a67e0fbe4c5ca5ccf72f98dd5b4c377fe563f392"}
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.1ms)  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 4ms (ActiveRecord: 0.4ms)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:22 +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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:22 +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-07-28 10:11:22 +0000
Started GET "/auth/gds/callback?code=J8lwR8nnXnHwMNcdF_S81OwcCWATJVORinj2_XO5f18&state=1569939c766c6757642d0842377794a7f6e2aa0da000f731" for 127.0.0.1 at 2020-07-28 10:11:22 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"J8lwR8nnXnHwMNcdF_S81OwcCWATJVORinj2_XO5f18", "state"=>"1569939c766c6757642d0842377794a7f6e2aa0da000f731"}
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.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 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:22 +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-07-28 10:11:22 +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-07-28 10:11:22 +0000
Started GET "/auth/gds/callback?code=blV2C8QALQ9e_f_NxQbtbqmeUixHRtnNwyHwEEr_k8o&state=520555c8cc018aadbfa6bee8f3f70f698a9b24bcafe43b85" for 127.0.0.1 at 2020-07-28 10:11:23 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"blV2C8QALQ9e_f_NxQbtbqmeUixHRtnNwyHwEEr_k8o", "state"=>"520555c8cc018aadbfa6bee8f3f70f698a9b24bcafe43b85"}
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.1ms)  begin transaction
   (0.2ms)  commit transaction
   (0.1ms)  begin transaction
   (0.0ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 4ms (ActiveRecord: 0.6ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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", "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-07-28 10:11:23 +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-07-28 10:11:23 +0000
Started GET "/auth/gds/callback?code=nYFRLgvp9Vh7yfvxdfyHawy3FV2pA008ZSkUYGAtagQ&state=5210e4a9a5a51d3b2702667414ec72d5149a6e5fc6c5c3bc" for 127.0.0.1 at 2020-07-28 10:11:23 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"nYFRLgvp9Vh7yfvxdfyHawy3FV2pA008ZSkUYGAtagQ", "state"=>"5210e4a9a5a51d3b2702667414ec72d5149a6e5fc6c5c3bc"}
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.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 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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", "f"], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (0.0ms)
Completed 200 OK in 2ms (Views: 0.3ms | 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.3ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", "t"], ["id", 3]]
   (4.1ms)  commit transaction
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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", "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-07-28 10:11:23 +0000
Started GET "/auth/gds/callback?code=fCAi8JIcOFPJoTA2vL5a-PXCiUw9rzKafqtA0re-IgE&state=1938240ddcc5b1d715fd2191a14b8b0d0fd4bc733a50ae01" for 127.0.0.1 at 2020-07-28 10:11:23 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"fCAi8JIcOFPJoTA2vL5a-PXCiUw9rzKafqtA0re-IgE", "state"=>"1938240ddcc5b1d715fd2191a14b8b0d0fd4bc733a50ae01"}
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.1ms)  begin transaction
   (0.0ms)  commit transaction
   (0.0ms)  begin transaction
  User Update (0.3ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", "f"], ["id", 3]]
   (4.1ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 8ms (ActiveRecord: 4.7ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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", "f"], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (0.0ms)
Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:23 +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-07-28 10:11:23 +0000
Started GET "/auth/gds/callback?code=WPs6YBEZPQypco2N5D-vHK55aJbmPOM12Ptdpm-G910&state=34cf706bcca8af2534e84758deb3357d60aa0e70ad1f4d2f" for 127.0.0.1 at 2020-07-28 10:11:23 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"WPs6YBEZPQypco2N5D-vHK55aJbmPOM12Ptdpm-G910", "state"=>"34cf706bcca8af2534e84758deb3357d60aa0e70ad1f4d2f"}
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.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 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11: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", "f"], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (0.0ms)
Completed 200 OK in 2ms (Views: 0.4ms | ActiveRecord: 0.1ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16:23 +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-07-29 06:16:23 +0000
Started GET "/auth/gds/callback?code=OYWbMAH0X6LIjZDJMTZT-57akHXHJ0dc5gsvchFSfYU&state=780869fba9ba3290e6a5ed74cb4dc1f4be362e657275990a" for 127.0.0.1 at 2020-07-29 06:16:23 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"OYWbMAH0X6LIjZDJMTZT-57akHXHJ0dc5gsvchFSfYU", "state"=>"780869fba9ba3290e6a5ed74cb4dc1f4be362e657275990a"}
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.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 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16: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", "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-07-28 10:11:23 +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-07-28 10:11:23 +0000
Started GET "/auth/gds/callback?code=uLPgIDvNdONxlHYAhrP-Z9qFihfQw8JccqQTnst9sFk&state=6dd140252e8d98b66bd282ff41d795913891fc09025b10b6" for 127.0.0.1 at 2020-07-28 10:11:24 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"uLPgIDvNdONxlHYAhrP-Z9qFihfQw8JccqQTnst9sFk", "state"=>"6dd140252e8d98b66bd282ff41d795913891fc09025b10b6"}
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.1ms)  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 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:24 +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-07-29 06:06:24 +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-07-28 10:11:24 +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-07-28 10:11:24 +0000
Started GET "/auth/gds/callback?code=HyaBgBCoecHgzI0rmkV0BmJo4Gm3yU719FzMP8B5Z5I&state=f6ad088570995cb0cd16ad099e57194bba41b9e0d79e4095" for 127.0.0.1 at 2020-07-28 10:11:24 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"HyaBgBCoecHgzI0rmkV0BmJo4Gm3yU719FzMP8B5Z5I", "state"=>"f6ad088570995cb0cd16ad099e57194bba41b9e0d79e4095"}
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.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 4ms (ActiveRecord: 0.4ms)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:24 +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-07-29 06:16:24 +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-07-29 06:16:24 +0000
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d31368"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.5ms)  commit transaction
   (0.0ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d39668"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.1ms)  commit transaction
Processing by Api::UserController#update as HTML
  Parameters: {"uid"=>"a1s2d31368"}
  Rendering /var/lib/jenkins/workspace/gds-sso_master-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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 5ms (Views: 5.0ms | ActiveRecord: 0.0ms)
   (0.0ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d31349"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.3ms)  commit transaction
   (0.0ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d39882"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.4ms)  commit transaction
Processing by Api::UserController#update as HTML
  Parameters: {"uid"=>"a1s2d31349"}
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "a1s2d31349"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  User Update (0.3ms)  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", 6]]
   (3.0ms)  commit transaction
Completed 200 OK in 6ms (ActiveRecord: 3.5ms)
  User Load (0.2ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 6], ["LIMIT", 1]]
   (0.0ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d37989"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.9ms)  commit transaction
   (0.0ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d31905"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (2.9ms)  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", "a1s2d35833"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.3ms)  commit transaction
   (0.0ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d37396"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.0ms)  commit transaction
Processing by Api::UserController#reauth as HTML
  Parameters: {"uid"=>"a1s2d35833"}
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "a1s2d35833"], ["LIMIT", 1]]
   (0.0ms)  begin transaction
  User Update (0.2ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", "t"], ["id", 10]]
   (4.0ms)  commit transaction
Completed 200 OK in 6ms (ActiveRecord: 4.3ms)
  User Load (0.1ms)  SELECT  "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 10], ["LIMIT", 1]]
   (0.0ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d34035"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.8ms)  commit transaction
   (0.0ms)  begin transaction
  User Create (0.2ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d31271"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.7ms)  commit transaction
Processing by Api::UserController#reauth as HTML
  Parameters: {"uid"=>"a1s2d34035"}
  Rendering /var/lib/jenkins/workspace/gds-sso_master-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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 1ms (Views: 0.9ms | ActiveRecord: 0.0ms)
   (1.5ms)  SELECT sqlite_version(*)
   (0.1ms)  SELECT sqlite_version(*)
   (0.2ms)  DROP TABLE IF EXISTS "users"
   (5.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 0)
   (6.7ms)  CREATE TABLE "ar_internal_metadata" ("key" varchar NOT NULL PRIMARY KEY, "value" varchar, "created_at" datetime(6) NOT NULL, "updated_at" datetime(6) NOT NULL)
  ActiveRecord::InternalMetadata Load (0.2ms)  SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  ActiveRecord::InternalMetadata Create (0.3ms)  INSERT INTO "ar_internal_metadata" ("key", "value", "created_at", "updated_at") VALUES (?, ?, ?, ?)  [["key", "environment"], ["value", "test"], ["created_at", "2020-07-28 10:11:32.682361"], ["updated_at", "2020-07-28 10:11:32.682361"]]
   (3.6ms)  commit transaction
   (3.4ms)  CREATE TABLE "schema_migrations" ("version" varchar NOT NULL PRIMARY KEY)
  ActiveRecord::InternalMetadata Load (0.2ms)  SELECT "ar_internal_metadata".* FROM "ar_internal_metadata" WHERE "ar_internal_metadata"."key" = ? LIMIT ?  [["key", "environment"], ["LIMIT", 1]]
   (0.1ms)  SELECT sqlite_version(*)
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d36619"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.1ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d35636"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.4ms)  commit transaction
Processing by Api::UserController#reauth as HTML
  Parameters: {"uid"=>"nonexistent-user"}
  User Load (0.2ms)  SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "nonexistent-user"], ["LIMIT", 1]]
Completed 200 OK in 3ms (ActiveRecord: 0.2ms | Allocations: 1427)
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d35989"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (6.0ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d33992"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.5ms)  commit transaction
Processing by Api::UserController#reauth as HTML
  Parameters: {"uid"=>"a1s2d35989"}
  Rendering /var/lib/jenkins/workspace/gds-sso_master-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (Duration: 1.1ms | Allocations: 201)
  Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary.
Completed 403 Forbidden in 7ms (Views: 6.3ms | ActiveRecord: 0.0ms | Allocations: 1977)
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d38773"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.3ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d36879"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.5ms)  commit transaction
Processing by Api::UserController#reauth as HTML
  Parameters: {"uid"=>"a1s2d38773"}
  User Load (0.1ms)  SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "a1s2d38773"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  User Update (0.2ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", 1], ["id", 5]]
   (3.4ms)  commit transaction
Completed 200 OK in 6ms (ActiveRecord: 3.9ms | Allocations: 930)
  User Load (0.2ms)  SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 5], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d35174"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (4.2ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d33679"], ["email", "ssopushuser@legit.com"], ["permissions", "---\n- signin\n- user_update_permission\n"]]
   (3.4ms)  commit transaction
Processing by Api::UserController#update as HTML
  Parameters: {"uid"=>"a1s2d35174"}
  Rendering /var/lib/jenkins/workspace/gds-sso_master-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/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-7MNGHJETCC7W4ACW54GEWCZ7RU2E7EW7NZYEBGCKG2QG3YLPM53Q/app/views/authorisations/unauthorised.html.erb within layouts/unauthorised (Duration: 0.2ms | Allocations: 47)
  Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary.
Completed 403 Forbidden in 1ms (Views: 0.8ms | ActiveRecord: 0.0ms | Allocations: 510)
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Moshua Jarshall"], ["uid", "a1s2d34309"], ["email", "old@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.7ms)  commit transaction
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "SSO Push user"], ["uid", "a1s2d35617"], ["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"=>"a1s2d34309"}
  User Load (0.2ms)  SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "a1s2d34309"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  User Update (0.3ms)  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", 9]]
   (3.9ms)  commit transaction
Completed 200 OK in 7ms (ActiveRecord: 4.4ms | Allocations: 1227)
  User Load (0.1ms)  SELECT "users".* FROM "users" WHERE "users"."id" = ? LIMIT ?  [["id", 9], ["LIMIT", 1]]
  User Load (0.2ms)  SELECT "users".* FROM "users" WHERE "users"."email" = ? ORDER BY "users"."id" ASC LIMIT ?  [["email", "dummyapiuser@domain.com"], ["LIMIT", 1]]
   (0.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Dummy API user created by gds-sso"], ["uid", "178"], ["email", "dummyapiuser@domain.com"], ["permissions", "---\n- signin\n"]]
   (3.6ms)  commit transaction
   (0.1ms)  begin transaction
  User Update (0.2ms)  UPDATE "users" SET "permissions" = ? WHERE "users"."id" = ?  [["permissions", "---\n- signin\n- extra_permission\n"], ["id", 11]]
   (3.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.1ms)  begin transaction
  User Create (0.3ms)  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]]
   (3.6ms)  commit transaction
  User Load (0.2ms)  SELECT "users".* FROM "users" WHERE "users"."uid" = ? ORDER BY "users"."id" ASC LIMIT ?  [["uid", "asd"], ["LIMIT", 1]]
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:33 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 5ms (ActiveRecord: 0.0ms | Allocations: 151)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:33 +0000
Started GET "/auth/gds/callback?code=Oj1D9udBilzd78OsJhnpbTQJLx4hXFgbR67DHw727LI&state=a886423e6e48f93b1942ddd0d78f58401e450f76e8246dfd" for 127.0.0.1 at 2020-07-28 10:11:33 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"Oj1D9udBilzd78OsJhnpbTQJLx4hXFgbR67DHw727LI", "state"=>"a886423e6e48f93b1942ddd0d78f58401e450f76e8246dfd"}
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.1ms)  begin transaction
  User Create (0.3ms)  INSERT INTO "users" ("name", "uid", "email", "permissions") VALUES (?, ?, ?, ?)  [["name", "Test User"], ["uid", "integration-uid"], ["email", "test@example-client.com"], ["permissions", "---\n- signin\n"]]
   (5.9ms)  commit transaction
   (0.1ms)  begin transaction
  User Update (0.2ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", 0], ["id", 13]]
   (5.0ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 17ms (ActiveRecord: 11.9ms | Allocations: 1447)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:33 +0000
Processing by ExampleController#restricted as HTML
  User Load (0.3ms)  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 3ms (Views: 1.1ms | ActiveRecord: 0.3ms | Allocations: 984)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:33 +0000
Processing by ExampleController#this_requires_signin_permission as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 103)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:33 +0000
Started GET "/auth/gds/callback?code=XC0200_RbEtKPMFUFljPkmfCP9oONTmGQRHC7vHw-30&state=19e877bfb32e477fbefcad0bc8bdc4657ac412d389773cf1" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"XC0200_RbEtKPMFUFljPkmfCP9oONTmGQRHC7vHw-30", "state"=>"19e877bfb32e477fbefcad0bc8bdc4657ac412d389773cf1"}
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]]
Redirected to http://www.example-client.com/this_requires_signin_permission
Completed 302 Found in 4ms (ActiveRecord: 0.2ms | Allocations: 913)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:34 +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.3ms | ActiveRecord: 0.2ms | Allocations: 652)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Processing by ExampleController#this_requires_signin_permission as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 103)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Started GET "/auth/gds/callback?code=dso8fTwunwLSSxMq8aftUdfsCJmXMLWM7UVSkKwTKlM&state=80e4f134de98379e8f5d0caff3b19bea7a0c0f10247f2f53" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"dso8fTwunwLSSxMq8aftUdfsCJmXMLWM7UVSkKwTKlM", "state"=>"80e4f134de98379e8f5d0caff3b19bea7a0c0f10247f2f53"}
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]]
Redirected to http://www.example-client.com/this_requires_signin_permission
Completed 302 Found in 4ms (ActiveRecord: 0.2ms | Allocations: 913)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:34 +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 2ms (Views: 0.3ms | ActiveRecord: 0.1ms | Allocations: 652)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 103)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Started GET "/auth/gds/callback?code=xVeVplpFbmzCdPKHt5pMQDxM4fDXTDnR3JuTFBQpwW4&state=83c0b531feddb39d11b6d324f20fc2e1a97d81e5124aed5d" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"xVeVplpFbmzCdPKHt5pMQDxM4fDXTDnR3JuTFBQpwW4", "state"=>"83c0b531feddb39d11b6d324f20fc2e1a97d81e5124aed5d"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 4ms (ActiveRecord: 0.2ms | Allocations: 913)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:34 +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", 0], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 2ms (Views: 0.3ms | ActiveRecord: 0.2ms | Allocations: 655)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 103)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Started GET "/auth/gds/callback?code=vcaFlA1VZB0JvaIvCGfDWpZSZvixa97R1ikQNiZxK9I&state=fb740bb80a69463c6e419ce29d72f9e7439dc16d34826c9d" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"vcaFlA1VZB0JvaIvCGfDWpZSZvixa97R1ikQNiZxK9I", "state"=>"fb740bb80a69463c6e419ce29d72f9e7439dc16d34826c9d"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 4ms (ActiveRecord: 0.2ms | Allocations: 915)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:34 +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.3ms | ActiveRecord: 0.1ms | Allocations: 653)
Started GET "/" for 127.0.0.1 at 2020-07-28 10:11:34 +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.3ms | ActiveRecord: 0.0ms | Allocations: 137)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 103)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:34 +0000
Started GET "/auth/gds/callback?code=4H8LH6jbmLmbqb8xc0DJdHTb6II1aaCnOneJQZA7GFU&state=072113ca0beac7394405bcad7cd25d5d65e44f73af659c3d" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"4H8LH6jbmLmbqb8xc0DJdHTb6II1aaCnOneJQZA7GFU", "state"=>"072113ca0beac7394405bcad7cd25d5d65e44f73af659c3d"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 3ms (ActiveRecord: 0.2ms | Allocations: 913)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:35 +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.4ms | 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.1ms)  begin transaction
  User Update (0.3ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", 1], ["id", 13]]
   (5.4ms)  commit transaction
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:35 +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: 533)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Started GET "/auth/gds/callback?code=4k3GTNNvVGyA_D7WzbeC1adZSkzeJyvzQP-zUEAerRM&state=faf8f1a7b56c569e308b9df75875150d993e06c7a6927412" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"4k3GTNNvVGyA_D7WzbeC1adZSkzeJyvzQP-zUEAerRM", "state"=>"faf8f1a7b56c569e308b9df75875150d993e06c7a6927412"}
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.1ms)  begin transaction
  User Update (0.3ms)  UPDATE "users" SET "remotely_signed_out" = ? WHERE "users"."id" = ?  [["remotely_signed_out", 0], ["id", 13]]
   (3.6ms)  commit transaction
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 8ms (ActiveRecord: 4.2ms | Allocations: 1104)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:35 +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.4ms | ActiveRecord: 0.1ms | Allocations: 650)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 103)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Started GET "/auth/gds/callback?code=n37NLIGMdzx5QimD-cSPPlTVH-9HFe5xu4O1d5TnnSc&state=264e446faf159b884add968d8ad5f004d32bff57cbe582e4" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"n37NLIGMdzx5QimD-cSPPlTVH-9HFe5xu4O1d5TnnSc", "state"=>"264e446faf159b884add968d8ad5f004d32bff57cbe582e4"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 4ms (ActiveRecord: 0.2ms | Allocations: 914)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:35 +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", 0], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 2ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 655)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16:35 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 1ms (ActiveRecord: 0.0ms | Allocations: 480)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-29 06:16:35 +0000
Started GET "/auth/gds/callback?code=KIHHM2-vQD7P2spez07YxyRpTwjuwkBrAWakVC00ezk&state=aec0498ead34d9b6a62a15a9894ffec6ed6e34facb724d40" for 127.0.0.1 at 2020-07-29 06:16:35 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"KIHHM2-vQD7P2spez07YxyRpTwjuwkBrAWakVC00ezk", "state"=>"aec0498ead34d9b6a62a15a9894ffec6ed6e34facb724d40"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 4ms (ActiveRecord: 0.2ms | Allocations: 1115)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16:35 +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", 0], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 2ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 877)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 103)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Started GET "/auth/gds/callback?code=6Yrr-jvsw4txE1DJUE9cBsTcYutw-AEKGjzJfpmOPHY&state=15589942c994ae56728d00e99838552c7d35c8500859c285" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"6Yrr-jvsw4txE1DJUE9cBsTcYutw-AEKGjzJfpmOPHY", "state"=>"15589942c994ae56728d00e99838552c7d35c8500859c285"}
Authenticating with gds_sso strategy
  User Load (0.3ms)  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 4ms (ActiveRecord: 0.3ms | Allocations: 913)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:35 +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", 0], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 2ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 652)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:16:35 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 1ms (ActiveRecord: 0.0ms | Allocations: 480)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-29 06:16:35 +0000
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 103)
Started GET "/auth/gds" for 127.0.0.1 at 2020-07-28 10:11:35 +0000
Started GET "/auth/gds/callback?code=V4C4G085QyxJZbkrmdwghKBuEppEmkyf5b1oOLW-A-0&state=486c0ea1075520065090312235e3f52e48d040b8df6cac5a" for 127.0.0.1 at 2020-07-28 10:11:36 +0000
Processing by AuthenticationsController#callback as HTML
  Parameters: {"code"=>"V4C4G085QyxJZbkrmdwghKBuEppEmkyf5b1oOLW-A-0", "state"=>"486c0ea1075520065090312235e3f52e48d040b8df6cac5a"}
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]]
Redirected to http://www.example-client.com/restricted
Completed 302 Found in 4ms (ActiveRecord: 0.2ms | Allocations: 915)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:36 +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", 0], ["LIMIT", 1]]
  Rendering text template
  Rendered text template (Duration: 0.0ms | Allocations: 1)
Completed 200 OK in 2ms (Views: 0.4ms | ActiveRecord: 0.2ms | Allocations: 655)
Started GET "/restricted" for 127.0.0.1 at 2020-07-29 06:06:36 +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.3ms | ActiveRecord: 0.1ms | Allocations: 875)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:36 +0000
Processing by ExampleController#restricted as HTML
Authenticating with gds_sso strategy
Completed   in 0ms (ActiveRecord: 0.0ms | Allocations: 105)
Started GET "/this_requires_signin_permission" for 127.0.0.1 at 2020-07-28 10:11:36 +0000
Processing by ExampleController#this_requires_signin_permission as JSON
  User Load (0.2ms)  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.3ms)  UPDATE "users" SET "disabled" = ? WHERE "users"."id" = ?  [["disabled", nil], ["id", 13]]
   (3.6ms)  commit transaction
  User Load (0.2ms)  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 65ms (Views: 0.5ms | ActiveRecord: 4.5ms | Allocations: 6880)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:36 +0000
Processing by ExampleController#restricted as JSON
Completed   in 21ms (ActiveRecord: 0.0ms | Allocations: 1754)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:36 +0000
Processing by ExampleController#restricted as JSON
  User Load (0.2ms)  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 62ms (Views: 0.5ms | ActiveRecord: 0.3ms | Allocations: 6323)
Started GET "/restricted" for 127.0.0.1 at 2020-07-28 10:11:36 +0000
Processing by ExampleController#restricted as JSON
Completed   in 15ms (ActiveRecord: 0.0ms | Allocations: 1855)