spec/dummy/log/test.log in lti_provider_engine-1.0.0 vs spec/dummy/log/test.log in lti_provider_engine-1.1.0

- old
+ new

@@ -7469,5 +7469,239 @@  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction +Initializing LTI key and secret using configuration in /Users/simon/Instructure/projects/lti_provider_engine/spec/dummy/config/lti.yml +Initializing LTI XML config using configuration in /Users/simon/Instructure/projects/lti_provider_engine/spec/dummy/config/lti_xml.yml +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host/launch", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_nonce"=>"uVXB75f6y17bwDBcjzxJFnE8QKgP37XOmJc89x2Q8", "oauth_signature"=>"/JNhxSqH+0snOy/FSNK+5dLrVvY=", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1525878063", "oauth_version"=>"1.0"} + Rendering text template + Rendered text template (0.0ms) +Completed 200 OK in 4ms (Views: 2.5ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host/launch", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_consumer_key"=>"12345", "oauth_nonce"=>"sdx1ZuouQjPpAasPkcRso0agCSKNwkXjO8nDOYxrc", "oauth_signature"=>"VpgFGV20K9Swds1Iwjt0FuP9L64=", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1525878063", "oauth_version"=>"1.0"} +  (0.1ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.7ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://test.canvas"], ["nonce", "sdx1ZuouQjPpAasPkcRso0agCSKNwkXjO8nDOYxrc"], ["provider_params", "---\nlaunch_presentation_return_url: http://test.canvas\nlti_message_type: basic-lti-launch-request\nlti_version: LTI-1p0\noauth_consumer_key: '12345'\noauth_nonce: sdx1ZuouQjPpAasPkcRso0agCSKNwkXjO8nDOYxrc\noauth_signature: VpgFGV20K9Swds1Iwjt0FuP9L64=\noauth_signature_method: HMAC-SHA1\noauth_timestamp: '1525878063'\noauth_version: '1.0'\ncustom_canvas_user_id: '1'\n"], ["created_at", "2018-05-09 15:01:03.041767"], ["updated_at", "2018-05-09 15:01:03.041767"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/cookie_test?nonce=sdx1ZuouQjPpAasPkcRso0agCSKNwkXjO8nDOYxrc +Completed 302 Found in 14ms (ActiveRecord: 0.9ms) + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" ORDER BY "lti_provider_launches"."id" ASC LIMIT ? [["LIMIT", 1]] +  (0.4ms) rollback transaction +  (0.1ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host/launch", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_consumer_key"=>"12345", "oauth_nonce"=>"8JRUWEfjT9d1qZyOqwHzjS9npEhv1Dxafi1lA5nZc", "oauth_signature"=>"6gazGW173bKh9cDleqz+sS/jp64=", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1525878063", "oauth_version"=>"1.0"} +  (0.1ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.5ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://test.canvas"], ["nonce", "8JRUWEfjT9d1qZyOqwHzjS9npEhv1Dxafi1lA5nZc"], ["provider_params", "---\nlaunch_presentation_return_url: http://test.canvas\nlti_message_type: basic-lti-launch-request\nlti_version: LTI-1p0\noauth_consumer_key: '12345'\noauth_nonce: 8JRUWEfjT9d1qZyOqwHzjS9npEhv1Dxafi1lA5nZc\noauth_signature: 6gazGW173bKh9cDleqz+sS/jp64=\noauth_signature_method: HMAC-SHA1\noauth_timestamp: '1525878063'\noauth_version: '1.0'\ncustom_canvas_user_id: '1'\n"], ["created_at", "2018-05-09 15:01:03.062058"], ["updated_at", "2018-05-09 15:01:03.062058"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/cookie_test?nonce=8JRUWEfjT9d1qZyOqwHzjS9npEhv1Dxafi1lA5nZc +Completed 302 Found in 5ms (ActiveRecord: 0.6ms) + LtiProvider::Launch Load (0.0ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" ORDER BY "lti_provider_launches"."id" ASC LIMIT ? [["LIMIT", 1]] +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host/launch", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_consumer_key"=>"12345", "oauth_nonce"=>"wCnHHfuf9k9twrkNFIqbq4y1uT3q16G9ewEDEmMETs", "oauth_signature"=>"SBVyITDeyeLkDtvd7tlR+do4rxc=", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1525878063", "oauth_version"=>"1.0"} + Rendering text template + Rendered text template (0.0ms) +Completed 200 OK in 2ms (Views: 0.3ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["created_at", "2018-05-09 15:01:03.073881"], ["updated_at", "2018-05-09 15:01:03.073881"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by LtiProvider::LtiController#consume_launch as HTML + Parameters: {"nonce"=>"abcd"} + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" WHERE (created_at > '2018-05-09 14:56:03.076201') AND "lti_provider_launches"."nonce" = ? LIMIT ? [["nonce", "abcd"], ["LIMIT", 1]] +  (0.1ms) SAVEPOINT active_record_1 + LtiProvider::Launch Destroy (0.1ms) DELETE FROM "lti_provider_launches" WHERE "lti_provider_launches"."id" = ? [["id", 1]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/ +Completed 302 Found in 5ms (ActiveRecord: 0.3ms) +  (0.1ms) SELECT COUNT(*) FROM "lti_provider_launches" +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["created_at", "2018-05-09 15:01:03.084360"], ["updated_at", "2018-05-09 15:01:03.084360"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by LtiProvider::LtiController#consume_launch as HTML + Parameters: {"nonce"=>"abcd"} + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" WHERE (created_at > '2018-05-09 14:56:03.086710') AND "lti_provider_launches"."nonce" = ? LIMIT ? [["nonce", "abcd"], ["LIMIT", 1]] +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Destroy (0.1ms) DELETE FROM "lti_provider_launches" WHERE "lti_provider_launches"."id" = ? [["id", 1]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/ +Completed 302 Found in 1ms (ActiveRecord: 0.2ms) +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["created_at", "2018-05-09 15:01:03.090553"], ["updated_at", "2018-05-09 15:01:03.090553"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by LtiProvider::LtiController#consume_launch as HTML + Parameters: {"nonce"=>"invalid"} + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" WHERE (created_at > '2018-05-09 14:56:03.092750') AND "lti_provider_launches"."nonce" = ? LIMIT ? [["nonce", "invalid"], ["LIMIT", 1]] + Rendering text template + Rendered text template (0.0ms) +Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["created_at", "2018-05-09 15:01:03.095958"], ["updated_at", "2018-05-09 15:01:03.095958"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Update (0.1ms) UPDATE "lti_provider_launches" SET "created_at" = ?, "updated_at" = ? WHERE "lti_provider_launches"."id" = ? [["created_at", "2018-05-09 14:51:03.097258"], ["updated_at", "2018-05-09 15:01:03.097523"], ["id", 1]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by LtiProvider::LtiController#consume_launch as HTML + Parameters: {"nonce"=>"abcd"} + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" WHERE (created_at > '2018-05-09 14:56:03.099422') AND "lti_provider_launches"."nonce" = ? LIMIT ? [["nonce", "abcd"], ["LIMIT", 1]] + Rendering text template + Rendered text template (0.0ms) +Completed 200 OK in 1ms (Views: 0.3ms | ActiveRecord: 0.1ms) +  (0.3ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#configure as XML +Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#cookie_test as HTML + Rendering /Users/simon/Instructure/projects/lti_provider_engine/app/views/lti_provider/lti/cookie_test.html.erb + Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. + Rendered /Users/simon/Instructure/projects/lti_provider_engine/app/views/lti_provider/lti/cookie_test.html.erb (0.5ms) +Completed 200 OK in 16ms (Views: 15.5ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#cookie_test as HTML + Rendering /Users/simon/Instructure/projects/lti_provider_engine/app/views/lti_provider/lti/cookie_test.html.erb + Rendered /Users/simon/Instructure/projects/lti_provider_engine/app/views/lti_provider/lti/cookie_test.html.erb (0.0ms) +Completed 200 OK in 1ms (Views: 0.5ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +Initializing LTI key and secret using configuration in /Users/simon/Instructure/projects/lti_provider_engine/spec/dummy/config/lti.yml +Initializing LTI XML config using configuration in /Users/simon/Instructure/projects/lti_provider_engine/spec/dummy/config/lti_xml.yml +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host/launch", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_consumer_key"=>"12345", "oauth_nonce"=>"Fy5uBxdmNvbpxQEpWwFht0KfMNRK6MQ0gjHKtBbS4", "oauth_signature"=>"H+RoOxseTfWr5kgCM2HXtvDIE+4=", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1525878094", "oauth_version"=>"1.0"} + Rendering text template + Rendered text template (0.0ms) +Completed 200 OK in 4ms (Views: 2.3ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host/launch", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_consumer_key"=>"12345", "oauth_nonce"=>"NRVC3POPF9rVHWIcNvWpp43DSPB1mYNNWEIeIrnEY", "oauth_signature"=>"Cas9zaHJzc4FCKUznYgjFeR67bE=", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1525878094", "oauth_version"=>"1.0"} +  (0.1ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.5ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://test.canvas"], ["nonce", "NRVC3POPF9rVHWIcNvWpp43DSPB1mYNNWEIeIrnEY"], ["provider_params", "---\nlaunch_presentation_return_url: http://test.canvas\nlti_message_type: basic-lti-launch-request\nlti_version: LTI-1p0\noauth_consumer_key: '12345'\noauth_nonce: NRVC3POPF9rVHWIcNvWpp43DSPB1mYNNWEIeIrnEY\noauth_signature: Cas9zaHJzc4FCKUznYgjFeR67bE=\noauth_signature_method: HMAC-SHA1\noauth_timestamp: '1525878094'\noauth_version: '1.0'\ncustom_canvas_user_id: '1'\n"], ["created_at", "2018-05-09 15:01:34.619635"], ["updated_at", "2018-05-09 15:01:34.619635"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/cookie_test?nonce=NRVC3POPF9rVHWIcNvWpp43DSPB1mYNNWEIeIrnEY +Completed 302 Found in 11ms (ActiveRecord: 0.6ms) + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" ORDER BY "lti_provider_launches"."id" ASC LIMIT ? [["LIMIT", 1]] +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host/launch", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_consumer_key"=>"12345", "oauth_nonce"=>"O52lCR1Bry7NNuU3qtnJv8priW1G4GUecpydsaaIkQs", "oauth_signature"=>"OGIWO5e25ljJkfs19pPGAhAyEXE=", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1525878094", "oauth_version"=>"1.0"} +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.2ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://test.canvas"], ["nonce", "O52lCR1Bry7NNuU3qtnJv8priW1G4GUecpydsaaIkQs"], ["provider_params", "---\nlaunch_presentation_return_url: http://test.canvas\nlti_message_type: basic-lti-launch-request\nlti_version: LTI-1p0\noauth_consumer_key: '12345'\noauth_nonce: O52lCR1Bry7NNuU3qtnJv8priW1G4GUecpydsaaIkQs\noauth_signature: OGIWO5e25ljJkfs19pPGAhAyEXE=\noauth_signature_method: HMAC-SHA1\noauth_timestamp: '1525878094'\noauth_version: '1.0'\ncustom_canvas_user_id: '1'\n"], ["created_at", "2018-05-09 15:01:34.634287"], ["updated_at", "2018-05-09 15:01:34.634287"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/cookie_test?nonce=O52lCR1Bry7NNuU3qtnJv8priW1G4GUecpydsaaIkQs +Completed 302 Found in 3ms (ActiveRecord: 0.3ms) + LtiProvider::Launch Load (0.0ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" ORDER BY "lti_provider_launches"."id" ASC LIMIT ? [["LIMIT", 1]] +  (0.3ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host/launch", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_nonce"=>"wato9XSjtDr65qOOj9JdWjNG41X2e9VIzzs06dC87tA", "oauth_signature"=>"hDLsvQ+Sb0l7joj5EEYV6QYn3Uw=", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1525878094", "oauth_version"=>"1.0"} + Rendering text template + Rendered text template (0.0ms) +Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["created_at", "2018-05-09 15:01:34.643387"], ["updated_at", "2018-05-09 15:01:34.643387"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by LtiProvider::LtiController#consume_launch as HTML + Parameters: {"nonce"=>"abcd"} + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" WHERE (created_at > '2018-05-09 14:56:34.648273') AND "lti_provider_launches"."nonce" = ? LIMIT ? [["nonce", "abcd"], ["LIMIT", 1]] +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Destroy (0.1ms) DELETE FROM "lti_provider_launches" WHERE "lti_provider_launches"."id" = ? [["id", 1]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/ +Completed 302 Found in 7ms (ActiveRecord: 0.3ms) +  (0.3ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.2ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["created_at", "2018-05-09 15:01:34.654399"], ["updated_at", "2018-05-09 15:01:34.654399"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by LtiProvider::LtiController#consume_launch as HTML + Parameters: {"nonce"=>"abcd"} + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" WHERE (created_at > '2018-05-09 14:56:34.656115') AND "lti_provider_launches"."nonce" = ? LIMIT ? [["nonce", "abcd"], ["LIMIT", 1]] +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Destroy (0.0ms) DELETE FROM "lti_provider_launches" WHERE "lti_provider_launches"."id" = ? [["id", 1]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/ +Completed 302 Found in 1ms (ActiveRecord: 0.2ms) +  (0.0ms) SELECT COUNT(*) FROM "lti_provider_launches" +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["created_at", "2018-05-09 15:01:34.660009"], ["updated_at", "2018-05-09 15:01:34.660009"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by LtiProvider::LtiController#consume_launch as HTML + Parameters: {"nonce"=>"invalid"} + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" WHERE (created_at > '2018-05-09 14:56:34.661874') AND "lti_provider_launches"."nonce" = ? LIMIT ? [["nonce", "invalid"], ["LIMIT", 1]] + Rendering text template + Rendered text template (0.0ms) +Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + LtiProvider::Launch Create (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "nonce", "provider_params", "created_at", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["created_at", "2018-05-09 15:01:34.664977"], ["updated_at", "2018-05-09 15:01:34.664977"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.1ms) SAVEPOINT active_record_1 + LtiProvider::Launch Update (0.1ms) UPDATE "lti_provider_launches" SET "created_at" = ?, "updated_at" = ? WHERE "lti_provider_launches"."id" = ? [["created_at", "2018-05-09 14:51:34.666227"], ["updated_at", "2018-05-09 15:01:34.666543"], ["id", 1]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Processing by LtiProvider::LtiController#consume_launch as HTML + Parameters: {"nonce"=>"abcd"} + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" WHERE (created_at > '2018-05-09 14:56:34.668294') AND "lti_provider_launches"."nonce" = ? LIMIT ? [["nonce", "abcd"], ["LIMIT", 1]] + Rendering text template + Rendered text template (0.0ms) +Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) +  (0.3ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#configure as XML +Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#cookie_test as HTML + Rendering /Users/simon/Instructure/projects/lti_provider_engine/app/views/lti_provider/lti/cookie_test.html.erb + Template rendering was prevented by rspec-rails. Use `render_views` to verify rendered view contents if necessary. + Rendered /Users/simon/Instructure/projects/lti_provider_engine/app/views/lti_provider/lti/cookie_test.html.erb (0.2ms) +Completed 200 OK in 9ms (Views: 8.7ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#cookie_test as HTML + Rendering /Users/simon/Instructure/projects/lti_provider_engine/app/views/lti_provider/lti/cookie_test.html.erb + Rendered /Users/simon/Instructure/projects/lti_provider_engine/app/views/lti_provider/lti/cookie_test.html.erb (0.0ms) +Completed 200 OK in 1ms (Views: 0.4ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction