gistfile1.txt

[- -] Connecting to database specified by database.yml
[- -]    (147.1ms)  SELECT * FROM unnest(current_schemas(false))
[- -]   Switchman::Shard Load (1.8ms)  SELECT "switchman_shards".* FROM "switchman_shards" WHERE "switchman_shards"."default" = 't' LIMIT 1
[- -]    (0.3ms)  SELECT * FROM unnest(current_schemas(false))
[- -]   Switchman::Shard Load (1.2ms)  SELECT "switchman_shards".* FROM "switchman_shards" WHERE "switchman_shards"."database_server_id" = 'test' AND "switchman_shards"."name" IS NULL LIMIT 1
[- -]    (38.8ms)  SELECT * FROM unnest(current_schemas(false))  [shard  master]
[- -]   Switchman::Shard Load (0.4ms)  SELECT "switchman_shards".* FROM "switchman_shards" WHERE "switchman_shards"."database_server_id" = 'development' AND "switchman_shards"."name" IS NULL LIMIT 1
[- -]    (0.3ms)  SELECT * FROM unnest(current_schemas(false))  [shard  master]
[- -]   Setting Load (41.1ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'encryption_key_hash' LIMIT 1  [shard 1 master]
[- -]   SQL Setting Load (0.5ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'session_secret_key' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670] Started GET "/login" for 142.58.3.119 at 2014-10-08 11:45:58 -0700
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.4ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'account_special_account_cache_time' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.2ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'shard_cache_time' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.2ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'default_account_id' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.3ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.up_front_cost' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670] request throttling increment: [0,50.0,1412793958.66009,["client_identifier","session:f3a76015aad7a355537d6197b1590a2d"],["count",0.0],["last_touched",null]]
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.4ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.outflow' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.2ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.maximum' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.3ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.whitelist' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.1ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.blacklist' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.2ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'request_throttle.hwm' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670] Processing by PseudonymSessionsController#new as HTML
[- 445146b0-3149-0132-8196-005056272670]   SQL Setting Load (0.4ms)  SELECT "settings".* FROM "settings" WHERE "settings"."name" = 'enable_page_views' LIMIT 1  [shard 1 master]
[- 445146b0-3149-0132-8196-005056272670]
NoMethodError (undefined method `unserialized_value' for #<ActiveSupport::HashWithIndifferentAccess:0x00000012b66f90>):
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activerecord-3.2.19/lib/active_record/attribute_methods/read.rb:72:in `__temp__'
  /var/rails/canvas/releases/20141008xxxxxx/app/models/plugin_setting.rb:113:in `settings_for_plugin'
  /var/rails/canvas/releases/20141008xxxxxx/lib/canvas/plugin.rb:65:in `saved_settings'
  /var/rails/canvas/releases/20141008xxxxxx/lib/canvas/plugin.rb:69:in `settings'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/core_ext/object/try.rb:36:in `try'
  /var/rails/canvas/releases/20141008xxxxxx/config/initializers/google_docs.rb:2:in `block in <top (required)>'
  /var/rails/canvas/releases/20141008xxxxxx/gems/google_docs/lib/google_docs/connection.rb:345:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/gems/google_docs/lib/google_docs/connection.rb:345:in `config'
  /var/rails/canvas/releases/20141008xxxxxx/app/models/account.rb:1199:in `allowable_services'
  /var/rails/canvas/releases/20141008xxxxxx/app/models/account.rb:1261:in `default_allowable_services'
  /var/rails/canvas/releases/20141008xxxxxx/app/models/account.rb:1297:in `allowed_services_hash'
  /var/rails/canvas/releases/20141008xxxxxx/app/models/account.rb:1340:in `service_enabled?'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/plugins/aaa_sfu_misc/lib/test_cluster.rb:64:in `block_student_access'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:595:in `block in _run__990901862412207235__process_action__2182174635384751353__callbacks'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_10572'
  /var/rails/canvas/releases/20141008xxxxxx/app/controllers/application_controller.rb:195:in `set_locale'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_10572'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:436:in `_run__990901862412207235__process_action__2182174635384751353__callbacks'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:405:in `__run_callback'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:81:in `run_callbacks'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/abstract_controller/callbacks.rb:17:in `process_action'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_controller/metal/rescue.rb:29:in `process_action'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/notifications.rb:123:in `block in instrument'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/notifications.rb:123:in `instrument'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activerecord-3.2.19/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/abstract_controller/base.rb:121:in `process'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/abstract_controller/rendering.rb:45:in `process'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_controller/metal.rb:203:in `dispatch'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_controller/metal.rb:246:in `block in action'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/routing/route_set.rb:73:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/routing/route_set.rb:36:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/routing/route_set.rb:608:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/gems/plugins/respondus_soap_endpoint/lib/respondus_soap_endpoint/middleware.rb:61:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/etag.rb:23:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/conditionalget.rb:25:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/head.rb:14:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/lib/canvas/request_throttle.rb:58:in `block in call'
  /var/rails/canvas/releases/20141008xxxxxx/lib/canvas/request_throttle.rb:236:in `reserve_capacity'
  /var/rails/canvas/releases/20141008xxxxxx/lib/canvas/request_throttle.rb:54:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/app/middleware/stats_timing.rb:8:in `block in call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/core_ext/benchmark.rb:5:in `block in ms'
  /usr/local/lib/ruby/1.9.1/benchmark.rb:295:in `realtime'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/core_ext/benchmark.rb:5:in `ms'
  /var/rails/canvas/releases/20141008xxxxxx/app/middleware/stats_timing.rb:8:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/flash.rb:242:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:210:in `context'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:205:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/encrypted_cookie_store-instructure-1.1.6/lib/encrypted_cookie_store.rb:41:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/app/middleware/sessions_timeout.rb:22:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/app/middleware/load_account.rb:12:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/cookies.rb:341:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activerecord-3.2.19/lib/active_record/query_cache.rb:64:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activerecord-3.2.19/lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:405:in `_run__1865565058243525225__call__3469218399453975367__callbacks'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:405:in `__run_callback'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/activesupport-3.2.19/lib/active_support/callbacks.rb:81:in `run_callbacks'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/actionpack-3.2.19/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/railties-3.2.19/lib/rails/rack/logger.rb:32:in `call_app'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/railties-3.2.19/lib/rails/rack/logger.rb:18:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/app/middleware/request_context_generator.rb:34:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/methodoverride.rb:21:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/app/middleware/prevent_non_multipart_parse.rb:32:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/runtime.rb:17:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-1.4.5/lib/rack/lock.rb:15:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:245:in `fetch'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:185:in `lookup'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:66:in `call!'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/railties-3.2.19/lib/rails/engine.rb:484:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/railties-3.2.19/lib/rails/application.rb:231:in `call'
  /var/rails/canvas/releases/20141008xxxxxx/vendor/bundle/ruby/1.9.1/gems/railties-3.2.19/lib/rails/railtie/configurable.rb:30:in `method_missing'
  /usr/local/lib/ruby/gems/1.9.1/gems/passenger-4.0.48/lib/phusion_passenger/rack/thread_handler_extension.rb:74:in `process_request'
  /usr/local/lib/ruby/gems/1.9.1/gems/passenger-4.0.48/lib/phusion_passenger/request_handler/thread_handler.rb:141:in `accept_and_process_next_request'
  /usr/local/lib/ruby/gems/1.9.1/gems/passenger-4.0.48/lib/phusion_passenger/request_handler/thread_handler.rb:109:in `main_loop'
  /usr/local/lib/ruby/gems/1.9.1/gems/passenger-4.0.48/lib/phusion_passenger/request_handler.rb:448:in `block (3 levels) in start_threads'