Skip to content

Instantly share code, notes, and snippets.

@freeatnet
Last active September 19, 2018 11:57
Show Gist options
  • Save freeatnet/d0501319936f567d7d855e200201bf2b to your computer and use it in GitHub Desktop.
Save freeatnet/d0501319936f567d7d855e200201bf2b to your computer and use it in GitHub Desktop.
A a reproducible deadlock in Rails 5.1.2
  1. Have a Rails 5.1.2 app (db=pg (0.20.0), server=puma (3.9.1)) with some models and RESTful endpoints for fetching them.
  2. Have a data-hungry frontend app that launches 5 requests to those endpoints at once (while including Content-Type: application/json in the requests, causing parameter wrapping code to activate, oh well).
  3. See requests stall frequently.
  4. Install ActionDispatch::DebugLocks
  5. Observe /rails/locks output below
Thread 0 [0x3fe97aa39604 sleep] No lock (yielded share)
Waiting in start_exclusive to "load"
may be pre-empted for: "load"
blocked by: 2, 3
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:111:in `sleep'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:111:in `wait'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:111:in `wait'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:123:in `wait_while'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:219:in `wait_for'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:81:in `block (2 levels) in start_exclusive'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:185:in `yield_shares'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:80:in `block in start_exclusive'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:214:in `mon_synchronize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:75:in `start_exclusive'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:147:in `exclusive'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies/interlock.rb:11:in `loading'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:36:in `load_interlock'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:357:in `require_or_load'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:510:in `load_missing_constant'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:202:in `const_missing'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:269:in `const_get'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:269:in `block in constantize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:267:in `each'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:267:in `inject'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:267:in `constantize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:582:in `get'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:613:in `constantize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/http/request.rb:82:in `controller_class'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:43:in `controller'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:29:in `serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:46:in `block in serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:33:in `each'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:33:in `serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:832:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/etag.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/conditional_get.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/head.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/session/abstract/id.rb:232:in `context'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/session/abstract/id.rb:226:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/cookies.rb:613:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activerecord-5.1.2/lib/active_record/migration.rb:556:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/callbacks.rb:97:in `run_callbacks'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/executor.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:135:in `call_app'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:28:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:18:in `catch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:18:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:36:in `call_app'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:24:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:69:in `block in tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:26:in `tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:69:in `tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:24:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/sprockets-rails-3.2.0/lib/sprockets/rails/quiet_assets.rb:13:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/request_id.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/method_override.rb:22:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/runtime.rb:22:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/executor.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/static.rb:125:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/sendfile.rb:111:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/debug_locks.rb:39:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/engine.rb:522:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/configuration.rb:224:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:600:in `handle_request'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:435:in `process_client'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:299:in `block in run'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/thread_pool.rb:120:in `block in spawn_thread'
---
Thread 1 [0x3fe97aa38bc8 sleep] No lock (yielded share)
Waiting in start_exclusive to "load"
may be pre-empted for: "load"
blocked by: 2, 3
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:111:in `sleep'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:111:in `wait'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:111:in `wait'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:123:in `wait_while'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:219:in `wait_for'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:81:in `block (2 levels) in start_exclusive'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:185:in `yield_shares'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:80:in `block in start_exclusive'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/monitor.rb:214:in `mon_synchronize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:75:in `start_exclusive'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/concurrency/share_lock.rb:147:in `exclusive'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies/interlock.rb:11:in `loading'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:36:in `load_interlock'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:357:in `require_or_load'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:510:in `load_missing_constant'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/dependencies.rb:202:in `const_missing'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:269:in `const_get'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:269:in `block in constantize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:267:in `each'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:267:in `inject'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:267:in `constantize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/inflector/methods.rb:312:in `safe_constantize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/core_ext/string/inflections.rb:77:in `safe_constantize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:147:in `_default_wrap_model'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:94:in `block in model'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/mutex_m.rb:74:in `synchronize'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/mutex_m.rb:74:in `mu_synchronize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:94:in `model'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:121:in `name'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:259:in `_wrapper_key'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:286:in `_wrapper_enabled?'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:235:in `process_action'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activerecord-5.1.2/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/abstract_controller/base.rb:124:in `process'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionview-5.1.2/lib/action_view/rendering.rb:30:in `process'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal.rb:189:in `dispatch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal.rb:253:in `dispatch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:31:in `serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:46:in `block in serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:33:in `each'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:33:in `serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:832:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/etag.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/conditional_get.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/head.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/session/abstract/id.rb:232:in `context'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/session/abstract/id.rb:226:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/cookies.rb:613:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activerecord-5.1.2/lib/active_record/migration.rb:556:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/callbacks.rb:97:in `run_callbacks'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/executor.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:135:in `call_app'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:28:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:18:in `catch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:18:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:36:in `call_app'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:24:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:69:in `block in tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:26:in `tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:69:in `tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:24:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/sprockets-rails-3.2.0/lib/sprockets/rails/quiet_assets.rb:13:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/request_id.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/method_override.rb:22:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/runtime.rb:22:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/executor.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/static.rb:125:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/sendfile.rb:111:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/debug_locks.rb:39:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/engine.rb:522:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/configuration.rb:224:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:600:in `handle_request'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:435:in `process_client'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:299:in `block in run'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/thread_pool.rb:120:in `block in spawn_thread'
---
Thread 2 [0x3fe97ae11efc sleep] Sharing
blocking: 0, 1
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/mutex_m.rb:74:in `synchronize'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/mutex_m.rb:74:in `mu_synchronize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:94:in `model'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:121:in `name'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:259:in `_wrapper_key'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:286:in `_wrapper_enabled?'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:235:in `process_action'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activerecord-5.1.2/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/abstract_controller/base.rb:124:in `process'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionview-5.1.2/lib/action_view/rendering.rb:30:in `process'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal.rb:189:in `dispatch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal.rb:253:in `dispatch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:31:in `serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:46:in `block in serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:33:in `each'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:33:in `serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:832:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/etag.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/conditional_get.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/head.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/session/abstract/id.rb:232:in `context'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/session/abstract/id.rb:226:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/cookies.rb:613:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activerecord-5.1.2/lib/active_record/migration.rb:556:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/callbacks.rb:97:in `run_callbacks'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/executor.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:135:in `call_app'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:28:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:18:in `catch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:18:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:36:in `call_app'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:24:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:69:in `block in tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:26:in `tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:69:in `tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:24:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/sprockets-rails-3.2.0/lib/sprockets/rails/quiet_assets.rb:13:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/request_id.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/method_override.rb:22:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/runtime.rb:22:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/executor.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/static.rb:125:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/sendfile.rb:111:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/debug_locks.rb:39:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/engine.rb:522:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/configuration.rb:224:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:600:in `handle_request'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:435:in `process_client'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:299:in `block in run'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/thread_pool.rb:120:in `block in spawn_thread'
---
Thread 3 [0x3fe97aa38d58 sleep] Sharing
blocking: 0, 1
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/mutex_m.rb:74:in `synchronize'
/Users/freeatnet/.rvm/rubies/ruby-2.4.1/lib/ruby/2.4.0/mutex_m.rb:74:in `mu_synchronize'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:94:in `model'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:121:in `name'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:259:in `_wrapper_key'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:286:in `_wrapper_enabled?'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal/params_wrapper.rb:235:in `process_action'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activerecord-5.1.2/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/abstract_controller/base.rb:124:in `process'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionview-5.1.2/lib/action_view/rendering.rb:30:in `process'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal.rb:189:in `dispatch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_controller/metal.rb:253:in `dispatch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:31:in `serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:46:in `block in serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:33:in `each'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/journey/router.rb:33:in `serve'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/routing/route_set.rb:832:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/etag.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/conditional_get.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/head.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/session/abstract/id.rb:232:in `context'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/session/abstract/id.rb:226:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/cookies.rb:613:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activerecord-5.1.2/lib/active_record/migration.rb:556:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/callbacks.rb:97:in `run_callbacks'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/executor.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:135:in `call_app'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:28:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:18:in `catch'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/web-console-3.5.1/lib/web_console/middleware.rb:18:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:36:in `call_app'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:24:in `block in call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:69:in `block in tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:26:in `tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/tagged_logging.rb:69:in `tagged'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/rack/logger.rb:24:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/sprockets-rails-3.2.0/lib/sprockets/rails/quiet_assets.rb:13:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/request_id.rb:25:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/method_override.rb:22:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/runtime.rb:22:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/activesupport-5.1.2/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/executor.rb:12:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/static.rb:125:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/rack-2.0.3/lib/rack/sendfile.rb:111:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/actionpack-5.1.2/lib/action_dispatch/middleware/debug_locks.rb:39:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/railties-5.1.2/lib/rails/engine.rb:522:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/configuration.rb:224:in `call'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:600:in `handle_request'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:435:in `process_client'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/server.rb:299:in `block in run'
/Users/freeatnet/.rvm/gems/ruby-2.4.1@projname/gems/puma-3.8.2/lib/puma/thread_pool.rb:120:in `block in spawn_thread'
@singhprd
Copy link

singhprd commented Sep 19, 2018

I think this may be related to this issue here:
rails/rails#32451

Monkey-patching this potential fix seemed to help a little in my case:
https://github.com/rails/rails/pull/32541/files

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment