search

lynndylanhurley / devise_token_auth

Token based authentication for Rails JSON APIs. Designed to work with jToker and ng-token-auth.
Do What The F*ck You Want To Public License
3.55k stars 1.13k forks source link

no implicit conversion of Hash into String #1240

Open fadyZohdy opened 6 years ago

fadyZohdy commented 6 years ago

Hi we are currently hitting this issue with jruby 9.2.4.0 , rails 5.0.2 and devise_auth_token current master commit stack trace 

json/ext/Parser.java:170→ initialize
json/ext/Parser.java:142→ new
json-2.0.3-java/lib/json/common.rb:156→ parse
activesupport-5.0.2/lib/active_support/json/decoding.rb:21→ decode
activerecord-5.0.2/lib/active_record/coders/json.rb:9→ load
activerecord-5.0.2/lib/active_record/type/serialized.rb:18→ deserialize
/opt/jruby/lib/ruby/stdlib/delegate.rb:349→ block in delegating_block
activerecord-5.0.2/lib/active_record/type/serialized.rb:18→ deserialize
/opt/jruby/lib/ruby/stdlib/delegate.rb:349→ block in delegating_block
activerecord-5.0.2/lib/active_record/type/serialized.rb:18→ deserialize
activerecord-5.0.2/lib/active_record/attribute.rb:140→ type_cast
activerecord-5.0.2/lib/active_record/attribute.rb:38→ value
activerecord-5.0.2/lib/active_record/attribute_set.rb:40→ fetch_value
activerecord-5.0.2/lib/active_record/attribute_methods/read.rb:62→ _read_attribute
activerecord-5.0.2/lib/active_record/attribute_methods/read.rb:36→ __temp__47f6b656e637
[PROJECT_ROOT]/vendor/cache/devise_token_auth-44b4a185479c/app/models/devise_token_auth/concerns/active_record_support.rb:32→ set_empty_token_hash
activesupport-5.0.2/lib/active_support/callbacks.rb:382→ block in make_lambda
activesupport-5.0.2/lib/active_support/callbacks.rb:207→ block in halting_and_conditional
activesupport-5.0.2/lib/active_support/callbacks.rb:456→ block in call
org/jruby/RubyArray.java:1789→ each
activesupport-5.0.2/lib/active_support/callbacks.rb:456→ call
activesupport-5.0.2/lib/active_support/callbacks.rb:101→ __run_callbacks__
activesupport-5.0.2/lib/active_support/callbacks.rb:750→ _run_initialize_callbacks
activerecord-5.0.2/lib/active_record/core.rb:352→ init_with
activerecord-5.0.2/lib/active_record/persistence.rb:69→ instantiate
activerecord-5.0.2/lib/active_record/querying.rb:50→ block in find_by_sql
activerecord-5.0.2/lib/active_record/result.rb:52→ block in each
org/jruby/RubyArray.java:1789→ each
activerecord-5.0.2/lib/active_record/result.rb:52→ each
org/jruby/RubyEnumerable.java:833→ map
activerecord-5.0.2/lib/active_record/querying.rb:50→ block in find_by_sql
activesupport-5.0.2/lib/active_support/notifications/instrumenter.rb:21→ instrument
activerecord-5.0.2/lib/active_record/querying.rb:49→ find_by_sql
activerecord-5.0.2/lib/active_record/relation.rb:702→ exec_queries
fresh_connection-2.4.4/lib/fresh_connection/extend/ar_relation.rb:69→ block in exec_queries
fresh_connection-2.4.4/lib/fresh_connection/access_control.rb:39→ switch_to
fresh_connection-2.4.4/lib/fresh_connection/access_control.rb:13→ access
fresh_connection-2.4.4/lib/fresh_connection/extend/ar_relation.rb:15→ manage_access
fresh_connection-2.4.4/lib/fresh_connection/extend/ar_relation.rb:69→ exec_queries
activerecord-5.0.2/lib/active_record/relation.rb:583→ load
activerecord-5.0.2/lib/active_record/relation.rb:260→ records
activerecord-5.0.2/lib/active_record/relation.rb:256→ to_a
activerecord-5.0.2/lib/active_record/relation/finder_methods.rb:563→ find_nth_with_limit
activerecord-5.0.2/lib/active_record/relation/finder_methods.rb:592→ find_nth_with_limit_and_offset
activerecord-5.0.2/lib/active_record/relation/finder_methods.rb:545→ find_nth
activerecord-5.0.2/lib/active_record/relation/finder_methods.rb:122→ first
[PROJECT_ROOT]/vendor/cache/devise_token_auth-44b4a185479c/app/controllers/devise_token_auth/concerns/resource_finder.rb:25→ find_resource
app/controllers/concerns/session_cacher.rb:14→ find_resource
[PROJECT_ROOT]/vendor/cache/devise_token_auth-44b4a185479c/app/controllers/devise_token_auth/sessions_controller.rb:21→ create
app/controllers/devise/sessions_controller.rb:7→ create
actionpack-5.0.2/lib/action_controller/metal/basic_implicit_render.rb:4→ send_action
actionpack-5.0.2/lib/abstract_controller/base.rb:188→ process_action
actionpack-5.0.2/lib/action_controller/metal/rendering.rb:30→ process_action
actionpack-5.0.2/lib/abstract_controller/callbacks.rb:20→ block in process_action
activesupport-5.0.2/lib/active_support/callbacks.rb:126→ call
activesupport-5.0.2/lib/active_support/callbacks.rb:506→ block in compile
activesupport-5.0.2/lib/active_support/callbacks.rb:455→ call
activesupport-5.0.2/lib/active_support/callbacks.rb:448→ block in around
activesupport-5.0.2/lib/active_support/callbacks.rb:286→ block in halting
sentry-raven-2.7.1/lib/raven/integrations/rails/controller_transaction.rb:7→ block in included
org/jruby/RubyBasicObject.java:2666→ instance_exec
activesupport-5.0.2/lib/active_support/callbacks.rb:391→ block in make_lambda
activesupport-5.0.2/lib/active_support/callbacks.rb:285→ block in halting
activesupport-5.0.2/lib/active_support/callbacks.rb:447→ block in around
activesupport-5.0.2/lib/active_support/callbacks.rb:455→ call
activesupport-5.0.2/lib/active_support/callbacks.rb:101→ __run_callbacks__
activesupport-5.0.2/lib/active_support/callbacks.rb:750→ _run_process_action_callbacks
activesupport-5.0.2/lib/active_support/callbacks.rb:90→ run_callbacks
actionpack-5.0.2/lib/abstract_controller/callbacks.rb:19→ process_action
actionpack-5.0.2/lib/action_controller/metal/rescue.rb:20→ process_action
actionpack-5.0.2/lib/action_controller/metal/instrumentation.rb:36→ block in process_action
activesupport-5.0.2/lib/active_support/notifications.rb:164→ block in instrument
activesupport-5.0.2/lib/active_support/notifications/instrumenter.rb:21→ instrument
activesupport-5.0.2/lib/active_support/notifications.rb:164→ instrument
actionpack-5.0.2/lib/action_controller/metal/instrumentation.rb:30→ process_action
actionpack-5.0.2/lib/action_controller/metal/params_wrapper.rb:248→ process_action
activerecord-5.0.2/lib/active_record/railties/controller_runtime.rb:18→ process_action
actionpack-5.0.2/lib/abstract_controller/base.rb:126→ process
actionview-5.0.2/lib/action_view/rendering.rb:30→ process
actionpack-5.0.2/lib/action_controller/metal.rb:190→ dispatch
actionpack-5.0.2/lib/action_controller/metal.rb:262→ dispatch
actionpack-5.0.2/lib/action_dispatch/routing/route_set.rb:50→ dispatch
actionpack-5.0.2/lib/action_dispatch/routing/route_set.rb:32→ serve
actionpack-5.0.2/lib/action_dispatch/routing/mapper.rb:16→ block in Constraints
actionpack-5.0.2/lib/action_dispatch/routing/mapper.rb:46→ serve
actionpack-5.0.2/lib/action_dispatch/journey/router.rb:39→ block in serve
org/jruby/RubyArray.java:1789→ each
actionpack-5.0.2/lib/action_dispatch/journey/router.rb:26→ serve
actionpack-5.0.2/lib/action_dispatch/routing/route_set.rb:725→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
aws-xray-sdk-0.11.1/lib/aws-xray-sdk/facets/rails/ex_middleware.rb:16→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
newrelic_rpm-5.0.0.342/lib/new_relic/rack/agent_hooks.rb:30→ traced_call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
newrelic_rpm-5.0.0.342/lib/new_relic/rack/browser_monitoring.rb:32→ traced_call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
prometheus-client-0.6.0/lib/prometheus/client/rack/exporter.rb:29→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
prometheus-client-0.6.0/lib/prometheus/client/rack/collector.rb:24→ block in call
prometheus-client-0.6.0/lib/prometheus/client/rack/collector.rb:57→ trace
prometheus-client-0.6.0/lib/prometheus/client/rack/collector.rb:24→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
warden-1.2.7/lib/warden/manager.rb:36→ block in call
org/jruby/RubyKernel.java:1181→ catch
warden-1.2.7/lib/warden/manager.rb:35→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-2.0.1/lib/rack/etag.rb:25→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-2.0.1/lib/rack/conditional_get.rb:38→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-2.0.1/lib/rack/head.rb:12→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/callbacks.rb:38→ block in call
activesupport-5.0.2/lib/active_support/callbacks.rb:97→ __run_callbacks__
activesupport-5.0.2/lib/active_support/callbacks.rb:750→ _run_call_callbacks
activesupport-5.0.2/lib/active_support/callbacks.rb:90→ run_callbacks
actionpack-5.0.2/lib/action_dispatch/middleware/callbacks.rb:36→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/remote_ip.rb:79→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
airbrake-5.8.1/lib/airbrake/rack/middleware.rb:52→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/debug_exceptions.rb:49→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/show_exceptions.rb:31→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
railties-5.0.2/lib/rails/rack/logger.rb:36→ call_app
railties-5.0.2/lib/rails/rack/logger.rb:24→ block in call
activesupport-5.0.2/lib/active_support/tagged_logging.rb:69→ block in tagged
activesupport-5.0.2/lib/active_support/tagged_logging.rb:26→ tagged
activesupport-5.0.2/lib/active_support/tagged_logging.rb:69→ tagged
railties-5.0.2/lib/rails/rack/logger.rb:24→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
request_store-1.3.2/lib/request_store/middleware.rb:9→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/request_id.rb:24→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-2.0.1/lib/rack/runtime.rb:22→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-timeout-0.4.2/lib/rack/timeout/core.rb:122→ block in call
rack-timeout-0.4.2/lib/rack/timeout/support/timeout.rb:19→ timeout
rack-timeout-0.4.2/lib/rack/timeout/core.rb:121→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/executor.rb:12→ call

newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-cache-1.7.1/lib/rack/cache/context.rb:140→ forward
rack-cache-1.7.1/lib/rack/cache/context.rb:147→ pass
rack-cache-1.7.1/lib/rack/cache/context.rb:159→ invalidate

request headers {"HTTP_VERSION"=>"HTTP/1.1", "HTTP_X_FORWARDED_FOR"=>"41.233.210.31, 70.132.17.60", "HTTP_X_FORWARDED_PROTO"=>"https", "HTTP_X_FORWARDED_PORT"=>"443", "HTTP_HOST"=>"api-stg.bulkwhiz.com", "HTTP_X_AMZN_TRACE_ID"=>"Root=1-5bfc217f-f18823bdae0cc5a531f1324a", "CONTENT_LENGTH"=>"56", "HTTP_X_AMZ_CF_ID"=>"hLMaI7Gt7sL_H4Pb3jv_MXXUGfO6mjTBc8AG4cMsFxPXxpt-HlE7Dw==", "HTTP_USER_AGENT"=>"Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_0) AppleWebKit/537.36 (KHTML, like Gecko) ReactNativeDebugger/0.8.1 Chrome/58.0.3029.110 Electron/1.7.15 Safari/537.36", "HTTP_VIA"=>"2.0 69127f05596a3a203754702b246cd233.cloudfront.net (CloudFront)", "HTTP_CLOUDFRONT_IS_MOBILE_VIEWER"=>"false", "HTTP_CLOUDFRONT_IS_TABLET_VIEWER"=>"false", "HTTP_CLOUDFRONT_IS_SMARTTV_VIEWER"=>"false", "HTTP_CLOUDFRONT_IS_DESKTOP_VIEWER"=>"true", "HTTP_CLOUDFRONT_VIEWER_COUNTRY"=>"EG", "HTTP_ACCEPT_LANGUAGE"=>"en-US", "HTTP_ACCEPT"=>"application/json, text/plain, */*", "HTTP_CLOUDFRONT_FORWARDED_PROTO"=>"https", "HTTP_ACCEPT_ENCODING"=>"gzip, deflate", "CONTENT_TYPE"=>"application/json", "HTTP_ORIGIN"=>"null", "HTTP_X_DEVTOOLS_REQUEST_ID"=>"27374.948", "HTTP_ACCESS_TOKEN"=>"xxxxxxxxxxxxxxxxx", "HTTP_ANONYMOUS_UID"=>"xxxxxxxxxxxxxxx", "HTTP_APP_VERSION"=>"dev-1.0.32", "HTTP_CLIENT"=>"87sDdzudN6hGR-LpEy62gQ", "HTTP_UID"=>"ihk2010+atom@gmail.com"}

