spec/dummy/log/test.log in lti_provider_engine-0.0.4 vs spec/dummy/log/test.log in lti_provider_engine-0.0.5

- old
+ new

@@ -5554,5 +5554,126 @@ SQL (0.2ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://test.canvas"], ["created_at", "2014-11-25 19:55:44.415862"], ["nonce", "ZGPvV4gwXerMzzlENVRfU6RmPM1mrt45rb99TBaE"], ["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: ZGPvV4gwXerMzzlENVRfU6RmPM1mrt45rb99TBaE\noauth_signature: f4XaeXYBH/+moYOlOdFXoQ5gKMA=\noauth_signature_method: HMAC-SHA1\noauth_timestamp: '1416945344'\noauth_version: '1.0'\ncustom_canvas_user_id: '1'\n"], ["updated_at", "2014-11-25 19:55:44.415862"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/cookie_test?nonce=ZGPvV4gwXerMzzlENVRfU6RmPM1mrt45rb99TBaE Completed 302 Found in 3ms (ActiveRecord: 0.3ms)  (0.4ms) rollback transaction +Initializing LTI key and secret using configuration in /Users/simon/Instructure/projects/lti_provider_engine/spec/dummy/config/lti.yml +Initializing LTI key and secret using configuration in /Users/simon/Instructure/projects/lti_provider_engine/spec/dummy/config/lti.yml +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.4ms) begin transaction +Processing by LtiProvider::LtiController#configure as XML +Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (1.2ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["created_at", "2015-08-20 19:45:59.693313"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["updated_at", "2015-08-20 19:45:59.693313"]] +  (0.1ms) 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 > '2015-08-20 19:40:59.704699') AND "lti_provider_launches"."nonce" = 'invalid' LIMIT 1 +Completed 200 OK in 37ms (Views: 13.4ms | ActiveRecord: 0.1ms) +  (0.7ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["created_at", "2015-08-20 19:45:59.743967"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["updated_at", "2015-08-20 19:45:59.743967"]] +  (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 > '2015-08-20 19:40:59.745988') AND "lti_provider_launches"."nonce" = 'abcd' LIMIT 1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (1.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 6ms (ActiveRecord: 1.2ms) +  (0.7ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["created_at", "2015-08-20 19:45:59.760962"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["updated_at", "2015-08-20 19:45:59.760962"]] +  (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 > '2015-08-20 19:40:59.763792') AND "lti_provider_launches"."nonce" = 'abcd' LIMIT 1 +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.3ms) 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 3ms (ActiveRecord: 0.6ms) +  (0.1ms) SELECT COUNT(*) FROM "lti_provider_launches" +  (0.6ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.2ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["created_at", "2015-08-20 19:45:59.769436"], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["updated_at", "2015-08-20 19:45:59.769436"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) UPDATE "lti_provider_launches" SET "created_at" = ?, "provider_params" = ?, "updated_at" = ? WHERE "lti_provider_launches"."id" = 1 [["created_at", "2015-08-20 19:35:59.770866"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\next_roles: student\n"], ["updated_at", "2015-08-20 19:45:59.771142"]] +  (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 > '2015-08-20 19:40:59.773739') AND "lti_provider_launches"."nonce" = 'abcd' LIMIT 1 +Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) +  (0.7ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#cookie_test as HTML +Completed 200 OK in 4ms (Views: 4.0ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#cookie_test as HTML +Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"oauth_consumer_key"=>"12345", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1440099959", "oauth_nonce"=>"DHlkQcKFRHyL4uXMEUGZUQPkGvWdHEvXSRsybpbgq0", "oauth_version"=>"1.0", "custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_signature"=>"ky8aMdeQ92j+geXeK3TGH8Hp2m4="} +Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"oauth_consumer_key"=>"12345", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1440099959", "oauth_nonce"=>"BhJqsEX8tlf2Xglq6wubYqqEb1lTe8DLcdauwFZLjI", "oauth_version"=>"1.0", "custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_signature"=>"f+colh7Dm4s/bgPWUNXdrFR4BQE="} +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://test.canvas"], ["created_at", "2015-08-20 19:45:59.843502"], ["nonce", "BhJqsEX8tlf2Xglq6wubYqqEb1lTe8DLcdauwFZLjI"], ["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: BhJqsEX8tlf2Xglq6wubYqqEb1lTe8DLcdauwFZLjI\noauth_signature: f+colh7Dm4s/bgPWUNXdrFR4BQE=\noauth_signature_method: HMAC-SHA1\noauth_timestamp: '1440099959'\noauth_version: '1.0'\ncustom_canvas_user_id: '1'\n"], ["updated_at", "2015-08-20 19:45:59.843502"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/cookie_test?nonce=BhJqsEX8tlf2Xglq6wubYqqEb1lTe8DLcdauwFZLjI +Completed 302 Found in 3ms (ActiveRecord: 0.4ms) +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"oauth_consumer_key"=>"12345", "oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1440099959", "oauth_nonce"=>"pqFhiL3kYP00C1MBdvWbj5JWMrt7jtzkVPYGGF9WwyY", "oauth_version"=>"1.0", "custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_signature"=>"nvONUqLctztRpU6ngUzjYundu7s="} +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.3ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://test.canvas"], ["created_at", "2015-08-20 19:45:59.854697"], ["nonce", "pqFhiL3kYP00C1MBdvWbj5JWMrt7jtzkVPYGGF9WwyY"], ["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: pqFhiL3kYP00C1MBdvWbj5JWMrt7jtzkVPYGGF9WwyY\noauth_signature: nvONUqLctztRpU6ngUzjYundu7s=\noauth_signature_method: HMAC-SHA1\noauth_timestamp: '1440099959'\noauth_version: '1.0'\ncustom_canvas_user_id: '1'\n"], ["updated_at", "2015-08-20 19:45:59.854697"]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/cookie_test?nonce=pqFhiL3kYP00C1MBdvWbj5JWMrt7jtzkVPYGGF9WwyY +Completed 302 Found in 3ms (ActiveRecord: 0.3ms) + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" ORDER BY "lti_provider_launches"."id" ASC LIMIT 1 +  (0.4ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1440099959", "oauth_nonce"=>"uwyU9DRiF7ndpctwTb6S48WUKQ5RQUCY0TIUSubz6Y", "oauth_version"=>"1.0", "custom_canvas_user_id"=>"1", "launch_presentation_return_url"=>"http://test.canvas", "launch_url"=>"http://test.host", "lti_message_type"=>"basic-lti-launch-request", "lti_version"=>"LTI-1p0", "oauth_signature"=>"8oJlfVCs8AuR9F2H6S0KW3ecE8Q="} +Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.0ms) +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) 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 +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.1ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction