Unusual exceptions sometimes occur at startup: uninitialized constant ActionDispatch::Http::FilterParameters::ParameterFilter -----------------------------------------------------------------------------------------------------------------------------
Key: JRUBY-6254 URL: https://jira.codehaus.org/browse/JRUBY-6254 Project: JRuby Issue Type: Bug Affects Versions: JRuby 1.6.5 Environment: Ubuntu 11.04 64-bit, Rails 3.0.10, tomcat 7.0.23, warbler 1.3.2, running in --1.9 mode. Reporter: David Wood Assignee: Thomas E Enebo In our (threadsafe) rails app, when I warble it, install it in tomcat 7, and run our automated tests against it, we frequently, but not every time, see an exception causing a test failure right at the start of the test run: {code} org.jruby.exceptions.RaiseException: (NameError) uninitialized constant ActionDispatch::Http::FilterParameters::ParameterFilter at org.jruby.RubyModule.const_missing(org/jruby/RubyModule.java:2590) at Module.const_missing(<<<OMITTED>>>/WEB-INF/gems/gems/rake-0.9.2.2/lib/rake/ext/module.rb:36) at org.jruby.RubyMethod.call(org/jruby/RubyMethod.java:133) at ActionDispatch::Http::FilterParameters.parameter_filter_for(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:57) at ActionDispatch::Http::FilterParameters.parameter_filter(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:49) at ActionDispatch::Request.filtered_query_string(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:64) at org.jruby.RubyString.gsub(org/jruby/RubyString.java:2877) at ActionDispatch::Http::FilterParameters.filtered_query_string(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:63) at ActionDispatch::Http::FilterParameters.filtered_path(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:43) at Rails::Rack::Logger.before_dispatch(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/rack/logger.rb:22) at Rails::Rack::Logger.call(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/rack/logger.rb:12) at Rack::Runtime.call(<<<OMITTED>>>/WEB-INF/gems/gems/rack-1.2.4/lib/rack/runtime.rb:17) at #<Class:0x6830d77d>.call(<<<OMITTED>>>/WEB-INF/gems/gems/activesupport-3.0.10/lib/active_support/cache/strategy/local_cache.rb:90) at ActionDispatch::Static.call(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/middleware/static.rb:30) at Rails::Application.call(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/application.rb:168) at Rack::Handler::Servlet.call(file:<<<OMITTED>>>/WEB-INF/lib/jruby-rack-1.1.1.jar!/rack/handler/servlet.rb:19) Dec 7, 2011 11:36:21 AM org.apache.catalina.core.ApplicationContext log SEVERE: Application Error org.jruby.exceptions.RaiseException: (NameError) uninitialized constant ActionDispatch::Http::FilterParameters::ParameterFilter at org.jruby.RubyModule.const_missing(org/jruby/RubyModule.java:2590) at Module.const_missing(<<<OMITTED>>>/WEB-INF/gems/gems/rake-0.9.2.2/lib/rake/ext/module.rb:36) at org.jruby.RubyMethod.call(org/jruby/RubyMethod.java:133) at ActionDispatch::Http::FilterParameters.parameter_filter_for(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:57) at ActionDispatch::Http::FilterParameters.parameter_filter(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:49) at ActionDispatch::Request.filtered_query_string(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:64) at org.jruby.RubyString.gsub(org/jruby/RubyString.java:2877) at ActionDispatch::Http::FilterParameters.filtered_query_string(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:63) at ActionDispatch::Http::FilterParameters.filtered_path(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:43) at Rails::Rack::Logger.before_dispatch(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/rack/logger.rb:22) at Rails::Rack::Logger.call(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/rack/logger.rb:12) at Rack::Runtime.call(<<<OMITTED>>>/WEB-INF/gems/gems/rack-1.2.4/lib/rack/runtime.rb:17) at #<Class:0x6830d77d>.call(<<<OMITTED>>>/WEB-INF/gems/gems/activesupport-3.0.10/lib/active_support/cache/strategy/local_cache.rb:90) at ActionDispatch::Static.call(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/middleware/static.rb:30) at Rails::Application.call(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/application.rb:168) at Rack::Handler::Servlet.call(file:<<<OMITTED>>>/WEB-INF/lib/jruby-rack-1.1.1.jar!/rack/handler/servlet.rb:19) Dec 7, 2011 11:36:21 AM org.apache.catalina.core.ApplicationContext log SEVERE: Application Error org.jruby.exceptions.RaiseException: (NameError) uninitialized constant ActionDispatch::Http::FilterParameters::ParameterFilter at org.jruby.RubyModule.const_missing(org/jruby/RubyModule.java:2590) at Module.const_missing(<<<OMITTED>>>/WEB-INF/gems/gems/rake-0.9.2.2/lib/rake/ext/module.rb:36) at org.jruby.RubyMethod.call(org/jruby/RubyMethod.java:133) at ActionDispatch::Http::FilterParameters.parameter_filter_for(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:57) at ActionDispatch::Http::FilterParameters.parameter_filter(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:49) at ActionDispatch::Request.filtered_query_string(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:64) at org.jruby.RubyString.gsub(org/jruby/RubyString.java:2877) at ActionDispatch::Http::FilterParameters.filtered_query_string(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:63) at ActionDispatch::Http::FilterParameters.filtered_path(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:43) at Rails::Rack::Logger.before_dispatch(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/rack/logger.rb:22) at Rails::Rack::Logger.call(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/rack/logger.rb:12) at Rack::Runtime.call(<<<OMITTED>>>/WEB-INF/gems/gems/rack-1.2.4/lib/rack/runtime.rb:17) at #<Class:0x6830d77d>.call(<<<OMITTED>>>/WEB-INF/gems/gems/activesupport-3.0.10/lib/active_support/cache/strategy/local_cache.rb:90) at ActionDispatch::Static.call(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/middleware/static.rb:30) at Rails::Application.call(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/application.rb:168) at Rack::Handler::Servlet.call(file:<<<OMITTED>>>/WEB-INF/lib/jruby-rack-1.1.1.jar!/rack/handler/servlet.rb:19) Dec 7, 2011 11:36:21 AM org.apache.catalina.core.ApplicationContext log SEVERE: Application Error org.jruby.exceptions.RaiseException: (NameError) uninitialized constant ActionDispatch::Http::FilterParameters::ParameterFilter at org.jruby.RubyModule.const_missing(org/jruby/RubyModule.java:2590) at Module.const_missing(<<<OMITTED>>>/WEB-INF/gems/gems/rake-0.9.2.2/lib/rake/ext/module.rb:36) at org.jruby.RubyMethod.call(org/jruby/RubyMethod.java:133) at ActionDispatch::Http::FilterParameters.parameter_filter_for(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:57) at ActionDispatch::Http::FilterParameters.parameter_filter(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:49) at ActionDispatch::Request.filtered_query_string(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:64) at org.jruby.RubyString.gsub(org/jruby/RubyString.java:2877) at ActionDispatch::Http::FilterParameters.filtered_query_string(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:63) at ActionDispatch::Http::FilterParameters.filtered_path(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/http/filter_parameters.rb:43) at Rails::Rack::Logger.before_dispatch(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/rack/logger.rb:22) at Rails::Rack::Logger.call(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/rack/logger.rb:12) at Rack::Runtime.call(<<<OMITTED>>>/WEB-INF/gems/gems/rack-1.2.4/lib/rack/runtime.rb:17) at #<Class:0x6830d77d>.call(<<<OMITTED>>>/WEB-INF/gems/gems/activesupport-3.0.10/lib/active_support/cache/strategy/local_cache.rb:90) at ActionDispatch::Static.call(<<<OMITTED>>>/WEB-INF/gems/gems/actionpack-3.0.10/lib/action_dispatch/middleware/static.rb:30) at Rails::Application.call(<<<OMITTED>>>/WEB-INF/gems/gems/railties-3.0.10/lib/rails/application.rb:168) at Rack::Handler::Servlet.call(file:<<<OMITTED>>>/WEB-INF/lib/jruby-rack-1.1.1.jar!/rack/handler/servlet.rb:19) Dec 7, 2011 11:36:21 AM org.apache.catalina.core.ApplicationContext log INFO: Completed 500 Internal Server Error in 7ms {code} We have now confirmed that this never happens over a number of runs with the same code in the MRI interpreter v1.9.*, so we believe it is jruby or warbler related. Because it happens intermittently, and only on the first few requests if at all, it smells like a threading problem. -- This message is automatically generated by JIRA. For more information on JIRA, see: http://www.atlassian.com/software/jira --------------------------------------------------------------------- To unsubscribe from this list, please visit: http://xircles.codehaus.org/manage_email