SessionCacher class

# based on https://gist.github.com/jhjguxin/5696365
module SessionCacher
  extend ActiveSupport::Concern

  included do
    # super devise's `serialize_from_session`, which can cache current_user in session
    # Suitable for devise 2.x - 3.x
    # https://github.com/plataformatec/devise/blob/master/lib/devise/models/authenticatable.rb#L207
    def find_resource(field, value)
      # record = Rails.cache.fetch("user:#{single_key}") { self.where(id: single_key).limit(1).first }
      # record if record && record.authenticatable_salt == salt
      # record.blank? ? super : record
      super
    end
  end

  # instance method
end

and since we are on jruby we are using activerecord-jdbcmysql-adapter the issue happens mainly at sign_in in devise/session#create

fadyZohdy commented 6 years ago

i just want to note that the issue is very inconsistent. the code would work for a while and then this isuue will happen and won't go away till we restart the app.

fadyZohdy commented 6 years ago

the issue happened again today but originated from another part of the code 


json/ext/Parser.java:170→ initialize
--
json/ext/Parser.java:142→ new
json-2.0.3-java/lib/json/common.rb:156→ parse
activesupport-5.0.2/lib/active_support/json/decoding.rb:21→ decode
activerecord-5.0.2/lib/active_record/coders/json.rb:9→ load
activerecord-5.0.2/lib/active_record/type/serialized.rb:18→ deserialize
/opt/jruby/lib/ruby/stdlib/delegate.rb:349→ block in delegating_block
activerecord-5.0.2/lib/active_record/type/serialized.rb:18→ deserialize
/opt/jruby/lib/ruby/stdlib/delegate.rb:349→ block in delegating_block
activerecord-5.0.2/lib/active_record/type/serialized.rb:18→ deserialize
activerecord-5.0.2/lib/active_record/attribute.rb:140→ type_cast
activerecord-5.0.2/lib/active_record/attribute.rb:38→ value
activerecord-5.0.2/lib/active_record/attribute_set.rb:40→ fetch_value
activerecord-5.0.2/lib/active_record/attribute_methods/read.rb:62→ _read_attribute
activerecord-5.0.2/lib/active_record/attribute_methods/read.rb:36→ __temp__47f6b656e637
[PROJECT_ROOT]/vendor/cache/devise_token_auth-44b4a185479c/app/models/devise_token_auth/concerns/active_record_support.rb:32→ set_empty_token_hash
activesupport-5.0.2/lib/active_support/callbacks.rb:382→ block in make_lambda
activesupport-5.0.2/lib/active_support/callbacks.rb:207→ block in halting_and_conditional
activesupport-5.0.2/lib/active_support/callbacks.rb:456→ block in call
org/jruby/RubyArray.java:1789→ each
activesupport-5.0.2/lib/active_support/callbacks.rb:456→ call
activesupport-5.0.2/lib/active_support/callbacks.rb:101→ __run_callbacks__
activesupport-5.0.2/lib/active_support/callbacks.rb:750→ _run_initialize_callbacks
activerecord-5.0.2/lib/active_record/core.rb:352→ init_with
activerecord-5.0.2/lib/active_record/persistence.rb:69→ instantiate
activerecord-5.0.2/lib/active_record/querying.rb:50→ block in find_by_sql
activerecord-5.0.2/lib/active_record/result.rb:52→ block in each
org/jruby/RubyArray.java:1789→ each
activerecord-5.0.2/lib/active_record/result.rb:52→ each
org/jruby/RubyEnumerable.java:833→ map
activerecord-5.0.2/lib/active_record/querying.rb:50→ block in find_by_sql
activesupport-5.0.2/lib/active_support/notifications/instrumenter.rb:21→ instrument
activerecord-5.0.2/lib/active_record/querying.rb:49→ find_by_sql
activerecord-5.0.2/lib/active_record/statement_cache.rb:109→ execute
fresh_connection-2.4.4/lib/fresh_connection/extend/ar_statement_cache.rb:5→ block in execute
fresh_connection-2.4.4/lib/fresh_connection/access_control.rb:39→ switch_to

