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 @@
[1m[36mSQL (0.2ms)[0m [1mINSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?)[0m [["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"]]
[1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
Redirected to http://test.host/cookie_test?nonce=ZGPvV4gwXerMzzlENVRfU6RmPM1mrt45rb99TBaE
Completed 302 Found in 3ms (ActiveRecord: 0.3ms)
[1m[36m (0.4ms)[0m [1mrollback transaction[0m
+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
+ [1m[36m (0.4ms)[0m [1mbegin transaction[0m
+Processing by LtiProvider::LtiController#configure as XML
+Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms)
+ [1m[35m (0.1ms)[0m rollback transaction
+ [1m[36m (0.0ms)[0m [1mbegin transaction[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (1.2ms)[0m [1mINSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?)[0m [["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"]]
+ [1m[35m (0.1ms)[0m RELEASE SAVEPOINT active_record_1
+Processing by LtiProvider::LtiController#consume_launch as HTML
+ Parameters: {"nonce"=>"invalid"}
+ [1m[36mLtiProvider::Launch Load (0.1ms)[0m [1mSELECT "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[0m
+Completed 200 OK in 37ms (Views: 13.4ms | ActiveRecord: 0.1ms)
+ [1m[35m (0.7ms)[0m rollback transaction
+ [1m[36m (0.0ms)[0m [1mbegin transaction[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.3ms)[0m [1mINSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?)[0m [["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"]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+Processing by LtiProvider::LtiController#consume_launch as HTML
+ Parameters: {"nonce"=>"abcd"}
+ [1m[36mLtiProvider::Launch Load (0.1ms)[0m [1mSELECT "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[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (1.1ms)[0m [1mDELETE FROM "lti_provider_launches" WHERE "lti_provider_launches"."id" = ?[0m [["id", 1]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+Redirected to http://test.host/
+Completed 302 Found in 6ms (ActiveRecord: 1.2ms)
+ [1m[36m (0.7ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.3ms)[0m 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"]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+Processing by LtiProvider::LtiController#consume_launch as HTML
+ Parameters: {"nonce"=>"abcd"}
+ [1m[35mLtiProvider::Launch Load (0.1ms)[0m 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
+ [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.3ms)[0m DELETE FROM "lti_provider_launches" WHERE "lti_provider_launches"."id" = ? [["id", 1]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+Redirected to http://test.host/
+Completed 302 Found in 3ms (ActiveRecord: 0.6ms)
+ [1m[35m (0.1ms)[0m SELECT COUNT(*) FROM "lti_provider_launches"
+ [1m[36m (0.6ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.1ms)[0m begin transaction
+ [1m[36m (0.1ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.2ms)[0m 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"]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.3ms)[0m [1mUPDATE "lti_provider_launches" SET "created_at" = ?, "provider_params" = ?, "updated_at" = ? WHERE "lti_provider_launches"."id" = 1[0m [["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"]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+Processing by LtiProvider::LtiController#consume_launch as HTML
+ Parameters: {"nonce"=>"abcd"}
+ [1m[36mLtiProvider::Launch Load (0.1ms)[0m [1mSELECT "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[0m
+Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms)
+ [1m[35m (0.7ms)[0m rollback transaction
+ [1m[36m (0.0ms)[0m [1mbegin transaction[0m
+Processing by LtiProvider::LtiController#cookie_test as HTML
+Completed 200 OK in 4ms (Views: 4.0ms | ActiveRecord: 0.0ms)
+ [1m[35m (0.1ms)[0m rollback transaction
+ [1m[36m (0.0ms)[0m [1mbegin transaction[0m
+Processing by LtiProvider::LtiController#cookie_test as HTML
+Completed 200 OK in 1ms (Views: 0.6ms | ActiveRecord: 0.0ms)
+ [1m[35m (0.0ms)[0m rollback transaction
+ [1m[36m (0.0ms)[0m [1mbegin transaction[0m
+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)
+ [1m[35m (0.1ms)[0m rollback transaction
+ [1m[36m (0.1ms)[0m [1mbegin transaction[0m
+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="}
+ [1m[35m (0.0ms)[0m SAVEPOINT active_record_1
+ [1m[36mSQL (0.3ms)[0m [1mINSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?)[0m [["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"]]
+ [1m[35m (0.0ms)[0m RELEASE SAVEPOINT active_record_1
+Redirected to http://test.host/cookie_test?nonce=BhJqsEX8tlf2Xglq6wubYqqEb1lTe8DLcdauwFZLjI
+Completed 302 Found in 3ms (ActiveRecord: 0.4ms)
+ [1m[36m (0.4ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m 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="}
+ [1m[36m (0.0ms)[0m [1mSAVEPOINT active_record_1[0m
+ [1m[35mSQL (0.3ms)[0m 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"]]
+ [1m[36m (0.0ms)[0m [1mRELEASE SAVEPOINT active_record_1[0m
+Redirected to http://test.host/cookie_test?nonce=pqFhiL3kYP00C1MBdvWbj5JWMrt7jtzkVPYGGF9WwyY
+Completed 302 Found in 3ms (ActiveRecord: 0.3ms)
+ [1m[35mLtiProvider::Launch Load (0.1ms)[0m SELECT "lti_provider_launches".* FROM "lti_provider_launches" ORDER BY "lti_provider_launches"."id" ASC LIMIT 1
+ [1m[36m (0.4ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m 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)
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.1ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.1ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.1ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.1ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.1ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m
+ [1m[35m (0.0ms)[0m begin transaction
+ [1m[36m (0.0ms)[0m [1mrollback transaction[0m