** [Raven] Raven 0.13.3 configured not to send errors. ** [Raven] Raven 0.13.3 configured not to send errors. ** [Raven] Raven 0.13.3 configured not to send errors. ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Skydrive::Account Load (0.1ms) SELECT "skydrive_accounts".* FROM "skydrive_accounts" WHERE "skydrive_accounts"."key" = 'one' AND "skydrive_accounts"."secret" = 'not_two' LIMIT 1  (0.0ms) begin transaction Skydrive::Account Exists (0.1ms) SELECT 1 AS one FROM "skydrive_accounts" WHERE "skydrive_accounts"."key" = 'one' LIMIT 1 SQL (0.2ms) INSERT INTO "skydrive_accounts" ("key", "secret") VALUES (?, ?) [["key", "one"], ["secret", "not_two"]]  (18.4ms) commit transaction  (0.1ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1 | | RestClient.post "https://login.windows.net/common/oauth2/token", "client_id=test&client_secret=&grant_type=refresh_token&refresh_token=&resource=NEW_TOKEN", "Accept"=>"*/*; q=0.5, application/xml", "Accept-Encoding"=>"gzip, deflate", "Content-Length"=>"88", "Content-Type"=>"application/x-www-form-urlencoded" # => 200 OK | application/json 584 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [CRZNTWLH] ======== Method: post Endpoint: https://login.windows.net/common/oauth2/token Headers: $Accept => '*/*; q=0.5, application/xml' $Accept-Encoding => 'gzip, deflate' $Content-Length => '88' $Content-Type => 'application/x-www-form-urlencoded' Payload: {:client_id=>"test", :client_secret=>nil, :grant_type=>"refresh_token", :refresh_token=>nil, :resource=>"NEW_TOKEN"} Response Code: 200 Response Headers: $cache-control => 'no-cache, no-store' $pragma => 'no-cache' $content-type => 'application/json' $expires => '-1' $request-id => '8fa6a09a-354a-4710-8650-e9095e70f8f8' $x-content-type-options => 'nosniff' $date => 'Wed, 08 Jan 2014 19:47:20 GMT' $content-length => '1183' Response Body: { "error_description" : "AADSTS90014: The request body must contain the following parameter: 'refresh_token'.\r\nTrace ID: 1a18b1f6-0000-4129-0000-7fa865546c86\r\nCorrelation ID: 4e75524f-0000-499b-0000-e3469408748d\r\nTimestamp: 2015-05-01 19:52:25Z", "correlation_id" : "4e75524f-0000-499b-0000-e3469408748d", "error_codes" : [ 90014 ], "timestamp" : "2015-05-01 19:52:25Z", "trace_id" : "1a18b1f6-0000-4129-0000-7fa865546c86", "error" : "invalid_request", "context" : null, "submit_url" : null } Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:90:in `block in update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient.rb:69:in `post' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:89:in `update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:157:in `block (3 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/matchers/built_in/raise_error.rb:43:in `matches?' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:50:in `block in handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:27:in `with_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:48:in `handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:54:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:87:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:156:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [CRZNTWLH] ========= =========================================================================  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1 RestClient.get "https://api.office.com/discovery/v1.0/me/services", "Accept"=>"", "Accept-Encoding"=>"gzip, deflate", "Authorization"=>"Bearer " # => 401 Unauthorized | application/json 250 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [DAKOFASW] ======== Method: get Endpoint: https://api.office.com/discovery/v1.0/me/services Headers: $Accept => '' $Authorization => 'Bearer ' $Accept-Encoding => 'gzip, deflate' Payload: --No Payload!!-- Response Code: 401 Response Headers: $cache-control => 'no-cache, no-store' $pragma => 'no-cache' $content-type => 'application/json; charset=utf-8' $expires => '-1' $request-id => '8fa6a09a-0000-4710-0000-e9095e70f8f8' $x-content-type-options => 'nosniff' $date => 'Wed, 08 Jan 2014 19:47:20 GMT' $content-length => '1183' Response Body: { "error": { "code": "-2147024891, System.UnauthorizedAccessException", "message": "Access denied. You do not have permission to perform this action or access this resource." } } Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:181:in `block in api_call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient.rb:65:in `get' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:180:in `api_call' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:70:in `get_my_files_service' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:183:in `block (3 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/matchers/built_in/raise_error.rb:43:in `matches?' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:50:in `block in handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:27:in `with_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:48:in `handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:54:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:87:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:182:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [DAKOFASW] ========= =========================================================================  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.1ms) RELEASE SAVEPOINT active_record_1 RestClient.get "https://personal.skydrive.com/_vti_bin/client.svc/", "Accept"=>"*/*; q=0.5, application/xml", "Accept-Encoding"=>"gzip, deflate", "Authorization"=>"Bearer" # => 200 OK | 0 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [VBGHTLYP] ======== Method: get Endpoint: https://personal.skydrive.com/_vti_bin/client.svc/ Headers: $Authorization => 'Bearer' $Accept => '*/*; q=0.5, application/xml' $Accept-Encoding => 'gzip, deflate' Payload: --No Payload!!-- Response Code: 200 Response Headers: $www-authenticate => 'Bearer realm="4xxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx",client_id="00000003-0000-0ff1-ce00-000000000000",trusted_issuers="00000001-0000-0000-c000-000000000000@*,https://sts.windows.net/*/,00000003-0000-0ff1-ce00-000000000000@90140122-8516-11e1-8eff-49304924019b"' Response Body: Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:111:in `block in get_realm' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/resource.rb:51:in `get' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:110:in `get_realm' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:77:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [VBGHTLYP] ========= =========================================================================  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1 | | RestClient.post "https://login.windows.net/common/oauth2/token", "client_id=test&client_secret=&grant_type=refresh_token&refresh_token=&resource=NEW_TOKEN", "Accept"=>"*/*; q=0.5, application/xml", "Accept-Encoding"=>"gzip, deflate", "Content-Length"=>"88", "Content-Type"=>"application/x-www-form-urlencoded" # => 200 OK | application/json 257 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [OULBEQUJ] ======== Method: post Endpoint: https://login.windows.net/common/oauth2/token Headers: $Accept => '*/*; q=0.5, application/xml' $Accept-Encoding => 'gzip, deflate' $Content-Length => '88' $Content-Type => 'application/x-www-form-urlencoded' Payload: {:client_id=>"test", :client_secret=>nil, :grant_type=>"refresh_token", :refresh_token=>nil, :resource=>"NEW_TOKEN"} Response Code: 200 Response Headers: $cache-control => 'no-cache, no-store' $pragma => 'no-cache' $content-type => 'application/json; charset=utf-8' $expires => '-1' $request-id => '8fa6a09a-354a-4710-8650-e9095e70f8f8' $x-content-type-options => 'nosniff' $date => 'Wed, 08 Jan 2014 19:47:20 GMT' $content-length => '1183' Response Body: {"token_type":"Bearer","access_token":"ABCDEFGHIJKLMNOPQRSTUVWXYZ","expires_in":"43199","not_before":"1389210441","expires_on":"1389253641","resource":"00000003-0000-0ff1-ce00-000000000000/instructure-my.sharepoint.com@4b13a608-c248-4bd1-9017-2794c0d7e5c5"} Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:90:in `block in update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient.rb:69:in `post' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:89:in `update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:54:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [OULBEQUJ] ========= =========================================================================  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1 | | RestClient.post "https://login.windows.net/common/oauth2/token", "client_id=test&client_secret=&grant_type=refresh_token&refresh_token=&resource=NEW_TOKEN", "Accept"=>"*/*; q=0.5, application/xml", "Accept-Encoding"=>"gzip, deflate", "Content-Length"=>"88", "Content-Type"=>"application/x-www-form-urlencoded" # => 200 OK | text/html 11 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [UZMUYBHI] ======== Method: post Endpoint: https://login.windows.net/common/oauth2/token Headers: $Accept => '*/*; q=0.5, application/xml' $Accept-Encoding => 'gzip, deflate' $Content-Length => '88' $Content-Type => 'application/x-www-form-urlencoded' Payload: {:client_id=>"test", :client_secret=>nil, :grant_type=>"refresh_token", :refresh_token=>nil, :resource=>"NEW_TOKEN"} Response Code: 200 Response Headers: $cache-control => 'no-cache, no-store' $pragma => 'no-cache' $content-type => 'text/html; charset=utf-8' $expires => '-1' $request-id => '8fa6a09a-0000-4710-0000-e9095e70f8f8' $x-content-type-options => 'nosniff' $date => 'Wed, 08 Jan 2014 19:47:20 GMT' $content-length => '1183' Response Body: Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:90:in `block in update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient.rb:69:in `post' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:89:in `update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:123:in `block (3 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/matchers/built_in/raise_error.rb:43:in `matches?' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:50:in `block in handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:27:in `with_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:48:in `handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:54:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:87:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:122:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [UZMUYBHI] ========= =========================================================================  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.804566"], ["email", "email@email.com"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.804566"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.810878') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.814667"], ["expired_at", "2017-04-13 22:36:18.814833"], ["oauth_code", "102806a0-ec9e-42af-ac33-5b9a7e2e94c6"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.817523"], ["email", "email@email.com"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.817523"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.819534') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.820431"], ["expired_at", "2017-04-13 22:36:18.820520"], ["oauth_code", "f98c7a91-42fc-438a-ad92-cff2b0b9b9df"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE (expired_at >= '2017-04-13 21:36:18.821024') AND "skydrive_api_keys"."oauth_code" = 'f98c7a91-42fc-438a-ad92-cff2b0b9b9df' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "skydrive_api_keys" SET "access_token" = ?, "oauth_code" = ? WHERE "skydrive_api_keys"."id" = 1 [["access_token", "060df635-7c03-43ea-8613-26c11570cd39"], ["oauth_code", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.824062"], ["email", "email@email.com"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.824062"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.825976') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.2ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.831755"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.831755"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.833890') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#skydrive_authorized as HTML  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.838093"], ["expired_at", "2017-04-13 22:06:18.838243"], ["oauth_code", "43b2d524-7e1e-491b-96d4-181f0ac52a37"], ["scope", "skydrive_oauth"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Rendered text template (0.0ms) Completed 401 Unauthorized in 5ms (Views: 3.0ms | ActiveRecord: 0.2ms)  (0.1ms) rollback transaction  (0.1ms) begin transaction Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.854004"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.854004"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.856142') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "access_token" = ?, "expires_on" = ? WHERE "skydrive_tokens"."id" = 1 [["access_token", "token"], ["expires_on", "2017-04-20 21:36:18.856780"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#skydrive_authorized as HTML Completed 201 Created in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.864311"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.864311"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.866435') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.1ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.869559') [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.870039') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.870698"], ["expired_at", "2017-04-13 22:36:18.870804"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "9287e5d7-8b2f-439e-b7b0-0ca905724a0e"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/9287e5d7-8b2f-439e-b7b0-0ca905724a0e Completed 302 Found in 4ms (ActiveRecord: 0.4ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = '9287e5d7-8b2f-439e-b7b0-0ca905724a0e' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1 Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."id" = ? LIMIT 1 [["id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.873193') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.876628"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.876628"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.878676') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.882055') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "skydrive_users" SET "email" = ?, "name" = ?, "updated_at" = ? WHERE "skydrive_users"."id" = 1 [["email", "updated_email@example.com"], ["name", "Updated Name"], ["updated_at", "2017-04-13 21:36:18.882700"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.883424') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.884058"], ["expired_at", "2017-04-13 22:36:18.884155"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "ce3ebd36-e077-4db3-87cf-07e939c7ceda"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/ce3ebd36-e077-4db3-87cf-07e939c7ceda Completed 302 Found in 4ms (ActiveRecord: 0.4ms) Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.886097') [["user_id", 1]]  (0.1ms) rollback transaction  (0.1ms) begin transaction Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.889494"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.889494"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.891858') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.893276"], ["expired_at", "2017-04-13 22:36:18.893398"], ["init_params", "{}"], ["oauth_code", "b41f86ae-d2a1-4ce0-a7eb-3f760fd097a4"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "skydrive_api_keys" SET "expired_at" = ? WHERE "skydrive_api_keys"."id" = 1 [["expired_at", "2017-04-13 21:36:18.894040"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.1ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.898004') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) DELETE FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = ? [["id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.899293') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.899930"], ["expired_at", "2017-04-13 22:36:18.900038"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "0808e757-2d78-48e3-8c2d-38ae5d4d136e"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/0808e757-2d78-48e3-8c2d-38ae5d4d136e Completed 302 Found in 5ms (ActiveRecord: 0.6ms)  (0.0ms) SELECT COUNT(*) FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = 1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SELECT COUNT(*) FROM "skydrive_users" WHERE "skydrive_users"."email" = 'user@email.com' Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "lti_user_id", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.906512"], ["email", "user@email.com"], ["lti_user_id", "user"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.906512"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.0ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.908328') [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.909091') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.909681"], ["expired_at", "2017-04-13 22:36:18.909777"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "0e7d37ab-fbb1-4bb9-9363-18b8d5018efe"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/0e7d37ab-fbb1-4bb9-9363-18b8d5018efe Completed 302 Found in 6ms (ActiveRecord: 0.7ms) Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."email" = 'user@email.com' ORDER BY "skydrive_users"."id" ASC LIMIT 1 Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.911713') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "lti_user_id", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.916289"], ["email", "user@email.com"], ["lti_user_id", "user"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.916289"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.0ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.918056') [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.918814') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.919402"], ["expired_at", "2017-04-13 22:36:18.919493"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "2b792e3e-8789-4c14-bff3-ff4e79be95a4"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/2b792e3e-8789-4c14-bff3-ff4e79be95a4 Completed 302 Found in 6ms (ActiveRecord: 0.7ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = '2b792e3e-8789-4c14-bff3-ff4e79be95a4' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.2ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'masquerading_user@asd.com' AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' AND "skydrive_users"."name" = 'Dr. masquerading name' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.924693"], ["email", "masquerading_user@asd.com"], ["name", "Dr. masquerading name"], ["updated_at", "2017-04-13 21:36:18.924693"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.926913') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.929908') [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.930327') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.930978"], ["expired_at", "2017-04-13 22:36:18.931111"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "be045154-3556-40e1-ba09-533cae81ca9b"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/be045154-3556-40e1-ba09-533cae81ca9b Completed 302 Found in 3ms (ActiveRecord: 0.4ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = 'be045154-3556-40e1-ba09-533cae81ca9b' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1 Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."id" = ? LIMIT 1 [["id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.933233') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "lti_user_id", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.937728"], ["email", "masqueraded session"], ["lti_user_id", "this_is_a_masqueraded_id"], ["name", "masqueraded session"], ["updated_at", "2017-04-13 21:36:18.937728"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.0ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.939470') [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.940140') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.940748"], ["expired_at", "2017-04-13 22:36:18.940846"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "d0a3ccc3-60eb-4e99-886b-0329ce8ec133"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/d0a3ccc3-60eb-4e99-886b-0329ce8ec133 Completed 302 Found in 6ms (ActiveRecord: 0.7ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = 'd0a3ccc3-60eb-4e99-886b-0329ce8ec133' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'masquerading_user@asd.com' AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' AND "skydrive_users"."name" = 'Dr. masquerading name' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.945016"], ["email", "masquerading_user@asd.com"], ["name", "Dr. masquerading name"], ["updated_at", "2017-04-13 21:36:18.945016"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.946926') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.950196') [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.950593') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.951169"], ["expired_at", "2017-04-13 22:36:18.951264"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "7bea80d1-12f3-4046-b818-fb33f6cf96ce"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/7bea80d1-12f3-4046-b818-fb33f6cf96ce Completed 302 Found in 3ms (ActiveRecord: 0.3ms) Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.953311') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SELECT COUNT(*) FROM "skydrive_users" WHERE "skydrive_users"."username" = 'this_is_a_masqueraded_id' Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "lti_user_id", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.959489"], ["email", "masqueraded session"], ["lti_user_id", "this_is_a_masqueraded_id"], ["name", "masqueraded session"], ["updated_at", "2017-04-13 21:36:18.959489"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.0ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.961330') [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.961975') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.962626"], ["expired_at", "2017-04-13 22:36:18.962723"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "5a26b9b5-5c1e-41b8-89a1-91c61c1960b4"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/5a26b9b5-5c1e-41b8-89a1-91c61c1960b4 Completed 302 Found in 6ms (ActiveRecord: 0.7ms) Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.964683') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'masquerading_user@asd.com' AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' AND "skydrive_users"."name" = 'Dr. masquerading name' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.968169"], ["email", "masquerading_user@asd.com"], ["name", "Dr. masquerading name"], ["updated_at", "2017-04-13 21:36:18.968169"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.970105') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.972979') [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.973374') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.973966"], ["expired_at", "2017-04-13 22:36:18.974062"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "b59b06bf-af05-4c24-b002-77c063868720"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/b59b06bf-af05-4c24-b002-77c063868720 Completed 302 Found in 3ms (ActiveRecord: 0.3ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = 'b59b06bf-af05-4c24-b002-77c063868720' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1 Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."id" = ? LIMIT 1 [["id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.976215') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'masquerading_user@asd.com' AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' AND "skydrive_users"."name" = 'Dr. masquerading name' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-13 21:36:18.979578"], ["email", "masquerading_user@asd.com"], ["name", "Dr. masquerading name"], ["updated_at", "2017-04-13 21:36:18.979578"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.981496') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.982392"], ["expired_at", "2017-04-13 22:36:18.982482"], ["init_params", "{}"], ["oauth_code", "281c3f00-68bd-4ea0-830c-e9a79214a163"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.0ms) UPDATE "skydrive_api_keys" SET "expired_at" = ? WHERE "skydrive_api_keys"."id" = 1 [["expired_at", "2017-04-13 21:36:18.983065"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.986442') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.0ms) DELETE FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = ? [["id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.987450') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.988107"], ["expired_at", "2017-04-13 22:36:18.988215"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "d7fd2691-54e0-4471-906d-5e11511722a3"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/d7fd2691-54e0-4471-906d-5e11511722a3 Completed 302 Found in 4ms (ActiveRecord: 0.5ms)  (0.1ms) SELECT COUNT(*) FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = 1  (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) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-13 21:36:18.993778"], ["email", "email@email.com"], ["name", "User"], ["updated_at", "2017-04-13 21:36:18.993778"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-13 21:36:18.995802') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.0ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = 1 LIMIT 1  (0.0ms) ROLLBACK TO SAVEPOINT active_record_1  (0.1ms) rollback transaction ** [Raven] Raven 0.13.3 configured not to send errors. ActiveRecord::SchemaMigration Load (0.1ms) SELECT "schema_migrations".* FROM "schema_migrations" Skydrive::Account Load (0.1ms) SELECT "skydrive_accounts".* FROM "skydrive_accounts" WHERE "skydrive_accounts"."key" = 'one' AND "skydrive_accounts"."secret" = 'not_two' LIMIT 1  (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.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.2ms) INSERT INTO "skydrive_users" ("created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.491710"], ["email", "email@email.com"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.491710"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.2ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.501513') [["user_id", 1]]  (0.1ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = 1 LIMIT 1  (0.0ms) ROLLBACK TO SAVEPOINT active_record_1  (0.2ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1 | | RestClient.post "https://login.windows.net/common/oauth2/token", "client_id=test&client_secret=&grant_type=refresh_token&refresh_token=&resource=NEW_TOKEN", "Accept"=>"*/*; q=0.5, application/xml", "Accept-Encoding"=>"gzip, deflate", "Content-Length"=>"88", "Content-Type"=>"application/x-www-form-urlencoded" # => 200 OK | application/json 584 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [RGACZXNJ] ======== Method: post Endpoint: https://login.windows.net/common/oauth2/token Headers: $Accept => '*/*; q=0.5, application/xml' $Accept-Encoding => 'gzip, deflate' $Content-Length => '88' $Content-Type => 'application/x-www-form-urlencoded' Payload: {:client_id=>"test", :client_secret=>nil, :grant_type=>"refresh_token", :refresh_token=>nil, :resource=>"NEW_TOKEN"} Response Code: 200 Response Headers: $cache-control => 'no-cache, no-store' $pragma => 'no-cache' $content-type => 'application/json' $expires => '-1' $request-id => '8fa6a09a-354a-4710-8650-e9095e70f8f8' $x-content-type-options => 'nosniff' $date => 'Wed, 08 Jan 2014 19:47:20 GMT' $content-length => '1183' Response Body: { "error_description" : "AADSTS90014: The request body must contain the following parameter: 'refresh_token'.\r\nTrace ID: 1a18b1f6-0000-4129-0000-7fa865546c86\r\nCorrelation ID: 4e75524f-0000-499b-0000-e3469408748d\r\nTimestamp: 2015-05-01 19:52:25Z", "correlation_id" : "4e75524f-0000-499b-0000-e3469408748d", "error_codes" : [ 90014 ], "timestamp" : "2015-05-01 19:52:25Z", "trace_id" : "1a18b1f6-0000-4129-0000-7fa865546c86", "error" : "invalid_request", "context" : null, "submit_url" : null } Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:90:in `block in update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient.rb:69:in `post' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:89:in `update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:157:in `block (3 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/matchers/built_in/raise_error.rb:43:in `matches?' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:50:in `block in handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:27:in `with_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:48:in `handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:54:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:87:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:156:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [RGACZXNJ] ========= =========================================================================  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.2ms) rollback transaction  (0.1ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.2ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1 | | RestClient.post "https://login.windows.net/common/oauth2/token", "client_id=test&client_secret=&grant_type=refresh_token&refresh_token=&resource=NEW_TOKEN", "Accept"=>"*/*; q=0.5, application/xml", "Accept-Encoding"=>"gzip, deflate", "Content-Length"=>"88", "Content-Type"=>"application/x-www-form-urlencoded" # => 200 OK | application/json 257 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [UJZPKWPF] ======== Method: post Endpoint: https://login.windows.net/common/oauth2/token Headers: $Accept => '*/*; q=0.5, application/xml' $Accept-Encoding => 'gzip, deflate' $Content-Length => '88' $Content-Type => 'application/x-www-form-urlencoded' Payload: {:client_id=>"test", :client_secret=>nil, :grant_type=>"refresh_token", :refresh_token=>nil, :resource=>"NEW_TOKEN"} Response Code: 200 Response Headers: $cache-control => 'no-cache, no-store' $pragma => 'no-cache' $content-type => 'application/json; charset=utf-8' $expires => '-1' $request-id => '8fa6a09a-354a-4710-8650-e9095e70f8f8' $x-content-type-options => 'nosniff' $date => 'Wed, 08 Jan 2014 19:47:20 GMT' $content-length => '1183' Response Body: {"token_type":"Bearer","access_token":"ABCDEFGHIJKLMNOPQRSTUVWXYZ","expires_in":"43199","not_before":"1389210441","expires_on":"1389253641","resource":"00000003-0000-0ff1-ce00-000000000000/instructure-my.sharepoint.com@4b13a608-c248-4bd1-9017-2794c0d7e5c5"} Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:90:in `block in update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient.rb:69:in `post' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:89:in `update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:54:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [UJZPKWPF] ========= =========================================================================  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.1ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.1ms) RELEASE SAVEPOINT active_record_1 RestClient.get "https://api.office.com/discovery/v1.0/me/services", "Accept"=>"", "Accept-Encoding"=>"gzip, deflate", "Authorization"=>"Bearer " # => 401 Unauthorized | application/json 250 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [UTJWZFXI] ======== Method: get Endpoint: https://api.office.com/discovery/v1.0/me/services Headers: $Accept => '' $Authorization => 'Bearer ' $Accept-Encoding => 'gzip, deflate' Payload: --No Payload!!-- Response Code: 401 Response Headers: $cache-control => 'no-cache, no-store' $pragma => 'no-cache' $content-type => 'application/json; charset=utf-8' $expires => '-1' $request-id => '8fa6a09a-0000-4710-0000-e9095e70f8f8' $x-content-type-options => 'nosniff' $date => 'Wed, 08 Jan 2014 19:47:20 GMT' $content-length => '1183' Response Body: { "error": { "code": "-2147024891, System.UnauthorizedAccessException", "message": "Access denied. You do not have permission to perform this action or access this resource." } } Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:181:in `block in api_call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient.rb:65:in `get' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:180:in `api_call' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:70:in `get_my_files_service' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:183:in `block (3 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/matchers/built_in/raise_error.rb:43:in `matches?' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:50:in `block in handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:27:in `with_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:48:in `handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:54:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:87:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:182:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [UTJWZFXI] ========= =========================================================================  (0.3ms) rollback transaction  (0.2ms) begin transaction  (0.2ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.2ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.2ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.1ms) RELEASE SAVEPOINT active_record_1 RestClient.get "https://personal.skydrive.com/_vti_bin/client.svc/", "Accept"=>"*/*; q=0.5, application/xml", "Accept-Encoding"=>"gzip, deflate", "Authorization"=>"Bearer" # => 200 OK | 0 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [SHFYFKYN] ======== Method: get Endpoint: https://personal.skydrive.com/_vti_bin/client.svc/ Headers: $Authorization => 'Bearer' $Accept => '*/*; q=0.5, application/xml' $Accept-Encoding => 'gzip, deflate' Payload: --No Payload!!-- Response Code: 200 Response Headers: $www-authenticate => 'Bearer realm="4xxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx",client_id="00000003-0000-0ff1-ce00-000000000000",trusted_issuers="00000001-0000-0000-c000-000000000000@*,https://sts.windows.net/*/,00000003-0000-0ff1-ce00-000000000000@90140122-8516-11e1-8eff-49304924019b"' Response Body: Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:111:in `block in get_realm' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/resource.rb:51:in `get' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:110:in `get_realm' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:77:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [SHFYFKYN] ========= =========================================================================  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1 | | RestClient.post "https://login.windows.net/common/oauth2/token", "client_id=test&client_secret=&grant_type=refresh_token&refresh_token=&resource=NEW_TOKEN", "Accept"=>"*/*; q=0.5, application/xml", "Accept-Encoding"=>"gzip, deflate", "Content-Length"=>"88", "Content-Type"=>"application/x-www-form-urlencoded" # => 200 OK | text/html 11 bytes ========================================================================== ========= BEGIN SKYDRIVE RestClient Response log [MCFLWZDZ] ======== Method: post Endpoint: https://login.windows.net/common/oauth2/token Headers: $Accept => '*/*; q=0.5, application/xml' $Accept-Encoding => 'gzip, deflate' $Content-Length => '88' $Content-Type => 'application/x-www-form-urlencoded' Payload: {:client_id=>"test", :client_secret=>nil, :grant_type=>"refresh_token", :refresh_token=>nil, :resource=>"NEW_TOKEN"} Response Code: 200 Response Headers: $cache-control => 'no-cache, no-store' $pragma => 'no-cache' $content-type => 'text/html; charset=utf-8' $expires => '-1' $request-id => '8fa6a09a-0000-4710-0000-e9095e70f8f8' $x-content-type-options => 'nosniff' $date => 'Wed, 08 Jan 2014 19:47:20 GMT' $content-length => '1183' Response Body: Caller: /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:90:in `block in update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:493:in `process_result' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:421:in `block in transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:123:in `start_without_connect' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/webmock-1.21.0/lib/webmock/http_lib_adapters/net_http.rb:150:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:413:in `transmit' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:176:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient/request.rb:41:in `execute' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rest-client-1.8.0/lib/restclient.rb:69:in `post' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/lib/skydrive/client.rb:89:in `update_api_tokens' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:123:in `block (3 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/matchers/built_in/raise_error.rb:43:in `matches?' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:50:in `block in handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:27:in `with_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/handler.rb:48:in `handle_matcher' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:54:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-expectations-3.2.1/lib/rspec/expectations/expectation_target.rb:87:in `to' /home/CORP.INSTRUCTURE.COM/abutterfield/code/dev/lti_skydrive_engine/spec/lib/skydrive/client_spec.rb:122:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:177:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `block in with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `block in with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `block in run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:614:in `block in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-rails-3.2.1/lib/rspec/rails/adapters.rb:113:in `block (2 levels) in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:333:in `instance_exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:387:in `execute_with' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:616:in `block (2 levels) in run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:247:in `call' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:617:in `run_around_example_hooks_for' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/hooks.rb:474:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:343:in `with_around_example_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:385:in `with_around_and_singleton_context_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example.rb:174:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:549:in `block in run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:545:in `run_examples' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/example_group.rb:512:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (3 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `map' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:110:in `block (2 levels) in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/configuration.rb:1526:in `with_suite_hooks' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:109:in `block in run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/reporter.rb:62:in `report' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:108:in `run_specs' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:86:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:70:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/lib/rspec/core/runner.rb:38:in `invoke' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/rspec-core-3.2.3/exe/rspec:4:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/rspec:23:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:74:in `kernel_load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli/exec.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:335:in `exec' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor.rb:359:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:20:in `dispatch' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/vendor/thor/lib/thor/base.rb:440:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/cli.rb:11:in `start' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:32:in `block in ' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/gems/bundler-1.14.6/exe/bundle:24:in `' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `load' /home/CORP.INSTRUCTURE.COM/abutterfield/.gem/ruby/2.3.0/bin/bundle:23:in `
' ========= END SKYDRIVE RestClient Response log [MCFLWZDZ] ========= =========================================================================  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.576354"], ["email", "email@email.com"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.576354"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.578879') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.581883"], ["email", "email@email.com"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.581883"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.584242') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 SQL (0.2ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.589061"], ["expired_at", "2017-04-14 16:04:14.589243"], ["oauth_code", "9d272bff-a9af-4b03-bc3a-7b68535000d5"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE (expired_at >= '2017-04-14 15:04:14.590268') AND "skydrive_api_keys"."oauth_code" = '9d272bff-a9af-4b03-bc3a-7b68535000d5' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "skydrive_api_keys" SET "access_token" = ?, "oauth_code" = ? WHERE "skydrive_api_keys"."id" = 1 [["access_token", "d3ff8131-765e-4724-b0a7-d15f7a72daf0"], ["oauth_code", nil]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = ? LIMIT 1 [["id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.594445"], ["email", "email@email.com"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.594445"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.596793') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.597807"], ["expired_at", "2017-04-14 16:04:14.597907"], ["oauth_code", "2e7994ad-c54d-440d-b7e6-c0830e87684f"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction  (0.0ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'masquerading_user@asd.com' AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' AND "skydrive_users"."name" = 'Dr. masquerading name' LIMIT 1 [["account_id", 1]]  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.616124"], ["email", "masquerading_user@asd.com"], ["name", "Dr. masquerading name"], ["updated_at", "2017-04-14 15:04:14.616124"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.619096') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.1ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.624334') [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.624872') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.625702"], ["expired_at", "2017-04-14 16:04:14.625894"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "e130f1f9-6e15-4215-916f-cc2fe6e5a75f"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/e130f1f9-6e15-4215-916f-cc2fe6e5a75f Completed 302 Found in 5ms (ActiveRecord: 0.6ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = 'e130f1f9-6e15-4215-916f-cc2fe6e5a75f' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1 Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."id" = ? LIMIT 1 [["id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.2ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.629738') [["user_id", 1]]  (0.2ms) rollback transaction  (0.0ms) begin transaction Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.2ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "lti_user_id", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.637419"], ["email", "masqueraded session"], ["lti_user_id", "this_is_a_masqueraded_id"], ["name", "masqueraded session"], ["updated_at", "2017-04-14 15:04:14.637419"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.0ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.640008') [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.640865') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.641681"], ["expired_at", "2017-04-14 16:04:14.641810"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "fbff0932-f3d7-40f0-a075-e692920a205c"], ["scope", "session"], ["user_id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/fbff0932-f3d7-40f0-a075-e692920a205c Completed 302 Found in 8ms (ActiveRecord: 1.1ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = 'fbff0932-f3d7-40f0-a075-e692920a205c' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'masquerading_user@asd.com' AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' AND "skydrive_users"."name" = 'Dr. masquerading name' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.647377"], ["email", "masquerading_user@asd.com"], ["name", "Dr. masquerading name"], ["updated_at", "2017-04-14 15:04:14.647377"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.649871') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.1ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.654831') [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.655385') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.656340"], ["expired_at", "2017-04-14 16:04:14.656491"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "e2667c57-9784-4695-94be-b104537e9d6d"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/e2667c57-9784-4695-94be-b104537e9d6d Completed 302 Found in 5ms (ActiveRecord: 0.5ms) Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.659002') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'masquerading_user@asd.com' AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' AND "skydrive_users"."name" = 'Dr. masquerading name' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.663414"], ["email", "masquerading_user@asd.com"], ["name", "Dr. masquerading name"], ["updated_at", "2017-04-14 15:04:14.663414"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.665954') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.667085"], ["expired_at", "2017-04-14 16:04:14.667205"], ["init_params", "{}"], ["oauth_code", "54f1ed88-9e07-40e6-ba2a-936f3f5576c4"], ["scope", "session"], ["user_id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "skydrive_api_keys" SET "expired_at" = ? WHERE "skydrive_api_keys"."id" = 1 [["expired_at", "2017-04-14 15:04:14.667889"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.671252') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) DELETE FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = ? [["id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.672306') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.672957"], ["expired_at", "2017-04-14 16:04:14.673062"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "753e0339-628e-4ecb-9156-20066f8a795c"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/753e0339-628e-4ecb-9156-20066f8a795c Completed 302 Found in 4ms (ActiveRecord: 0.5ms)  (0.1ms) SELECT COUNT(*) FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = 1  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'masquerading_user@asd.com' AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' AND "skydrive_users"."name" = 'Dr. masquerading name' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.678217"], ["email", "masquerading_user@asd.com"], ["name", "Dr. masquerading name"], ["updated_at", "2017-04-14 15:04:14.678217"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.680621') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.1ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.1ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.684833') [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.685464') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.686320"], ["expired_at", "2017-04-14 16:04:14.686451"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "dac44c4c-b0d8-4906-bb64-ad546de466b5"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/dac44c4c-b0d8-4906-bb64-ad546de466b5 Completed 302 Found in 5ms (ActiveRecord: 0.5ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = 'dac44c4c-b0d8-4906-bb64-ad546de466b5' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1 Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."id" = ? LIMIT 1 [["id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.689194') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SELECT COUNT(*) FROM "skydrive_users" WHERE "skydrive_users"."username" = 'this_is_a_masqueraded_id' Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "lti_user_id", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.695283"], ["email", "masqueraded session"], ["lti_user_id", "this_is_a_masqueraded_id"], ["name", "masqueraded session"], ["updated_at", "2017-04-14 15:04:14.695283"], ["username", "this_is_a_masqueraded_id"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.0ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.697342') [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.698088') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.698761"], ["expired_at", "2017-04-14 16:04:14.698873"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "1d3e796b-97e7-46eb-9f82-7a57e2a07bb2"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/1d3e796b-97e7-46eb-9f82-7a57e2a07bb2 Completed 302 Found in 7ms (ActiveRecord: 0.7ms) Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."username" = 'this_is_a_masqueraded_id' ORDER BY "skydrive_users"."id" ASC LIMIT 1 Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.701151') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.704316"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.704316"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.706541') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "access_token" = ?, "expires_on" = ? WHERE "skydrive_tokens"."id" = 1 [["access_token", "token"], ["expires_on", "2017-04-21 15:04:14.707236"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#skydrive_authorized as HTML Completed 201 Created in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.714381"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.714381"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.717395') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#skydrive_authorized as HTML  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.721652"], ["expired_at", "2017-04-14 15:34:14.721770"], ["oauth_code", "c5add166-1f50-4981-844c-ff60c8bb7517"], ["scope", "skydrive_oauth"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Completed 401 Unauthorized in 6ms (Views: 3.3ms | ActiveRecord: 0.2ms)  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.730242"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.730242"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.1ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.733300') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.739258') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) UPDATE "skydrive_users" SET "email" = ?, "name" = ?, "updated_at" = ? WHERE "skydrive_users"."id" = 1 [["email", "updated_email@example.com"], ["name", "Updated Name"], ["updated_at", "2017-04-14 15:04:14.740194"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.741090') [["user_id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.741969"], ["expired_at", "2017-04-14 16:04:14.742125"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "a1411c2d-0909-437a-b53c-11db9fafd238"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/a1411c2d-0909-437a-b53c-11db9fafd238 Completed 302 Found in 6ms (ActiveRecord: 0.7ms) Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.744763') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.1ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.749436"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.749436"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.752051') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.755583') [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.756122') [["user_id", 1]]  (0.1ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.756939"], ["expired_at", "2017-04-14 16:04:14.757071"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "cc55963c-c507-4cfc-9ef8-e74d3194fa24"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/cc55963c-c507-4cfc-9ef8-e74d3194fa24 Completed 302 Found in 4ms (ActiveRecord: 0.4ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = 'cc55963c-c507-4cfc-9ef8-e74d3194fa24' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1 Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."id" = ? LIMIT 1 [["id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.759573') [["user_id", 1]]  (0.1ms) rollback transaction  (0.0ms) begin transaction Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."email" = 'user@email.com' AND "skydrive_users"."username" = 'user' AND "skydrive_users"."name" = 'User' LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.763688"], ["email", "user@email.com"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.763688"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.1ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.766102') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.767139"], ["expired_at", "2017-04-14 16:04:14.767260"], ["init_params", "{}"], ["oauth_code", "ddad176d-bf3f-4516-a413-a5fc9573f91d"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1  (0.0ms) SAVEPOINT active_record_1 SQL (0.0ms) UPDATE "skydrive_api_keys" SET "expired_at" = ? WHERE "skydrive_api_keys"."id" = 1 [["expired_at", "2017-04-14 15:04:14.767934"]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]] Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.771312') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.0ms) DELETE FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = ? [["id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.772504') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.773184"], ["expired_at", "2017-04-14 16:04:14.773289"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "4e5e05c1-4ffd-4804-a56c-3211c4ee8cf6"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/4e5e05c1-4ffd-4804-a56c-3211c4ee8cf6 Completed 302 Found in 5ms (ActiveRecord: 0.5ms)  (0.1ms) SELECT COUNT(*) FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."id" = 1  (0.1ms) rollback transaction  (0.0ms) begin transaction Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "lti_user_id", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.781042"], ["email", "user@email.com"], ["lti_user_id", "user"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.781042"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.0ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.783342') [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.784245') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.784943"], ["expired_at", "2017-04-14 16:04:14.785058"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "80cb2615-6197-4103-9bad-28b25d7b1b5d"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/80cb2615-6197-4103-9bad-28b25d7b1b5d Completed 302 Found in 8ms (ActiveRecord: 0.8ms) Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."oauth_code" = '80cb2615-6197-4103-9bad-28b25d7b1b5d' ORDER BY "skydrive_api_keys"."id" ASC LIMIT 1  (0.1ms) rollback transaction  (0.0ms) begin transaction  (0.1ms) SELECT COUNT(*) FROM "skydrive_users" WHERE "skydrive_users"."email" = 'user@email.com' Processing by Skydrive::LaunchController#basic_launch as HTML Skydrive::User Load (0.0ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."account_id" = ? AND "skydrive_users"."username" = 'user' ORDER BY "skydrive_users"."id" ASC LIMIT 1 [["account_id", 1]]  (0.0ms) SAVEPOINT active_record_1 Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" IS NULL LIMIT 1 SQL (0.1ms) INSERT INTO "skydrive_users" ("account_id", "created_at", "email", "lti_user_id", "name", "updated_at", "username") VALUES (?, ?, ?, ?, ?, ?, ?) [["account_id", 1], ["created_at", "2017-04-14 15:04:14.792331"], ["email", "user@email.com"], ["lti_user_id", "user"], ["name", "User"], ["updated_at", "2017-04-14 15:04:14.792331"], ["username", "user"]] SQL (0.1ms) INSERT INTO "skydrive_tokens" DEFAULT VALUES Skydrive::Token Exists (0.1ms) SELECT 1 AS one FROM "skydrive_tokens" WHERE ("skydrive_tokens"."user_id" = 1 AND "skydrive_tokens"."id" != 1) LIMIT 1 SQL (0.0ms) UPDATE "skydrive_tokens" SET "user_id" = ? WHERE "skydrive_tokens"."id" = 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.794569') [["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.795475') [["user_id", 1]]  (0.0ms) SAVEPOINT active_record_1 SQL (0.1ms) INSERT INTO "skydrive_api_keys" ("created_at", "expired_at", "init_params", "oauth_code", "scope", "user_id") VALUES (?, ?, ?, ?, ?, ?) [["created_at", "2017-04-14 15:04:14.796219"], ["expired_at", "2017-04-14 16:04:14.796356"], ["init_params", "{\"controller\":\"skydrive/launch\",\"action\":\"basic_launch\"}"], ["oauth_code", "d89248fb-9ae9-4419-98d1-79a932558026"], ["scope", "session"], ["user_id", 1]]  (0.0ms) RELEASE SAVEPOINT active_record_1 Redirected to http://test.host/skydrive/launch/d89248fb-9ae9-4419-98d1-79a932558026 Completed 302 Found in 7ms (ActiveRecord: 0.8ms) Skydrive::User Load (0.1ms) SELECT "skydrive_users".* FROM "skydrive_users" WHERE "skydrive_users"."email" = 'user@email.com' ORDER BY "skydrive_users"."id" ASC LIMIT 1 Skydrive::Token Load (0.0ms) SELECT "skydrive_tokens".* FROM "skydrive_tokens" WHERE "skydrive_tokens"."user_id" = ? LIMIT 1 [["user_id", 1]] Skydrive::ApiKey Load (0.1ms) SELECT "skydrive_api_keys".* FROM "skydrive_api_keys" WHERE "skydrive_api_keys"."user_id" = ? AND (expired_at < '2017-04-14 15:04:14.798614') [["user_id", 1]]  (0.1ms) rollback transaction