search

instructure / canvas-lms

The open LMS by Instructure, Inc.
https://github.com/instructure/canvas-lms/wiki
GNU Affero General Public License v3.0
5.7k stars 2.51k forks source link

Section Enrollment is not created #1276

Open edward-blanco opened 6 years ago

edward-blanco commented 6 years ago

Read this first

We use GitHub issues for bug reports only. Please post feature requests on our community site. For support, see getting help. Thanks!

Summary:

When I tried to create a new section enrollment an internal_server_error is returned

Steps to reproduce:

  1. Conclude the current section enrollment
  2. Create a new course section
  3. Create a new enrollment associated with the new course section

Expected behavior:

The new section enrollment should be created

Actual behavior:


DETAIL:  Key (enrollment_id)=(25172) already exists.
: INSERT INTO "scores" ("enrollment_id", "current_score", "final_score", "created_at", "updated_at", "course_score", "unposted_current_score", "unposted_final_score", "current_points", "unposted_current_points", "final_points", "unposted_final_points") VALUES (25172, 76.67, 7.67, '2018-04-12 22:54:06.943588', '2018-04-12 22:54:06.943588', 't', 76.67, 7.67, 76.67, 76.67, 7.67, 7.67) RETURNING "id" caused by ERROR:  duplicate key value violates unique constraint "index_course_scores"
DETAIL:  Key (enrollment_id)=(25172) already exists.```

### Additional notes:
Checking the scores table, doesn't exist info associated with enrollment_id = 25198.
This behavior occurs with some users, for other users is working fine
omarkhan commented 6 years ago

Thanks for the report @edward-blanco. I was not able to reproduce this. Could you provide some more detailed steps to reproduce, maybe with some screenshots?

Timvandervoord commented 5 years ago

We seem to have the same issue, when you enroll a student to a different section an error occurs, the logfile is below.

Steps to reproduce:

  1. Add a student to a (different) section
ActiveRecord::RecordNotUnique (PG::UniqueViolation: ERROR:  duplicate key value violates unique constraint "index_course_scores"
DETAIL:  Key (enrollment_id)=(28763) already exists.
: INSERT INTO "scores" ("enrollment_id", "current_score", "final_score", "created_at", "updated_at", "course_score") VALUES (28763, 48.48, 11.88, '2019-04-23 13:54:13.618609', '2019-04-23 13:54:13.618609', 't') RETURNING "id"):
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/postgresql_adapter.rb:616:in `async_exec'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/postgresql_adapter.rb:616:in `block (2 levels) in exec_no_cache'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/dependencies/interlock.rb:46:in `block in permit_concurrent_loads'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/concurrency/share_lock.rb:185:in `yield_shares'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/dependencies/interlock.rb:45:in `permit_concurrent_loads'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/postgresql_adapter.rb:615:in `block in exec_no_cache'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract_adapter.rb:613:in `block (2 levels) in log'
  /usr/local/rvm/rubies/ruby-2.4.0/lib/ruby/2.4.0/monitor.rb:214:in `mon_synchronize'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract_adapter.rb:612:in `block in log'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
  /usr/local/rvm/rubies/ruby-2.4.0/lib/ruby/2.4.0/delegate.rb:83:in `method_missing'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/sharded_instrumenter.rb:20:in `instrument'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract_adapter.rb:604:in `log'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/abstract_adapter.rb:37:in `log'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/postgresql_adapter.rb:614:in `exec_no_cache'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/postgresql_adapter.rb:601:in `execute_and_clear'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/postgresql/database_statements.rb:79:in `exec_query'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/database_statements.rb:102:in `exec_insert'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/postgresql/database_statements.rb:113:in `exec_insert'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/database_statements.rb:133:in `insert'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/query_cache.rb:17:in `insert'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/relation.rb:61:in `insert'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/persistence.rb:582:in `_create_record'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/counter_cache.rb:178:in `_create_record'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/locking/optimistic.rb:69:in `_create_record'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/attribute_methods/dirty.rb:297:in `_create_record'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/callbacks.rb:340:in `block in _create_record'
  /var/canvas/gems/activesupport-suspend_callbacks/lib/active_support/callbacks/suspension.rb:182:in `run_callbacks'
  /var/canvas/config/initializers/active_record.rb:1439:in `block in run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/relation.rb:336:in `scoping'
  /var/canvas/config/initializers/active_record.rb:1439:in `run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:827:in `_run_create_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/callbacks.rb:340:in `_create_record'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/timestamp.rb:95:in `_create_record'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/persistence.rb:553:in `create_or_update'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/callbacks.rb:336:in `block in create_or_update'
  /var/canvas/gems/activesupport-suspend_callbacks/lib/active_support/callbacks/suspension.rb:182:in `run_callbacks'
  /var/canvas/config/initializers/active_record.rb:1439:in `block in run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/relation.rb:336:in `scoping'
  /var/canvas/config/initializers/active_record.rb:1439:in `run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:827:in `_run_save_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/callbacks.rb:336:in `create_or_update'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/persistence.rb:162:in `save!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/validations.rb:50:in `save!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/attribute_methods/dirty.rb:43:in `save!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:313:in `block in save!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:384:in `block in with_transaction_returning_status'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/database_statements.rb:233:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:210:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:36:in `block in transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/relation.rb:74:in `activate'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:31:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:381:in `with_transaction_returning_status'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:313:in `save!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/suppressor.rb:46:in `save!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:102:in `block in save!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/relation.rb:336:in `scoping'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:102:in `save!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/persistence.rb:294:in `block in update!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:384:in `block in with_transaction_returning_status'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/database_statements.rb:233:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:210:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:44:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:381:in `with_transaction_returning_status'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/persistence.rb:292:in `update!'
  /var/canvas/app/models/enrollment.rb:1454:in `block in copy_scores_from_existing_enrollment'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/relation/delegation.rb:39:in `each'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/relation/delegation.rb:39:in `each'
  /var/canvas/app/models/enrollment.rb:1454:in `copy_scores_from_existing_enrollment'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:413:in `block in make_lambda'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:235:in `block in halting_and_conditional'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:511:in `block in invoke_after'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:511:in `each'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:511:in `invoke_after'
  /var/canvas/gems/activesupport-suspend_callbacks/lib/active_support/callbacks/suspension.rb:183:in `run_callbacks'
  /var/canvas/config/initializers/active_record.rb:1439:in `block in run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/relation.rb:336:in `scoping'
  /var/canvas/config/initializers/active_record.rb:1439:in `run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:827:in `_run_save_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/callbacks.rb:336:in `create_or_update'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/persistence.rb:129:in `save'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/validations.rb:44:in `save'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/attribute_methods/dirty.rb:35:in `save'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:308:in `block (2 levels) in save'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:384:in `block in with_transaction_returning_status'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/database_statements.rb:233:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:210:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:36:in `block in transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/relation.rb:74:in `activate'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:31:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:381:in `with_transaction_returning_status'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:308:in `block in save'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:323:in `rollback_active_record_state!'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:307:in `save'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/suppressor.rb:42:in `save'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:97:in `block in save'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/relation.rb:336:in `scoping'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:97:in `save'
  /var/canvas/gems/broadcast_policy/lib/broadcast_policy/instance_methods.rb:99:in `save_without_broadcasting'
  /var/canvas/app/models/course.rb:2051:in `block (2 levels) in enroll_user'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/database_statements.rb:233:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:210:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:44:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:120:in `block in transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/app/models/switchman/shard_internal.rb:584:in `block in activate'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/app/models/switchman/shard_internal.rb:88:in `activate'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/app/models/switchman/shard_internal.rb:583:in `activate'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:119:in `transaction'
  /var/canvas/app/models/course.rb:2045:in `block in enroll_user'
  /var/canvas/config/initializers/active_record.rb:615:in `block (2 levels) in unique_constraint_retry'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/query_cache.rb:74:in `uncached'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/query_cache.rb:19:in `uncached'
  /var/canvas/config/initializers/active_record.rb:615:in `block in unique_constraint_retry'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/database_statements.rb:235:in `block in transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/transaction.rb:194:in `block in within_new_transaction'
  /usr/local/rvm/rubies/ruby-2.4.0/lib/ruby/2.4.0/monitor.rb:214:in `mon_synchronize'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/transaction.rb:191:in `within_new_transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/connection_adapters/abstract/database_statements.rb:235:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/transactions.rb:210:in `transaction'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/switchman-1.12.16/lib/switchman/active_record/base.rb:44:in `transaction'
  /var/canvas/config/initializers/active_record.rb:615:in `unique_constraint_retry'
  /var/canvas/app/models/course.rb:2003:in `enroll_user'
  /var/canvas/app/controllers/enrollments_api_controller.rb:634:in `block in create'
  /var/canvas/lib/due_date_cacher.rb:56:in `with_executing_user'
  /var/canvas/app/controllers/enrollments_api_controller.rb:633:in `create'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/abstract_controller/base.rb:186:in `process_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_controller/metal/rendering.rb:30:in `process_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:108:in `block in run_callbacks'
  /var/canvas/app/controllers/application_controller.rb:482:in `report_to_datadog'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:117:in `block in run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/canvas_statsd-2.0.4/lib/canvas_statsd/statsd.rb:81:in `batch'
  /var/canvas/app/controllers/application_controller.rb:466:in `batch_statsd'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:117:in `block in run_callbacks'
  /var/canvas/lib/temp_cache.rb:28:in `enable'
  /var/canvas/app/controllers/application_controller.rb:462:in `enable_request_cache'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:117:in `block in run_callbacks'
  /var/canvas/app/controllers/application_controller.rb:456:in `set_locale'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:117:in `block in run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:135:in `run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/abstract_controller/callbacks.rb:19:in `process_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_controller/metal/rescue.rb:20:in `process_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/notifications.rb:166:in `block in instrument'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/notifications.rb:166:in `instrument'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.2/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/abstract_controller/base.rb:124:in `process'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionview-5.1.6.2/lib/action_view/rendering.rb:30:in `process'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_controller/metal.rb:189:in `dispatch'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_controller/metal.rb:253:in `dispatch'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/routing/route_set.rb:31:in `serve'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/journey/router.rb:50:in `block in serve'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/journey/router.rb:33:in `each'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/journey/router.rb:33:in `serve'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/routing/route_set.rb:844:in `call'
  /var/canvas/gems/plugins/respondus_soap_endpoint/lib/respondus_soap_endpoint/middleware.rb:78:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/etag.rb:25:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/conditional_get.rb:38:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/head.rb:12:in `call'
  /var/canvas/app/middleware/request_throttle.rb:60:in `block in call'
  /var/canvas/app/middleware/request_throttle.rb:274:in `reserve_capacity'
  /var/canvas/app/middleware/request_throttle.rb:55:in `call'
  /var/canvas/app/middleware/request_context_session.rb:25:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/session/abstract/id.rb:232:in `context'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/session/abstract/id.rb:226:in `call'
  /var/canvas/app/middleware/load_account.rb:29:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/middleware/cookies.rb:613:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/callbacks.rb:97:in `run_callbacks'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.2/lib/rails/rack/logger.rb:36:in `call_app'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.2/lib/rails/rack/logger.rb:26:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/request_store-1.4.1/lib/request_store/middleware.rb:19:in `call'
  /var/canvas/app/middleware/request_context_generator.rb:49:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/method_override.rb:22:in `call'
  /var/canvas/app/middleware/prevent_non_multipart_parse.rb:33:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/runtime.rb:22:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.2/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.2/lib/action_dispatch/middleware/executor.rb:12:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/sendfile.rb:111:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.2/lib/rails/engine.rb:522:in `call'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.2/lib/rails/railtie.rb:185:in `public_send'
  /var/canvas/vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.2/lib/rails/railtie.rb:185:in `method_missing'
  /usr/local/rvm/gems/ruby-2.4.0/gems/passenger-6.0.2/src/ruby_supportlib/phusion_passenger/rack/thread_handler_extension.rb:97:in `process_request'
  /usr/local/rvm/gems/ruby-2.4.0/gems/passenger-6.0.2/src/ruby_supportlib/phusion_passenger/request_handler/thread_handler.rb:157:in `accept_and_process_next_request'
  /usr/local/rvm/gems/ruby-2.4.0/gems/passenger-6.0.2/src/ruby_supportlib/phusion_passenger/request_handler/thread_handler.rb:110:in `main_loop'
  /usr/local/rvm/gems/ruby-2.4.0/gems/passenger-6.0.2/src/ruby_supportlib/phusion_passenger/request_handler.rb:415:in `block (3 levels) in start_threads'
  /usr/local/rvm/gems/ruby-2.4.0/gems/passenger-6.0.2/src/ruby_supportlib/phusion_passenger/utils.rb:113:in `block in create_thread_and_abort_on_exception'
stale[bot] commented 2 years ago

Thanks for contributing to this issue. As it has been 2 years since the last activity, we are automatically closing the issue in 30 days. This is often because the request was already solved in some way and it just wasn't updated or it's no longer applicable. If that's not the case, please respond before the issue is closed, or post a message on the mailing list. We'll gladly take a look again!