spec/dummy/log/test.log in lti_provider_engine-0.0.1 vs spec/dummy/log/test.log in lti_provider_engine-0.0.2

- old
+ new

@@ -3639,5 +3639,129 @@  (0.1ms) 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/simonista/Instructure/lti_provider_engine/spec/dummy/config/lti.yml +Initializing LTI XML config using configuration in /Users/simonista/Instructure/lti_provider_engine/spec/dummy/config/lti_xml.yml +Connecting to database specified by database.yml +  (0.4ms) begin transaction +Processing by LtiProvider::LtiController#configure as XML +Completed 200 OK in 3ms (Views: 0.2ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (56.0ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["created_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\n"], ["updated_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00]] +  (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 "lti_provider_launches"."nonce" = 'invalid' AND (created_at > '2013-11-13 18:12:38.709691') LIMIT 1 +Completed 200 OK in 35ms (Views: 30.4ms | ActiveRecord: 0.1ms) +  (0.8ms) rollback transaction +  (0.1ms) begin transaction +  (0.1ms) SAVEPOINT active_record_1 + SQL (0.5ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["created_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\n"], ["updated_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +  (0.0ms) SAVEPOINT active_record_1 +  (0.3ms) UPDATE "lti_provider_launches" SET "created_at" = '2013-11-13 18:07:38.755725', "updated_at" = '2013-11-13 18:17:38.756323', "provider_params" = '--- +custom_canvas_course_id: 1 +custom_canvas_user_id: 2 +tool_consumer_instance_guid: 123abc +' WHERE "lti_provider_launches"."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 "lti_provider_launches"."nonce" = 'abcd' AND (created_at > '2013-11-13 18:12:38.759918') LIMIT 1 +Completed 200 OK in 1ms (Views: 0.2ms | ActiveRecord: 0.1ms) +  (0.7ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["created_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\n"], ["updated_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00]] +  (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 "lti_provider_launches"."nonce" = 'abcd' AND (created_at > '2013-11-13 18:12:38.769711') LIMIT 1 +  (0.0ms) 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 7ms (ActiveRecord: 0.4ms) +  (0.6ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://canvas"], ["created_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00], ["nonce", "abcd"], ["provider_params", "---\ncustom_canvas_course_id: 1\ncustom_canvas_user_id: 2\ntool_consumer_instance_guid: 123abc\n"], ["updated_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00]] +  (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 "lti_provider_launches"."nonce" = 'abcd' AND (created_at > '2013-11-13 18:12:38.785619') LIMIT 1 +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.2ms) 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.4ms) +  (0.1ms) SELECT COUNT(*) FROM "lti_provider_launches"  +  (0.6ms) 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"=>"1384366658", "oauth_nonce"=>"YMnk53DMGS2rNyfjP7NZYtiarSEoq0tFJCSi1hzQdfw", "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"=>"SUhQZCcg10WvX/jLxN5W0yowtfI="} +Completed 200 OK in 3ms (Views: 0.4ms | ActiveRecord: 0.0ms) +  (0.1ms) rollback transaction +  (0.1ms) begin transaction +Processing by LtiProvider::LtiController#launch as HTML + Parameters: {"oauth_signature_method"=>"HMAC-SHA1", "oauth_timestamp"=>"1384366658", "oauth_nonce"=>"RwOtU9a6iCxS388vT2ko5UK9A6nVA6K0Y6zzJyPfBE", "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"=>"Eb21ImcKHFaAUsTJxeNiYC4LHjw="} +Completed 200 OK in 1ms (Views: 0.2ms | 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"=>"1384366658", "oauth_nonce"=>"l4Qxxzjd57DwxAv51CebEF3snqrulkMGmTRho2PMJhs", "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"=>"NIUhzyHbVAP9eFK3TTn2UOfppWg="} +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://test.canvas"], ["created_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00], ["nonce", "l4Qxxzjd57DwxAv51CebEF3snqrulkMGmTRho2PMJhs"], ["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: l4Qxxzjd57DwxAv51CebEF3snqrulkMGmTRho2PMJhs\noauth_signature: NIUhzyHbVAP9eFK3TTn2UOfppWg=\noauth_signature_method: HMAC-SHA1\noauth_timestamp: '1384366658'\noauth_version: '1.0'\ncustom_canvas_user_id: '1'\n"], ["updated_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/cookie_test?nonce=l4Qxxzjd57DwxAv51CebEF3snqrulkMGmTRho2PMJhs +Completed 302 Found in 6ms (ActiveRecord: 0.5ms) +  (0.7ms) 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"=>"1384366658", "oauth_nonce"=>"JEOf6mnOW8aGl4hCyTCkP82NYaUHILppos3OQlnIMMg", "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"=>"NdWVoj4uU8tf813Q49UMBuaxNvM="} +  (0.0ms) SAVEPOINT active_record_1 + SQL (0.4ms) INSERT INTO "lti_provider_launches" ("canvas_url", "created_at", "nonce", "provider_params", "updated_at") VALUES (?, ?, ?, ?, ?) [["canvas_url", "http://test.canvas"], ["created_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00], ["nonce", "JEOf6mnOW8aGl4hCyTCkP82NYaUHILppos3OQlnIMMg"], ["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: JEOf6mnOW8aGl4hCyTCkP82NYaUHILppos3OQlnIMMg\noauth_signature: NdWVoj4uU8tf813Q49UMBuaxNvM=\noauth_signature_method: HMAC-SHA1\noauth_timestamp: '1384366658'\noauth_version: '1.0'\ncustom_canvas_user_id: '1'\n"], ["updated_at", Wed, 13 Nov 2013 18:17:38 UTC +00:00]] +  (0.0ms) RELEASE SAVEPOINT active_record_1 +Redirected to http://test.host/cookie_test?nonce=JEOf6mnOW8aGl4hCyTCkP82NYaUHILppos3OQlnIMMg +Completed 302 Found in 6ms (ActiveRecord: 0.4ms) + LtiProvider::Launch Load (0.1ms) SELECT "lti_provider_launches".* FROM "lti_provider_launches" LIMIT 1 +  (0.6ms) rollback transaction +  (0.0ms) begin transaction +Processing by LtiProvider::LtiController#cookie_test as HTML +Completed 200 OK in 2ms (Views: 1.6ms | ActiveRecord: 0.0ms) +  (0.0ms) 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 +  (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 +  (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 +  (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.0ms) rollback transaction +  (0.0ms) begin transaction +  (0.0ms) rollback transaction