NoMethodError: undefined method `duration' for nil:NilClass

[PetrKaleta]

Stack trace:

org/jruby/ext/thread/Mutex.java:149:in `synchronize`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/middleware/chain.rb:124:in `invoke`
[GEM_ROOT]/gems/newrelic_rpm-3.9.0.229/lib/new_relic/agent/instrumentation/sidekiq.rb:33:in `call`
[GEM_ROOT]/gems/newrelic_rpm-3.9.0.229/lib/new_relic/agent/instrumentation/controller_instrumentation.rb:364:in `perform_action_with_newrelic_trace`
[GEM_ROOT]/gems/newrelic_rpm-3.9.0.229/lib/new_relic/agent/instrumentation/sidekiq.rb:29:in `call`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/middleware/chain.rb:124:in `invoke`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/middleware/server/retry_jobs.rb:62:in `call`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/middleware/chain.rb:124:in `invoke`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/middleware/server/logging.rb:11:in `call`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/logging.rb:22:in `with_context`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/middleware/server/logging.rb:7:in `call`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/middleware/chain.rb:124:in `invoke`
org/jruby/RubyProc.java:271:in `call`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/middleware/chain.rb:127:in `invoke`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/processor.rb:51:in `process`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/processor.rb:94:in `stats`
[GEM_ROOT]/gems/sidekiq-3.1.4/lib/sidekiq/processor.rb:50:in `process`
org/jruby/RubyKernel.java:1945:in `public_send`
[GEM_ROOT]/gems/celluloid-0.15.2/lib/celluloid/calls.rb:25:in `dispatch`
[GEM_ROOT]/gems/celluloid-0.15.2/lib/celluloid/calls.rb:122:in `dispatch`
[GEM_ROOT]/gems/celluloid-0.15.2/lib/celluloid/actor.rb:322:in `handle_message`
[GEM_ROOT]/gems/celluloid-0.15.2/lib/celluloid/actor.rb:416:in `task`
[GEM_ROOT]/gems/celluloid-0.15.2/lib/celluloid/tasks.rb:55:in `initialize`
[GEM_ROOT]/gems/celluloid-0.15.2/lib/celluloid/tasks.rb:47:in `initialize`
[GEM_ROOT]/gems/celluloid-0.15.2/lib/celluloid/tasks/task_fiber.rb:13:in `create`

Bundle list:

  * addressable (2.3.6)
  * aws-sdk (1.46.0)
  * bundler (1.6.3)
  * celluloid (0.15.2)
  * coderay (1.1.0)
  * connection_pool (2.0.0)
  * crack (0.4.2)
  * cranky (0.3.1)
  * database_cleaner (1.3.0)
  * diff-lcs (1.2.5)
  * erubis (2.7.0)
  * ethon (0.7.1)
  * ffi (1.9.3)
  * honeybadger (1.15.3)
  * i18n (0.6.9)
  * jdbc-postgres (9.3.1101)
  * json (1.8.1)
  * mail (2.6.1)
  * method_source (0.8.2)
  * mime-types (2.3)
  * newrelic_rpm (3.9.0.229)
  * nokogiri (1.6.2.1)
  * pony (1.9)
  * pry (0.10.0)
  * pry-doc (0.6.0)
  * puma (2.8.2)
  * rack (1.5.2)
  * rack-protection (1.5.3)
  * rack-test (0.6.2)
  * rack-timeout (0.0.4)
  * rake (10.3.2)
  * redis (3.1.0)
  * redis-namespace (1.5.0)
  * roo (1.13.2)
  * rspec (3.0.0)
  * rspec-core (3.0.2)
  * rspec-expectations (3.0.2)
  * rspec-mocks (3.0.2)
  * rspec-support (3.0.2)
  * ruby-ole (1.2.11.7)
  * rubyzip (1.1.4)
  * safe_yaml (1.0.3)
  * sequel (4.12.0)
  * sequel_postgresql_triggers (1.0.7)
  * sidekiq (3.1.4)
  * simple_oauth (0.2.0)
  * sinatra (1.4.5)
  * sinatra-flash (0.3.0)
  * sinatra-partial (0.4.0)
  * slop (3.5.0)
  * spoon (0.0.4)
  * spreadsheet (0.9.7)
  * tilt (1.4.1)
  * timers (1.1.0)
  * typhoeus (0.6.9)
  * webmock (1.18.0)
  * yard (0.8.7.4)

Running on:

Heroku JRuby 1.7.13, concurrency: 5

[mperham]

Are you sure that's the full backtrace? That trace doesn't make sense.

[PetrKaleta]

Yes, thats everything that Honeybadger logged...

[PetrKaleta]

Maybe its something related to newrelic

[mperham]

I doubt it's a problem with NewRelic. I'd guess it's in the middleware after NewRelic in the chain.

But I can't track down the problem with that trace. The error message tells me a bit but Sidekiq itself does not call a duration method on anything.

[PetrKaleta]

Yes I see, its a newrelic issue https://github.com/newrelic/rpm/search?q=duration&ref=cmdform

[mperham]

Ah, yes, that's a brand new version of NewRelic, 3.9.0. Looks like a new bug.