fresh_connection-2.4.4/lib/fresh_connection/access_control.rb:13→ access
fresh_connection-2.4.4/lib/fresh_connection/extend/ar_relation.rb:15→ manage_access
fresh_connection-2.4.4/lib/fresh_connection/extend/ar_statement_cache.rb:5→ execute
activerecord-5.0.2/lib/active_record/core.rb:171→ find
app/controllers/user_management/users_controller.rb:189→ set_user
activesupport-5.0.2/lib/active_support/callbacks.rb:382→ block in make_lambda
activesupport-5.0.2/lib/active_support/callbacks.rb:150→ block in halting_and_conditional
actionpack-5.0.2/lib/abstract_controller/callbacks.rb:12→ block in Callbacks
activesupport-5.0.2/lib/active_support/callbacks.rb:151→ block in halting_and_conditional
activesupport-5.0.2/lib/active_support/callbacks.rb:454→ block in call
org/jruby/RubyArray.java:1789→ each
activesupport-5.0.2/lib/active_support/callbacks.rb:454→ call
activesupport-5.0.2/lib/active_support/callbacks.rb:448→ block in around
activesupport-5.0.2/lib/active_support/callbacks.rb:286→ block in halting
sentry-raven-2.7.1/lib/raven/integrations/rails/controller_transaction.rb:7→ block in included
org/jruby/RubyBasicObject.java:2666→ instance_exec
activesupport-5.0.2/lib/active_support/callbacks.rb:391→ block in make_lambda
activesupport-5.0.2/lib/active_support/callbacks.rb:285→ block in halting
activesupport-5.0.2/lib/active_support/callbacks.rb:447→ block in around
activesupport-5.0.2/lib/active_support/callbacks.rb:455→ call
activesupport-5.0.2/lib/active_support/callbacks.rb:101→ __run_callbacks__
activesupport-5.0.2/lib/active_support/callbacks.rb:750→ _run_process_action_callbacks
activesupport-5.0.2/lib/active_support/callbacks.rb:90→ run_callbacks
actionpack-5.0.2/lib/abstract_controller/callbacks.rb:19→ process_action
actionpack-5.0.2/lib/action_controller/metal/rescue.rb:20→ process_action
actionpack-5.0.2/lib/action_controller/metal/instrumentation.rb:36→ block in process_action
activesupport-5.0.2/lib/active_support/notifications.rb:164→ block in instrument
activesupport-5.0.2/lib/active_support/notifications/instrumenter.rb:21→ instrument
activesupport-5.0.2/lib/active_support/notifications.rb:164→ instrument
actionpack-5.0.2/lib/action_controller/metal/instrumentation.rb:30→ process_action
actionpack-5.0.2/lib/action_controller/metal/params_wrapper.rb:248→ process_action
activerecord-5.0.2/lib/active_record/railties/controller_runtime.rb:18→ process_action
actionpack-5.0.2/lib/abstract_controller/base.rb:126→ process
actionview-5.0.2/lib/action_view/rendering.rb:30→ process
actionpack-5.0.2/lib/action_controller/metal.rb:190→ dispatch
actionpack-5.0.2/lib/action_controller/metal.rb:262→ dispatch
actionpack-5.0.2/lib/action_dispatch/routing/route_set.rb:50→ dispatch
actionpack-5.0.2/lib/action_dispatch/routing/route_set.rb:32→ serve
actionpack-5.0.2/lib/action_dispatch/journey/router.rb:39→ block in serve
org/jruby/RubyArray.java:1789→ each
actionpack-5.0.2/lib/action_dispatch/journey/router.rb:26→ serve
actionpack-5.0.2/lib/action_dispatch/routing/route_set.rb:725→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
aws-xray-sdk-0.11.1/lib/aws-xray-sdk/facets/rails/ex_middleware.rb:16→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
newrelic_rpm-5.0.0.342/lib/new_relic/rack/agent_hooks.rb:30→ traced_call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
newrelic_rpm-5.0.0.342/lib/new_relic/rack/browser_monitoring.rb:32→ traced_call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
prometheus-client-0.6.0/lib/prometheus/client/rack/exporter.rb:29→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
prometheus-client-0.6.0/lib/prometheus/client/rack/collector.rb:24→ block in call
prometheus-client-0.6.0/lib/prometheus/client/rack/collector.rb:57→ trace
prometheus-client-0.6.0/lib/prometheus/client/rack/collector.rb:24→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
warden-1.2.7/lib/warden/manager.rb:36→ block in call
org/jruby/RubyKernel.java:1181→ catch
warden-1.2.7/lib/warden/manager.rb:35→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-2.0.1/lib/rack/etag.rb:25→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-2.0.1/lib/rack/conditional_get.rb:25→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-2.0.1/lib/rack/head.rb:12→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/callbacks.rb:38→ block in call
activesupport-5.0.2/lib/active_support/callbacks.rb:97→ __run_callbacks__
activesupport-5.0.2/lib/active_support/callbacks.rb:750→ _run_call_callbacks
activesupport-5.0.2/lib/active_support/callbacks.rb:90→ run_callbacks
actionpack-5.0.2/lib/action_dispatch/middleware/callbacks.rb:36→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/remote_ip.rb:79→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
airbrake-5.8.1/lib/airbrake/rack/middleware.rb:52→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/debug_exceptions.rb:49→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/show_exceptions.rb:31→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
railties-5.0.2/lib/rails/rack/logger.rb:36→ call_app
railties-5.0.2/lib/rails/rack/logger.rb:24→ block in call
activesupport-5.0.2/lib/active_support/tagged_logging.rb:69→ block in tagged
activesupport-5.0.2/lib/active_support/tagged_logging.rb:26→ tagged
activesupport-5.0.2/lib/active_support/tagged_logging.rb:69→ tagged
railties-5.0.2/lib/rails/rack/logger.rb:24→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
request_store-1.3.2/lib/request_store/middleware.rb:9→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/request_id.rb:24→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-2.0.1/lib/rack/runtime.rb:22→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-timeout-0.4.2/lib/rack/timeout/core.rb:122→ block in call
rack-timeout-0.4.2/lib/rack/timeout/support/timeout.rb:19→ timeout
rack-timeout-0.4.2/lib/rack/timeout/core.rb:121→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
actionpack-5.0.2/lib/action_dispatch/middleware/executor.rb:12→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-cache-1.7.1/lib/rack/cache/context.rb:140→ forward
rack-cache-1.7.1/lib/rack/cache/context.rb:249→ fetch
rack-cache-1.7.1/lib/rack/cache/context.rb:189→ lookup
rack-cache-1.7.1/lib/rack/cache/context.rb:66→ call!

rack-cache-1.7.1/lib/rack/cache/context.rb:51→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
rack-2.0.1/lib/rack/sendfile.rb:111→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
app/middleware/track_bad_response_code_to_mixpanel.rb:12→ _call
app/middleware/track_bad_response_code_to_mixpanel.rb:7→ call
newrelic_rpm-5.0.0.342/lib/new_relic/agent/instrumentation/middleware_tracing.rb:92→ call
app/middleware/log_item_view.rb:31→ _call
app/middleware/log_item_view.rb:7→ call

and this is the last call from our app

def set_user
    begin
      @user = User.find(params[:id])
    rescue ActiveRecord::RecordNotFound => error
      render json: { errors: error }, status: :not_found
    end
  end
MaicolBen commented 5 years ago

This might be fixed in #1250, post here if it's still happening when you have this gem from the master branch, otherwise I will close this in 1 month