Updated Terms of Use Dialog Box producing an error

[b0rgbart3]

I installed Canvas on my local Mac running OSX 10.10.3 with a 2.3 GZ processor and 4GB of ram,
and when I run the server on LocalHost3000, first I get the cool gray login-dialog box where I type in my login info, and then I get a new screen with a dialog box that says "Updated Terms of Use"... I click "Agree", and then I click "Submit".... but then instead of getting routed to the dashboard (or root route).... I just get a chunk of code displayed in the browser (which looks like my login hash data).... which should be getting processed by some method in the app instead of getting sent to the browser.

Help!

See attached screenshots

[b0rgbart3]

It may be of help to see the output from canvas in my terminal while this is happening which starts with this: (Note: the Routing Error) ->

[FreedomBen]

This looks like a (somewhat) known issue with canvas. We're not sure yet what causes it, but I think it has something to do with a crash in the Google Analytics javascript that prevents later javascript from being loaded. The GA script crashes when it can't get out to the internet (which is super annoying for sure).

Make sure you have no firewalls or other things blocking traffic to the internet. Also make sure no browser plugins/extensions are blocking the requests.

[b0rgbart3]

Thank you for this comment.
I had high hopes that this would solve my problem, because I was running 'LittleSnitch" which monitors network traffic and blocks network requests.
However, I turned off the Network Filter and set the app to "silent mode" which should allow any connection attempts, and rebooted my system. I know the app is no longer blocking connections because it doesn't warn me about various connections while I am surfing the web.
But unfortunately, - I am still having the same issue.

-[x] I de-activated the 'LittleSnitch' network traffic monitoring application.

-[x] I checked to make sure there are no other firewalls or plugins/ extensions that might be blocking network requests.

-[x] I tried using Safari instead of Google's Chrome browser -

-[x] I saw that canvas prefers Ruby 2.1 as opposed to the 2.2 that I had installed, so I installed rvm so that I could specify Ruby 2.1. But that has not helped.

-[x] I also saw that canvas prefers node version 0.10.33 instead of the 0.12.5 that I had installed, so I also installed nvm so that I could specify to use node v.0.10.33. -- But that has not helped either.

Because you mentioned this might be a problem with the Google Analytics javascript code - I thought I would try running the rake db:initial_setup again, and choose to opt out of the collection of usage data. (Initially I had chosen option 2 - to send anonymized data.) I thought it was curious to note that doing so produced a change in the hash that appears to a more generic error report. - But still doesn't let me in to the dashboard. :-(

{"errors":[{"message":"An error occurred.","error_code":"internal_server_error"}],"error_report_id":8}

[claydiffrient]

@b0rgbart3 I've been working to figure out what's going on. Could you perhaps post a gist/screenshot of what is found at <your_canvas>/error_reports/8 ? If that URL doesn't work then please post the relevant log file information from log/development.log or log/production.log.

[b0rgbart3]

Hi Clay- Thanks for jumping in on this.
I don't see an "/error_reports" folder.
But here is the log/development.log file:
(Because it's a very long file, I posted it to my webserver here):
http://www.bartdority.com/development_log

Note however, that I am attempting to install / run / test the canvas app
on my local machine (a Mac running OSX10.10.3) --not the webserver
where I've posted this log file to. I'm guessing that means I'm still in 'development',
and hence the development log as opposed to a production.log.

I was following the QuickStart Guide instructions (https://github.com/instructure/canvas-lms/wiki/Quick-Start) --not the Production Start guide.

Thanks for your help.

[claydiffrient]

@b0rgbart3 Yeah so error_reports is actually an endpoint for looking at the errors in the web browser. So I should have said <your_canvas_url>/error_reports/8 which in most cases is http://localhost:3000/error_reports/ when running locally. It generally distills a lot of information from the logs. I'll dig into your logs and see if I can find anything fishy there.

[claydiffrient]

@b0rgbart3 What browser are you using to do this with?

[claydiffrient]

@b0rgbart3 please replace the file app/views/shared/terms_required.html.erb with a different version found here for now: https://gist.github.com/claydiffrient/caeec36736eb04ae1b4e and let me know if you still have the problem.

[claydiffrient]

@b0rgbart3 Could you also double check that you have run rake canvas:compile_assets successfully?

[b0rgbart3]

After substituting that file, I still have the same problem, although the error msg was slightly different:
{"errors":[{"message":"Invalid access token."}],"error_report_id":13}

You make a very good point to ask about having run the canvas:compile_assets successfully or not - because I haven't . In all of my attempts at getting this install to work - I have almost always had trouble with npm install errors. In fact, this was actually my first issue that i raised -- see Issue #3: (#3).

I tried npm install again just now and got these error messages:

$ npm install
npm ERR! install Couldn't read dependencies
npm ERR! Darwin 14.3.0
npm ERR! argv "node" "/usr/local/bin/npm" "install"
npm ERR! node v0.12.5
npm ERR! npm  v2.11.2
npm ERR! path /Users/bartdority/package.json
npm ERR! code ENOPACKAGEJSON
npm ERR! errno -2

npm ERR! package.json ENOENT, open '/Users/bartdority/package.json'
npm ERR! package.json This is most likely not a problem with npm itself.
npm ERR! package.json npm can't find a package.json file in your current directory.

npm ERR! Please include the following file with any support request:
npm ERR!     /Users/bartdority/npm-debug.log
$ cd canvas
$ npm install
npm WARN package.json canvas-lms@0.0.0 No license field.
npm WARN cannot run in wd canvas-lms@0.0.0 script/gem_npm install     (wd=/Users/bartdority/canvas)

I then realized that it might have forgotten to specify that nvm use node 0.10.33 -- so after specifying: nvm use v0.10.33 -- and running rake canvas:compile_assets, I get this output:

$ rake canvas:compile_assets
working in 8 processes
--> Starting: 'Compile sass and make jammit css bundles'
--> Starting: 'npm run compile-sass'--> Starting: 'css:styleguide'
--> Starting: 'Generate documentation [yardoc]'
--> Starting: 'compile coffee, js 18n, and run r.js optimizer'
--> Starting: 'js:generate'
--> creating styleguide
Building client app 'canvas_quizzes'
    Running 'npm install'...
Parsing app/controllers/login/shared.rb.
> canvas-lms@0.0.0 compile-sass /Users/bartdority/canvas
> node script/compile-sass.js

npm WARN package.json canvas_quizzes@1.0.0 No repository field.
⌍ ParsinRunning 'npm run build'...tions_controller.rb.
[warn]: @param tag has unknown parameter name: conversations 
    in file `app/controllers/quizzes/quiz_submission_users_controller.rb' near line 139
# writing file: app/views/info/styleguide.html.erb
--> Finished: 'css:styleguide' in 21.955255
Client app 'canvas_quizzes' was built successfully.i/all_resources.html..................... 
--> Compiling React JSX
--> Generating plugin extensions--> Creating ember app bundles
--> Pre-compiling handlebars templates
--> Pre-compiling ember handlebars templates
--> Generating plugin extensions finished in 0.085487
--> Creating ember app bundles finished in 0.113545
module.js:340
module.js:340
module.js:340
module.js:340
module.js:340
module.js:340
module.js:340
module.js:340
module.js:340
    throw err;
          ^
    throw err;
          ^
    throw err;
      ^

     ^
Error: Cannot find module 'handlebars'
    at Function.Module._resolveFilename (module.js:338:15)
    at Function.Module._load (module.js:280:25)
    at Module.require (module.js:364:17)
    at require (module.js:380:17)
    at Object.<anonymous> (/Users/bartdority/canvas/gems/canvas_i18nliner/bin/prepare_hbs:4:18)
    at Module._compile (module.js:456:26)
    at Object.Module._extensions..js (module.js:474:10)
    at Module.load (module.js:356:32)
    at Function.Module._load (module.js:312:12)
    at Function.Module.runMain (module.js:497:10)

[claydiffrient]

@b0rgbart3 with that sort of error I think the best approach would be to do this: rm -rf node_modules && rm -rf gems/**/node_modules && npm cache clean && npm install and then try running rake canvas:compile_assets again.

[b0rgbart3]

Same Problem.

Here is the terminal output after typing these commands: rm -rf node_modules && rm -rf gems/**/node_modules && npm cache clean && npm install

Interesting that I didn't get any actual "ERROR" messages - just a whole lot of warnings - but when I launch the app - and click SUBMIT on the T.O.U dialog - I still get the same error in the browser.
(which reads:-> "{"errors":[{"message":"Invalid access token."}],"error_report_id":21}")

This is my terminal output:
$ npm install
npm WARN package.json canvas-lms@0.0.0 No license field.
npm WARN cannot run in wd canvas-lms@0.0.0 script/gem_npm install (wd=/Users/bartdority/canvas)
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/node-sass requires mkdirp@'~0.3.5' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/mkdirp,
npm WARN unmet dependency which is version 0.5.1
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/karma requires glob@'~3.1.21' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/glob,
npm WARN unmet dependency which is version 3.2.11
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/karma requires lodash@'~1.1' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/lodash,
npm WARN unmet dependency which is version 2.4.2
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/karma-osx-reporter/node_modules/node-osx-notifier/node_modules/express requires mkdirp@'0.3.3' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/mkdirp,
npm WARN unmet dependency which is version 0.5.1
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/karma-coverage/node_modules/istanbul requires mkdirp@'0.3.x' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/mkdirp,
npm WARN unmet dependency which is version 0.5.1
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/engine.io requires debug@'1.0.3' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/debug,
npm WARN unmet dependency which is version 2.1.0
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/socket.io-parser requires debug@'0.7.4' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/debug,
npm WARN unmet dependency which is version 2.1.0
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/socket.io-client requires debug@'0.7.4' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/debug,
npm WARN unmet dependency which is version 2.1.0
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/socket.io-adapter requires debug@'1.0.2' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/debug,
npm WARN unmet dependency which is version 2.1.0
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/socket.io-adapter requires socket.io-parser@'2.2.2' but will load
npm WARN unmet dependency /Users/bartdority/canvas/node_modules/testem/node_modules/socket.io/node_modules/socket.io-parser,
npm WARN unmet dependency which is version 2.2.4

$GEM_HOME/bin/bundle exec rails server
=> Booting Thin
=> Rails 3.2.21 application starting in development on http://0.0.0.0:3000
=> Call with -d to detach
=> Ctrl-C to shutdown server
Thin web server (v1.6.3 codename Protein Powder)
Maximum connections set to 1024
Listening on 0.0.0.0:3000, CTRL+C to stop
Jammit Warning: No assets match 'public/stylesheets_compiled/legacy_normal_contrast/bundles/facebook.css'
Jammit Warning: No assets match 'public/stylesheets_compiled/legacy_high_contrast/bundles/facebook.css'
Jammit Warning: No assets match 'public/stylesheets_compiled/new_styles_normal_contrast/bundles/facebook.css'
Jammit Warning: No assets match 'public/stylesheets_compiled/new_styles_high_contrast/bundles/facebook.css'
Jammit Warning: No assets match 'public/stylesheets_compiled/k12_normal_contrast/bundles/facebook.css'
Jammit Warning: No assets match 'public/stylesheets_compiled/k12_high_contrast/bundles/facebook.css'
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Started GET "/" for 127.0.0.1 at 2015-07-03 11:36:12 -0700
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.7ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'account_special_account_cache_time' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.5ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'shard_cache_time' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.4ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'default_account_id' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "_account_lookup2/1" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Account Load (1.0ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = 1 LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "_account_lookup2/1" 0.0294
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "_account_lookup2/1" 0.0005
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/sessions" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL PluginSetting Load (0.5ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'sessions' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/sessions" 0.0071
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/sessions" 0.0001
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.6ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.up_front_cost' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.4ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.skip' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.3ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.whitelist' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.4ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.blacklist' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.4ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.hwm' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Processing by UsersController#user_dashboard as HTML
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Pseudonym Load (1.4ms) SELECT "pseudonyms".* FROM "pseudonyms" WHERE "pseudonyms"."id" = 1 LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL User Load (1.2ms) SELECT "users".* FROM "users" WHERE "users"."id" = 1 LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.7ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'enable_page_views' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.5ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'terms_of_use_url' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.3ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'privacy_policy_url' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/terms_required.html.erb within layouts/application (10.5ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/google_docs" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL PluginSetting Load (0.6ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'google_docs' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/google_docs" 0.0018
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/google_docs" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/linked_in" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL PluginSetting Load (0.3ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'linked_in' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/linked_in" 0.0010
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/linked_in" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/twitter" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL PluginSetting Load (0.3ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'twitter' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/twitter" 0.0010
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/twitter" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/yo" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL PluginSetting Load (0.3ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'yo' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/yo" 0.0008
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/yo" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/diigo" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL PluginSetting Load (0.5ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'diigo' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/diigo" 0.0010
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/diigo" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL SQL (1.2ms) SELECT DISTINCT "enrollments"."type" FROM "enrollments" WHERE "enrollments"."root_account_id" = 1 AND "enrollments"."type" IN ('StudentEnrollment', 'StudentViewEnrollment', 'TeacherEnrollment', 'TaEnrollment', 'DesignerEnrollment') AND "enrollments"."user_id" = 1 AND "enrollments"."workflow_state" = 'active' [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.4ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'site_admin_account_id' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "_account_lookup2/2" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Account Load (0.7ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = 2 LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "_account_lookup2/2" 0.0019
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "_account_lookup2/2" 0.0003
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL SQL (1.6ms) SELECT "account_users"."id" AS t0_r0, "account_users"."account_id" AS t0_r1, "account_users"."user_id" AS t0_r2, "account_users"."created_at" AS t0_r3, "account_users"."updated_at" AS t0_r4, "account_users"."role_id" AS t0_r5, "accounts"."id" AS t1_r0, "accounts"."name" AS t1_r1, "accounts"."created_at" AS t1_r2, "accounts"."updated_at" AS t1_r3, "accounts"."workflow_state" AS t1_r4, "accounts"."deleted_at" AS t1_r5, "accounts"."parent_account_id" AS t1_r6, "accounts"."sis_source_id" AS t1_r7, "accounts"."sis_batch_id" AS t1_r8, "accounts"."current_sis_batch_id" AS t1_r9, "accounts"."root_account_id" AS t1_r10, "accounts"."last_successful_sis_batch_id" AS t1_r11, "accounts"."membership_types" AS t1_r12, "accounts"."require_authorization_code" AS t1_r13, "accounts"."default_time_zone" AS t1_r14, "accounts"."external_status" AS t1_r15, "accounts"."storage_quota" AS t1_r16, "accounts"."default_storage_quota" AS t1_r17, "accounts"."enable_user_notes" AS t1_r18, "accounts"."allowed_services" AS t1_r19, "accounts"."turnitin_pledge" AS t1_r20, "accounts"."turnitin_comments" AS t1_r21, "accounts"."turnitin_account_id" AS t1_r22, "accounts"."turnitin_salt" AS t1_r23, "accounts"."turnitin_crypted_secret" AS t1_r24, "accounts"."show_section_name_as_course_name" AS t1_r25, "accounts"."allow_sis_import" AS t1_r26, "accounts"."equella_endpoint" AS t1_r27, "accounts"."settings" AS t1_r28, "accounts"."uuid" AS t1_r29, "accounts"."default_locale" AS t1_r30, "accounts"."stuck_sis_fields" AS t1_r31, "accounts"."default_user_storage_quota" AS t1_r32, "accounts"."lti_guid" AS t1_r33, "accounts"."default_group_storage_quota" AS t1_r34, "accounts"."turnitin_host" AS t1_r35, "accounts"."integration_id" AS t1_r36, "accounts"."lti_context_id" AS t1_r37 FROM "account_users" INNER JOIN "accounts" ON "accounts"."id" = "account_users"."account_id" WHERE (user_id=1 AND (root_account_id IN (1,2) OR account_id IN (1,2))) [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag_account_ids/accounts/1-20150702005759" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag_account_ids/accounts/1-20150702005759" 0.0001
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag_account_ids/accounts/1-20150702005759" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/Account/10000000000002/use_new_styles" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.5ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 2 AND "feature_flags"."context_type" = 'Account' AND "feature_flags"."feature" = 'use_new_styles' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/Account/10000000000002/use_new_styles" 0.0126
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/Account/10000000000002/use_new_styles" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/Account/10000000000001/use_new_styles" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.4ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 1 AND "feature_flags"."context_type" = 'Account' AND "feature_flags"."feature" = 'use_new_styles' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/Account/10000000000001/use_new_styles" 0.0016
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/Account/10000000000001/use_new_styles" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag_account_ids/accounts/1-20150702005759" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag_account_ids/accounts/1-20150702005759" 0.0001
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag_account_ids/accounts/1-20150702005759" 0.0001
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/Account/10000000000002/k12" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.3ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 2 AND "feature_flags"."context_type" = 'Account' AND "feature_flags"."feature" = 'k12' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/Account/10000000000002/k12" 0.0014
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/Account/10000000000002/k12" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/Account/10000000000001/k12" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.3ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 1 AND "feature_flags"."context_type" = 'Account' AND "feature_flags"."feature" = 'k12' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/Account/10000000000001/k12" 0.0010
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/Account/10000000000001/k12" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag_account_ids/users/1-20150622194301" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag_account_ids/users/1-20150622194301" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag_account_ids/users/1-20150622194301" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/Account/10000000000002/high_contrast" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.4ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 2 AND "feature_flags"."context_type" = 'Account' AND "feature_flags"."feature" = 'high_contrast' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/Account/10000000000002/high_contrast" 0.0013
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/Account/10000000000002/high_contrast" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/User/10000000000001/high_contrast" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.3ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 1 AND "feature_flags"."context_type" = 'User' AND "feature_flags"."feature" = 'high_contrast' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/User/10000000000001/high_contrast" 0.0021
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/User/10000000000001/high_contrast" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered layouts/head.html.erb (3.9ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/flash_notices.html.erb (0.9ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.5ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'show_feedback_link' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/identity.html.erb (5.1ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/user_header_navigation.html.erb (7.2ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "users/1-20150622194301/current_enrollments3//" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Enrollment Load (3.2ms) SELECT "enrollments".* FROM "enrollments" INNER JOIN "courses" ON "courses"."id" = "enrollments"."course_id" WHERE "enrollments"."user_id" = 1 AND (enrollments.workflow_state='active' AND (courses.workflow_state='available' OR courses.workflow_state='claimed' AND enrollments.type IN ('TeacherEnrollment','TaEnrollment','DesignerEnrollment','StudentViewEnrollment')) OR enrollments.workflow_state='invited' AND (courses.workflow_state='available' OR courses.workflow_state<>'deleted' AND enrollments.type IN ('TeacherEnrollment','TaEnrollment','DesignerEnrollment','StudentViewEnrollment'))) [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "users/1-20150622194301/current_enrollments3//" 0.0347
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "users/1-20150622194301/current_enrollments3//" 0.0001
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "users/1-20150622194301/active_emails" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL CommunicationChannel Load (0.9ms) SELECT "communication_channels".* FROM "communication_channels" WHERE "communication_channels"."user_id" = 1 AND "communication_channels"."workflow_state" = 'active' AND "communication_channels"."path_type" = 'email' ORDER BY communication_channels.position ASC [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "users/1-20150622194301/active_emails" 0.0184
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "users/1-20150622194301/active_emails" 0.0001
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "bartdority@gmail.com/invited_enrollments2" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Enrollment Load (1.1ms) SELECT enrollments.* FROM "enrollments" INNER JOIN "users" ON "users"."id" = "enrollments"."user_id" INNER JOIN "communication_channels" ON "communication_channels"."user_id" = "users"."id" WHERE "enrollments"."workflow_state" = 'invited' AND (users.workflow_state='creation_pending' AND communication_channels.workflow_state='unconfirmed' AND path_type='email' AND LOWER(path)=LOWER('bartdority@gmail.com')) [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "bartdority@gmail.com/invited_enrollments2" 0.0025
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "bartdority@gmail.com/invited_enrollments2" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "users/1-20150622194301/current_group_memberships/" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL SQL (1.2ms) SELECT "group_memberships"."id" AS t0_r0, "group_memberships"."group_id" AS t0_r1, "group_memberships"."workflow_state" AS t0_r2, "group_memberships"."created_at" AS t0_r3, "group_memberships"."updated_at" AS t0_r4, "group_memberships"."user_id" AS t0_r5, "group_memberships"."uuid" AS t0_r6, "group_memberships"."sis_batch_id" AS t0_r7, "group_memberships"."moderator" AS t0_r8, "groups"."id" AS t1_r0, "groups"."name" AS t1_r1, "groups"."workflow_state" AS t1_r2, "groups"."created_at" AS t1_r3, "groups"."updated_at" AS t1_r4, "groups"."context_id" AS t1_r5, "groups"."context_type" AS t1_r6, "groups"."category" AS t1_r7, "groups"."max_membership" AS t1_r8, "groups"."hashtag" AS t1_r9, "groups"."show_public_context_messages" AS t1_r10, "groups"."is_public" AS t1_r11, "groups"."account_id" AS t1_r12, "groups"."default_wiki_editing_roles" AS t1_r13, "groups"."wiki_id" AS t1_r14, "groups"."deleted_at" AS t1_r15, "groups"."join_level" AS t1_r16, "groups"."default_view" AS t1_r17, "groups"."migration_id" AS t1_r18, "groups"."storage_quota" AS t1_r19, "groups"."uuid" AS t1_r20, "groups"."root_account_id" AS t1_r21, "groups"."sis_source_id" AS t1_r22, "groups"."sis_batch_id" AS t1_r23, "groups"."stuck_sis_fields" AS t1_r24, "groups"."group_category_id" AS t1_r25, "groups"."description" AS t1_r26, "groups"."avatar_attachment_id" AS t1_r27, "groups"."leader_id" AS t1_r28 FROM "group_memberships" LEFT OUTER JOIN "groups" ON "groups"."id" = "group_memberships"."group_id" WHERE "group_memberships"."user_id" = 1 AND (group_memberships.workflow_state = 'accepted' AND groups.workflow_state <> 'deleted') [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "users/1-20150622194301/current_group_memberships/" 0.0139
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "users/1-20150622194301/current_group_memberships/" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "all_accounts/users/1-20150622194301/" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Account Load (0.8ms) SELECT "accounts".* FROM "accounts" INNER JOIN "account_users" ON "accounts"."id" = "account_users"."account_id" WHERE "account_users"."user_id" = 1 AND (accounts.workflow_state<>'deleted') [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "all_accounts/users/1-20150622194301/" 0.0193
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "all_accounts/users/1-20150622194301/" 0.0004
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "users/1-20150622194301/courses_with_primary_enrollment2/favorite_courses//" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL SQL (0.4ms) SELECT "favorites"."context_id" FROM "favorites" WHERE "favorites"."user_id" = 1 AND "favorites"."context_type" = 'Course' [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Course Load (1.3ms) SELECT DISTINCT ON ("courses"."id") courses., enrollments.id AS primary_enrollment_id, enrollments.type AS primary_enrollment_type, enrollments.role_id AS primary_enrollment_role_id, CASE WHEN enrollments.type IN ('TeacherEnrollment') THEN 0 WHEN enrollments.type IN ('TaEnrollment') THEN 1 WHEN enrollments.type IN ('DesignerEnrollment') THEN 2 WHEN enrollments.type IN ('StudentEnrollment') THEN 3 WHEN enrollments.type IN ('StudentViewEnrollment') THEN 4 WHEN enrollments.type IN ('ObserverEnrollment') THEN 5 ELSE 6 END AS primary_enrollment_rank, enrollments.workflow_state AS primary_enrollment_state FROM "courses" INNER JOIN "enrollments" ON "enrollments"."course_id" = "courses"."id" WHERE "enrollments"."user_id" = 1 AND (enrollments.workflow_state='active' AND (courses.workflow_state='available' OR courses.workflow_state='claimed' AND enrollments.type IN ('TeacherEnrollment','TaEnrollment','DesignerEnrollment','StudentViewEnrollment')) OR enrollments.workflow_state='invited' AND (courses.workflow_state='available' OR courses.workflow_state<>'deleted' AND enrollments.type IN ('TeacherEnrollment','TaEnrollment','DesignerEnrollment','StudentViewEnrollment'))) AND ('f') ORDER BY courses.id, CASE WHEN enrollments.type IN ('TeacherEnrollment') THEN 0 WHEN enrollments.type IN ('TaEnrollment') THEN 1 WHEN enrollments.type IN ('DesignerEnrollment') THEN 2 WHEN enrollments.type IN ('StudentEnrollment') THEN 3 WHEN enrollments.type IN ('StudentViewEnrollment') THEN 4 WHEN enrollments.type IN ('ObserverEnrollment') THEN 5 ELSE 6 END, CASE WHEN enrollments.workflow_state IN ('active') THEN 0 WHEN enrollments.workflow_state IN ('invited', 'creation_pending') THEN 1 WHEN enrollments.workflow_state IN ('completed') THEN 2 WHEN enrollments.workflow_state IN ('rejected') THEN 3 WHEN enrollments.workflow_state IN ('deleted') THEN 4 ELSE 5 END [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Enrollment Load (0.4ms) SELECT "enrollments". FROM "enrollments" WHERE 1=0 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "users/1-20150622194301/courses_with_primary_enrollment2/favorite_courses//" 0.0293
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "users/1-20150622194301/courses_with_primary_enrollment2/favorite_courses//" 0.0002
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "users/1-20150622194301/courses_with_primary_enrollment2/current_and_invited_courses//" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Course Load (2.4ms) SELECT DISTINCT ON ("courses"."id") courses., enrollments.id AS primary_enrollment_id, enrollments.type AS primary_enrollment_type, enrollments.role_id AS primary_enrollment_role_id, CASE WHEN enrollments.type IN ('TeacherEnrollment') THEN 0 WHEN enrollments.type IN ('TaEnrollment') THEN 1 WHEN enrollments.type IN ('DesignerEnrollment') THEN 2 WHEN enrollments.type IN ('StudentEnrollment') THEN 3 WHEN enrollments.type IN ('StudentViewEnrollment') THEN 4 WHEN enrollments.type IN ('ObserverEnrollment') THEN 5 ELSE 6 END AS primary_enrollment_rank, enrollments.workflow_state AS primary_enrollment_state FROM "courses" INNER JOIN "enrollments" ON "enrollments"."course_id" = "courses"."id" WHERE "enrollments"."user_id" = 1 AND (enrollments.workflow_state='active' AND (courses.workflow_state='available' OR courses.workflow_state='claimed' AND enrollments.type IN ('TeacherEnrollment','TaEnrollment','DesignerEnrollment','StudentViewEnrollment')) OR enrollments.workflow_state='invited' AND (courses.workflow_state='available' OR courses.workflow_state<>'deleted' AND enrollments.type IN ('TeacherEnrollment','TaEnrollment','DesignerEnrollment','StudentViewEnrollment'))) ORDER BY courses.id, CASE WHEN enrollments.type IN ('TeacherEnrollment') THEN 0 WHEN enrollments.type IN ('TaEnrollment') THEN 1 WHEN enrollments.type IN ('DesignerEnrollment') THEN 2 WHEN enrollments.type IN ('StudentEnrollment') THEN 3 WHEN enrollments.type IN ('StudentViewEnrollment') THEN 4 WHEN enrollments.type IN ('ObserverEnrollment') THEN 5 ELSE 6 END, CASE WHEN enrollments.workflow_state IN ('active') THEN 0 WHEN enrollments.workflow_state IN ('invited', 'creation_pending') THEN 1 WHEN enrollments.workflow_state IN ('completed') THEN 2 WHEN enrollments.workflow_state IN ('rejected') THEN 3 WHEN enrollments.workflow_state IN ('deleted') THEN 4 ELSE 5 END [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL CACHE (0.0ms) 1::SELECT "enrollments". FROM "enrollments" WHERE 1=0
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "users/1-20150622194301/courses_with_primary_enrollment2/current_and_invited_courses//" 0.0061
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "users/1-20150622194301/courses_with_primary_enrollment2/current_and_invited_courses//" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "users/1-20150622194301/active_emails" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL CACHE (0.0ms) 1::SELECT "communication_channels".* FROM "communication_channels" WHERE "communication_channels"."user_id" = 1 AND "communication_channels"."workflow_state" = 'active' AND "communication_channels"."path_type" = 'email' ORDER BY communication_channels.position ASC
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "users/1-20150622194301/active_emails" 0.0019
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "users/1-20150622194301/active_emails" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "bartdority@gmail.com/invited_enrollments2" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL CACHE (0.0ms) 1::SELECT enrollments.* FROM "enrollments" INNER JOIN "users" ON "users"."id" = "enrollments"."user_id" INNER JOIN "communication_channels" ON "communication_channels"."user_id" = "users"."id" WHERE "enrollments"."workflow_state" = 'invited' AND (users.workflow_state='creation_pending' AND communication_channels.workflow_state='unconfirmed' AND path_type='email' AND LOWER(path)=LOWER('bartdority@gmail.com'))
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "bartdority@gmail.com/invited_enrollments2" 0.0010
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "bartdority@gmail.com/invited_enrollments2" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_menu_course.html.erb (0.0ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_menu_courses.html.erb (3.4ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_menu_section.html.erb (0.8ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_menu_account.html.erb (1.3ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_menu_section.html.erb (2.5ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_home_menu.html.erb (283.8ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag_account_ids/accounts/1-20150702005759" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag_account_ids/accounts/1-20150702005759" 0.0001
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag_account_ids/accounts/1-20150702005759" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/Account/10000000000002/lor_for_account" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.8ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 2 AND "feature_flags"."context_type" = 'Account' AND "feature_flags"."feature" = 'lor_for_account' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/Account/10000000000002/lor_for_account" 0.0022
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/Account/10000000000002/lor_for_account" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/Account/10000000000001/lor_for_account" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.6ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 1 AND "feature_flags"."context_type" = 'Account' AND "feature_flags"."feature" = 'lor_for_account' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/Account/10000000000001/lor_for_account" 0.0015
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/Account/10000000000001/lor_for_account" 0.0001
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag_account_ids/users/1-20150622194301" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag_account_ids/users/1-20150622194301" 0.0001
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag_account_ids/users/1-20150622194301" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/Account/10000000000002/lor_for_user" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.5ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 2 AND "feature_flags"."context_type" = 'Account' AND "feature_flags"."feature" = 'lor_for_user' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/Account/10000000000002/lor_for_user" 0.0021
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/Account/10000000000002/lor_for_user" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "feature_flag/User/10000000000001/lor_for_user" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL FeatureFlag Load (0.4ms) SELECT "feature_flags".* FROM "feature_flags" WHERE "feature_flags"."context_id" = 1 AND "feature_flags"."context_type" = 'User' AND "feature_flags"."feature" = 'lor_for_user' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "feature_flag/User/10000000000001/lor_for_user" 0.0014
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "feature_flag/User/10000000000001/lor_for_user" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered external_tools/_global_nav_menu_items.html.erb (12.8ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_primary_header_navigation.html.erb (308.0ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.6ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'show_opensource_linkback' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_footer_links.html.erb (0.4ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_canvas_footer.erb (7.3ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered shared/_global_dialogs.html.erb (1.3ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/kaltura" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL PluginSetting Load (0.5ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'kaltura' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/kaltura" 0.0015
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/kaltura" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/kaltura" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL CACHE (0.0ms) 1::SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'kaltura' LIMIT 1
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/kaltura" 0.0005
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/kaltura" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL Setting Load (0.3ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'google_analytics_key' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/crocodoc" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL PluginSetting Load (0.3ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'crocodoc' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/crocodoc" 0.0009
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/crocodoc" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: read "settings_for_plugin2/pandapub" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] SQL PluginSetting Load (0.4ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'pandapub' LIMIT 1 [shard 1 master]
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: generate "settings_for_plugin2/pandapub" 0.0010
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] CacheStore: write "settings_for_plugin2/pandapub" 0.0000
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Rendered layouts/_foot.html.erb (48.8ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Filter chain halted as :require_reacceptance_of_terms rendered or redirected
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] Completed 401 Unauthorized in 845.6ms (Views: 598.9ms | ActiveRecord: 64.2ms)
[- e3845d75-2301-4f5d-85b8-9c5eab8c6ff1] [STAT] 265196 270656 5460 0 1.0299999999999994 0.10000000000000009
[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16]
[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16]
[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16] Started GET "/javascripts/jst/helpDialog.js" for 127.0.0.1 at 2015-07-03 11:36:14 -0700
[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16] CacheStore: read "settings_for_plugin2/sessions" 0.0000
[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16] SQL PluginSetting Load (0.4ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'sessions' LIMIT 1 [shard 1 master]
[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16] CacheStore: generate "settings_for_plugin2/sessions" 0.0017
[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16] CacheStore: write "settings_for_plugin2/sessions" 0.0000
[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16] [STAT] 270760 270760 0 270656 0.009999999999999787 0
[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16]
ActionController::RoutingError (No route matches [GET] "/javascripts/jst/helpDialog.js"):
app/middleware/request_context_generator.rb:36:in call' app/middleware/prevent_non_multipart_parse.rb:32:incall'

[- 4bba0a55-ff7e-4b46-8828-e398a3a79f16] Rendered /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (1.2ms)
[- 0d9497a6-3568-4119-b177-a0998309ab6d]
[- 0d9497a6-3568-4119-b177-a0998309ab6d]
[- 0d9497a6-3568-4119-b177-a0998309ab6d] Started GET "/javascripts/jst/registration/incompleteRegistrationWarning.js" for 127.0.0.1 at 2015-07-03 11:36:14 -0700
[- 0d9497a6-3568-4119-b177-a0998309ab6d] CacheStore: read "settings_for_plugin2/sessions" 0.0000
[- 0d9497a6-3568-4119-b177-a0998309ab6d] SQL PluginSetting Load (0.4ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'sessions' LIMIT 1 [shard 1 master]
[- 0d9497a6-3568-4119-b177-a0998309ab6d] CacheStore: generate "settings_for_plugin2/sessions" 0.0014
[- 0d9497a6-3568-4119-b177-a0998309ab6d] CacheStore: write "settings_for_plugin2/sessions" 0.0000
[- 0d9497a6-3568-4119-b177-a0998309ab6d] [STAT] 271000 271004 4 270760 0 0
[- 0d9497a6-3568-4119-b177-a0998309ab6d]
ActionController::RoutingError (No route matches [GET] "/javascripts/jst/registration/incompleteRegistrationWarning.js"):
app/middleware/request_context_generator.rb:36:in call' app/middleware/prevent_non_multipart_parse.rb:32:incall'

[- 0d9497a6-3568-4119-b177-a0998309ab6d] Rendered /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (0.9ms)
[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0]
[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0]
[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0] Started GET "/javascripts/compiled/views/KeyboardNavDialog.js" for 127.0.0.1 at 2015-07-03 11:36:14 -0700
[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0] CacheStore: read "settings_for_plugin2/sessions" 0.0000
[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0] SQL PluginSetting Load (0.7ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'sessions' LIMIT 1 [shard 1 master]
[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0] CacheStore: generate "settings_for_plugin2/sessions" 0.0020
[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0] CacheStore: write "settings_for_plugin2/sessions" 0.0001
[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0] [STAT] 271312 271312 0 271004 0 0
[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0]
ActionController::RoutingError (No route matches [GET] "/javascripts/compiled/views/KeyboardNavDialog.js"):
app/middleware/request_context_generator.rb:36:in call' app/middleware/prevent_non_multipart_parse.rb:32:incall'

[- 4ad8dd59-c06e-4581-b303-5e74480ef4e0] Rendered /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (1.0ms)
[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8]
[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8]
[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8] Started GET "/javascripts/compiled/xhr/FakeXHR.js" for 127.0.0.1 at 2015-07-03 11:36:14 -0700
[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8] CacheStore: read "settings_for_plugin2/sessions" 0.0000
[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8] SQL PluginSetting Load (0.4ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'sessions' LIMIT 1 [shard 1 master]
[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8] CacheStore: generate "settings_for_plugin2/sessions" 0.0013
[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8] CacheStore: write "settings_for_plugin2/sessions" 0.0000
[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8] [STAT] 271456 271456 0 271312 0 0
[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8]
ActionController::RoutingError (No route matches [GET] "/javascripts/compiled/xhr/FakeXHR.js"):
app/middleware/request_context_generator.rb:36:in call' app/middleware/prevent_non_multipart_parse.rb:32:incall'

[- da5ce65f-e3ce-4d6a-9e6f-8962406c37b8] Rendered /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (1.6ms)
[- e95c1780-618d-4d11-8836-ce3c94e326c6]
[- e95c1780-618d-4d11-8836-ce3c94e326c6]
[- e95c1780-618d-4d11-8836-ce3c94e326c6] Started GET "/javascripts/compiled/widget/DatetimeField.js" for 127.0.0.1 at 2015-07-03 11:36:14 -0700
[- e95c1780-618d-4d11-8836-ce3c94e326c6] CacheStore: read "settings_for_plugin2/sessions" 0.0000
[- e95c1780-618d-4d11-8836-ce3c94e326c6] SQL PluginSetting Load (0.4ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'sessions' LIMIT 1 [shard 1 master]
[- e95c1780-618d-4d11-8836-ce3c94e326c6] CacheStore: generate "settings_for_plugin2/sessions" 0.0013
[- e95c1780-618d-4d11-8836-ce3c94e326c6] CacheStore: write "settings_for_plugin2/sessions" 0.0000
[- e95c1780-618d-4d11-8836-ce3c94e326c6] [STAT] 271468 271468 0 271456 0.009999999999999787 0
[- e95c1780-618d-4d11-8836-ce3c94e326c6]
ActionController::RoutingError (No route matches [GET] "/javascripts/compiled/widget/DatetimeField.js"):
app/middleware/request_context_generator.rb:36:in call' app/middleware/prevent_non_multipart_parse.rb:32:incall'

[- e95c1780-618d-4d11-8836-ce3c94e326c6] Rendered /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/templates/rescues/routing_error.erb within rescues/layout (1.6ms)
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] Started PUT "/api/v1/users/self" for 127.0.0.1 at 2015-07-03 11:36:17 -0700
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] CacheStore: read "settings_for_plugin2/sessions" 0.0000
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL PluginSetting Load (0.4ms) SELECT "plugin_settings".* FROM "plugin_settings" WHERE "plugin_settings"."name" = 'sessions' LIMIT 1 [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] CacheStore: generate "settings_for_plugin2/sessions" 0.0015
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] CacheStore: write "settings_for_plugin2/sessions" 0.0000
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] Processing by UsersController#update as JSON
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] Parameters: {"user"=>{"terms_of_use"=>"1"}, "id"=>"self"}
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL Pseudonym Load (1.2ms) SELECT "pseudonyms".* FROM "pseudonyms" WHERE "pseudonyms"."id" = 1 LIMIT 1 [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL User Load (0.8ms) SELECT "users".* FROM "users" WHERE "users"."id" = 1 LIMIT 1 [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d]
AuthenticationMethods::AccessTokenError (AuthenticationMethods::AccessTokenError):
/Users/bartdority/canvas/lib/authentication_methods.rb:155:in load_user' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:429:in_run__2379169858944111669__process_action__1707029613473090010__callbacks'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:405:in __run_callback' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:385:in_run_process_action_callbacks'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:81:in run_callbacks' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/abstract_controller/callbacks.rb:17:inprocess_action'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/rescue.rb:29:in process_action' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/instrumentation.rb:30:inblock in process_action'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/notifications.rb:123:in block in instrument' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/notifications/instrumenter.rb:20:ininstrument'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/notifications.rb:123:in instrument' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/instrumentation.rb:29:inprocess_action'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/params_wrapper.rb:207:in process_action' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activerecord/lib/active_record/railties/controller_runtime.rb:18:inprocess_action'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/abstract_controller/base.rb:121:in process' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/abstract_controller/rendering.rb:45:inprocess'
/Users/bartdority/.gem/ruby/2.1.6/gems/rack-mini-profiler-0.9.1/lib/mini_profiler/profiling_methods.rb:79:in block in profile_method' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal.rb:203:indispatch'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/rack_delegation.rb:14:in dispatch' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal.rb:246:inblock in action'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/routing/route_set.rb:73:in call' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/routing/route_set.rb:73:indispatch'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/routing/route_set.rb:36:in call' /Users/bartdority/.gem/ruby/2.1.6/gems/journey-1.0.4/lib/journey/router.rb:68:inblock in call'
/Users/bartdority/.gem/ruby/2.1.6/gems/journey-1.0.4/lib/journey/router.rb:56:in each' /Users/bartdority/.gem/ruby/2.1.6/gems/journey-1.0.4/lib/journey/router.rb:56:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/routing/route_set.rb:608:in call' /Users/bartdority/canvas/gems/plugins/respondus_soap_endpoint/lib/respondus_soap_endpoint/middleware.rb:61:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/best_standards_support.rb:17:in call' /Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/etag.rb:23:incall'
/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/conditionalget.rb:35:in call' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/head.rb:14:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/params_parser.rb:21:in call' /Users/bartdority/canvas/lib/canvas/request_throttle.rb:60:inblock in call'
/Users/bartdority/canvas/lib/canvas/request_throttle.rb:258:in reserve_capacity' /Users/bartdority/canvas/lib/canvas/request_throttle.rb:55:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/flash.rb:242:in call' /Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:210:incontext'
/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:205:in call' /Users/bartdority/.gem/ruby/2.1.6/gems/encrypted_cookie_store-instructure-1.1.10/lib/encrypted_cookie_store.rb:47:incall'
/Users/bartdority/canvas/app/middleware/sessions_timeout.rb:22:in call' /Users/bartdority/canvas/app/middleware/load_account.rb:12:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/cookies.rb:341:in call' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activerecord/lib/active_record/query_cache.rb:64:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activerecord/lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in call' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/callbacks.rb:28:inblock in call'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:405:in _run__1089271514175925497__call__3857359791556427719__callbacks' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:405:in__run_callback'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:385:in _run_call_callbacks' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:81:inrun_callbacks'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/callbacks.rb:27:in call' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/reloader.rb:65:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/remote_ip.rb:31:in call' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/debug_exceptions.rb:16:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/show_exceptions.rb:56:in call' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/rack/logger.rb:32:incall_app'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/rack/logger.rb:18:in call' /Users/bartdority/.gem/ruby/2.1.6/gems/request_store-1.1.0/lib/request_store/middleware.rb:8:incall'
/Users/bartdority/canvas/app/middleware/request_context_generator.rb:36:in call' /Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/methodoverride.rb:21:incall'
/Users/bartdority/canvas/app/middleware/prevent_non_multipart_parse.rb:32:in call' /Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/runtime.rb:17:incall'
/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/lock.rb:15:in call' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/static.rb:83:incall'
/Users/bartdority/.gem/ruby/2.1.6/gems/rack-mini-profiler-0.9.1/lib/mini_profiler/profiler.rb:193:in call' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/engine.rb:484:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/application.rb:231:in call' /Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/content_length.rb:14:incall'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/rack/log_tailer.rb:17:in call' /Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:86:inblock in pre_process'
/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:84:in catch' /Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:84:inpre_process'
/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:53:in process' /Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:39:inreceive_data'
/Users/bartdority/.gem/ruby/2.1.6/gems/eventmachine-1.0.4/lib/eventmachine.rb:187:in run_machine' /Users/bartdority/.gem/ruby/2.1.6/gems/eventmachine-1.0.4/lib/eventmachine.rb:187:inrun'
/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/backends/base.rb:73:in start' /Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/server.rb:162:instart'
/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/handler/thin.rb:13:in run' /Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/server.rb:268:instart'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/commands/server.rb:70:in start' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/commands.rb:55:inblock in <top (required)>'
/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/commands.rb:50:in tap' /Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/commands.rb:50:in<top (required)>'
script/rails:11:in require' script/rails:11:in

'

[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL Setting Load (0.4ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'error_report_exception_handling' LIMIT 1 [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL Account Load (0.5ms) SELECT "accounts".* FROM "accounts" WHERE "accounts"."id" = 1 LIMIT 1 [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL (0.2ms) BEGIN [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL CACHE (0.0ms) 1::SELECT "users".* FROM "users" WHERE "users"."id" = 1 LIMIT 1
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] CacheStore: read "user_email/users/1-20150622194301" 0.0000
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL CommunicationChannel Load (0.5ms) SELECT "communication_channels".* FROM "communication_channels" WHERE "communication_channels"."user_id" = 1 AND "communication_channels"."path_type" = 'email' AND (communication_channels.workflow_state<>'retired') ORDER BY communication_channels.position ASC LIMIT 1 [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] CacheStore: generate "user_email/users/1-20150622194301" 0.0042
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] CacheStore: write "user_email/users/1-20150622194301" 0.0000
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL SQL (2.2ms) INSERT INTO "error_reports" ("account_id", "backtrace", "category", "created_at", "data", "email", "message", "request_context_id", "request_method", "updated_at", "user_agent", "user_id") VALUES ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12) RETURNING "id" [["account_id", 1], ["backtrace", "/Users/bartdority/canvas/lib/authentication_methods.rb:155:in load_user'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:429:inrun__2379169858944111669__process_action__1707029613473090010__callbacks'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:405:in __run_callback'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:385:inrun_process_action_callbacks'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:81:in run_callbacks'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/abstract_controller/callbacks.rb:17:inprocess_action'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/rescue.rb:29:in process_action'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/instrumentation.rb:30:inblock in process_action'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/notifications.rb:123:in block in instrument'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/notifications/instrumenter.rb:20:ininstrument'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/notifications.rb:123:in instrument'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/instrumentation.rb:29:inprocess_action'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/params_wrapper.rb:207:in process_action'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activerecord/lib/active_record/railties/controller_runtime.rb:18:inprocess_action'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/abstract_controller/base.rb:121:in process'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/abstract_controller/rendering.rb:45:inprocess'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-mini-profiler-0.9.1/lib/mini_profiler/profiling_methods.rb:79:in block in profile_method'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal.rb:203:indispatch'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal/rack_delegation.rb:14:in dispatch'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_controller/metal.rb:246:inblock in action'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/routing/route_set.rb:73:in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/routing/route_set.rb:73:indispatch'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/routing/route_set.rb:36:in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/journey-1.0.4/lib/journey/router.rb:68:inblock in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/journey-1.0.4/lib/journey/router.rb:56:in each'\n/Users/bartdority/.gem/ruby/2.1.6/gems/journey-1.0.4/lib/journey/router.rb:56:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/routing/route_set.rb:608:in call'\n/Users/bartdority/canvas/gems/plugins/respondus_soap_endpoint/lib/respondus_soap_endpoint/middleware.rb:61:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/best_standards_support.rb:17:in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/etag.rb:23:incall'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/conditionalget.rb:35:in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/head.rb:14:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/params_parser.rb:21:in call'\n/Users/bartdority/canvas/lib/canvas/request_throttle.rb:60:inblock in call'\n/Users/bartdority/canvas/lib/canvas/request_throttle.rb:258:in reserve_capacity'\n/Users/bartdority/canvas/lib/canvas/request_throttle.rb:55:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/flash.rb:242:in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:210:incontext'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:205:in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/encrypted_cookie_store-instructure-1.1.10/lib/encrypted_cookie_store.rb:47:incall'\n/Users/bartdority/canvas/app/middleware/sessions_timeout.rb:22:in call'\n/Users/bartdority/canvas/app/middleware/load_account.rb:12:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/cookies.rb:341:in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activerecord/lib/active_record/query_cache.rb:64:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activerecord/lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/callbacks.rb:28:inblock in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:405:in _run__1089271514175925497__call__3857359791556427719__callbacks'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:405:in__run_callback'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:385:in _run_call_callbacks'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/activesupport/lib/active_support/callbacks.rb:81:inrun_callbacks'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/callbacks.rb:27:in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/reloader.rb:65:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/remote_ip.rb:31:in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/debug_exceptions.rb:16:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/show_exceptions.rb:56:in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/rack/logger.rb:32:incall_app'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/rack/logger.rb:18:in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/request_store-1.1.0/lib/request_store/middleware.rb:8:incall'\n/Users/bartdority/canvas/app/middleware/request_context_generator.rb:36:in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/methodoverride.rb:21:incall'\n/Users/bartdority/canvas/app/middleware/prevent_non_multipart_parse.rb:32:in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/runtime.rb:17:incall'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/lock.rb:15:in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/actionpack/lib/action_dispatch/middleware/static.rb:83:incall'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-mini-profiler-0.9.1/lib/mini_profiler/profiler.rb:193:in call'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/engine.rb:484:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/application.rb:231:in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/content_length.rb:14:incall'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/rack/log_tailer.rb:17:in call'\n/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:86:inblock in pre_process'\n/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:84:in catch'\n/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:84:inpre_process'\n/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:53:in process'\n/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/connection.rb:39:inreceive_data'\n/Users/bartdority/.gem/ruby/2.1.6/gems/eventmachine-1.0.4/lib/eventmachine.rb:187:in run_machine'\n/Users/bartdority/.gem/ruby/2.1.6/gems/eventmachine-1.0.4/lib/eventmachine.rb:187:inrun'\n/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/backends/base.rb:73:in start'\n/Users/bartdority/.gem/ruby/2.1.6/gems/thin-1.6.3/lib/thin/server.rb:162:instart'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/handler/thin.rb:13:in run'\n/Users/bartdority/.gem/ruby/2.1.6/gems/rack-1.4.5/lib/rack/server.rb:268:instart'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/commands/server.rb:70:in start'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/commands.rb:55:inblock in <top (required)>'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/commands.rb:50:in tap'\n/Users/bartdority/.gem/ruby/2.1.6/bundler/gems/rails-b344986bc3d9/railties/lib/rails/commands.rb:50:in<top (required)>'\nscript/rails:11:in require'\nscript/rails:11:in

'"], ["category", "AuthenticationMethods::AccessTokenError"], ["created_at", Fri, 03 Jul 2015 12:36:17 MDT -06:00], ["data", "--- \nresponse_code: 401\nformat: !ruby/object:Mime::Type \n string: application/json\n symbol: :json\n synonyms: \n - text/x-json\n - application/jsonrequest\ntype: \nHTTP_ACCEPT: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,/;q=0.8\nHTTP_ACCEPT_ENCODING: gzip, deflate\nHTTP_HOST: localhost:3000\nHTTP_REFERER: http://localhost:3000/\nHTTP_USER_AGENT: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_10_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/43.0.2357.130 Safari/537.36\nPATH_INFO: /api/v1/users/self\nQUERY_STRING: "?"\nREQUEST_METHOD: PUT\nREQUEST_PATH: /api/v1/users/self\nREQUEST_URI: http://localhost:3000/api/v1/users/self\nSERVER_NAME: localhost\nSERVER_PORT: "3000"\nSERVER_PROTOCOL: HTTP/1.1\nREMOTE_ADDR: 127.0.0.1\npath_parameters: "{:action=>\"update\", :format=>\"json\", :controller=>\"users\", :id=>\"self\"}"\nquery_parameters: "{}"\nrequest_parameters: "{\"user\"=>{\"terms_of_use\"=>\"1\"}, \"_method\"=>\"put\"}"\nexception_message: AuthenticationMethods::AccessTokenError\nhostname: Barts-Mac-mini.local\npid: 1042\n"], ["email", "bartdority@gmail.com"], ["message", "AuthenticationMethods::AccessTokenError"], ["request_context_id", "46ad98f4-0ee4-4a4e-be4d-ff094401e54d"], ["request_method", :put], ["updated_at", Fri, 03 Jul 2015 12:36:17 MDT -06:00], ["user_agent", "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_10_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/43.0.2357.130 Safari/537.36"], ["user_id", 1]] [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL (0.7ms) COMMIT [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] Created ErrorReport ID 10000000000021
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL Setting Load (0.5ms) SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'collect_error_statistics' LIMIT 1 [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] SQL ErrorReport Load (1.1ms) SELECT "error_reports".* FROM "error_reports" WHERE "error_reports"."id" = $1 LIMIT 1 [["id", 21]] [shard 1 master]
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] Completed 401 Unauthorized in 80.7ms (Views: 0.8ms | ActiveRecord: 11.0ms)
[- 46ad98f4-0ee4-4a4e-be4d-ff094401e54d] [STAT] 271472 271636 164 271468 0.09000000000000163 0.020000000000000018

[b0rgbart3]

Hi Clay - I still haven't made any progress on this issue.
Any ideas for me to try?
Thanks very much for your help.

[claydiffrient]

@b0rgbart3 Sorry for the delay on getting back to you on this. I've had to step away from this issue for a bit to try and get a better picture of what's happening. I'm still going to try and figure out the issue, but it may take a little time.

[claydiffrient]

For now though, a simple Setting.set('terms_required', 'false') in the rails console should get you by.