Project

General

Profile

Bug #13997 » production.log

Joe Campos, 03/02/2016 07:52 AM

 
| Started GET "/" for 10.9.80.122 at 2016-03-02 07:43:36 -0500
2016-03-02 07:43:36 [app] [I] Processing by DashboardController#index as HTML
2016-03-02 07:43:36 [app] [D] Setting current user thread-local variable to admin
2016-03-02 07:43:36 [app] [I] Session for Admin User is expired.
2016-03-02 07:43:36 [app] [I] Redirected to https://10.245.208.113/users/login
2016-03-02 07:43:36 [app] [I] Filter chain halted as :session_expiry rendered or redirected
2016-03-02 07:43:36 [app] [I] Completed 302 Found in 7ms (ActiveRecord: 1.1ms)
2016-03-02 07:43:36 [app] [I]
|
| Started GET "/users/login" for 10.9.80.122 at 2016-03-02 07:43:36 -0500
2016-03-02 07:43:36 [app] [I] Processing by UsersController#login as HTML
2016-03-02 07:43:36 [app] [D] Setting current user thread-local variable to nil
2016-03-02 07:43:36 [app] [I] Rendered users/login.html.erb within layouts/login (3.4ms)
2016-03-02 07:43:36 [app] [I] Rendered layouts/base.html.erb (1.5ms)
2016-03-02 07:43:36 [app] [I] Completed 200 OK in 18ms (Views: 14.4ms | ActiveRecord: 0.0ms)
2016-03-02 07:43:55 [app] [I]
|
| Started POST "/users/login" for 10.9.80.122 at 2016-03-02 07:43:55 -0500
2016-03-02 07:43:55 [app] [I] Processing by UsersController#login as HTML
2016-03-02 07:43:55 [app] [I] Parameters: {"utf8"=>"✓", "authenticity_token"=>"T8J1Mq8KyjXfPNSORTJB/cAz9ffCMeGHjMWAZMjvvGc=", "login"=>{"login"=>"admin", "password"=>"[FILTERED]"}, "commit"=>"Login"}
2016-03-02 07:43:55 [app] [D] Setting current user thread-local variable to nil
2016-03-02 07:43:55 [app] [D] Setting current user thread-local variable to foreman_admin
2016-03-02 07:43:55 [app] [D] Setting current user thread-local variable to nil
2016-03-02 07:43:55 [app] [D] Setting current user thread-local variable to foreman_admin
2016-03-02 07:43:55 [app] [I] Expire fragment views/tabs_and_title_records-3 (0.4ms)
2016-03-02 07:43:55 [app] [D] Setting current user thread-local variable to nil
2016-03-02 07:43:55 [app] [D] Setting current user thread-local variable to admin
2016-03-02 07:43:55 [app] [I] Expire fragment views/tabs_and_title_records-3 (0.1ms)
2016-03-02 07:43:55 [app] [I] Redirected to https://10.245.208.113/
2016-03-02 07:43:55 [app] [I] Completed 302 Found in 24ms (ActiveRecord: 4.9ms)
2016-03-02 07:43:55 [app] [I]
|
| Started GET "/" for 10.9.80.122 at 2016-03-02 07:43:55 -0500
2016-03-02 07:43:55 [app] [I] Processing by DashboardController#index as HTML
2016-03-02 07:43:55 [app] [D] Setting current user thread-local variable to admin
2016-03-02 07:43:55 [app] [I] Rendered dashboard/_status_widget.html.erb (3.3ms)
2016-03-02 07:43:55 [app] [I] Rendered dashboard/_status_chart_widget.html.erb (0.9ms)
2016-03-02 07:43:55 [app] [I] Rendered dashboard/_reports_widget.html.erb (7.1ms)
2016-03-02 07:43:55 [app] [I] Rendered dashboard/_distribution_widget.html.erb (19.0ms)
2016-03-02 07:43:55 [app] [I] Rendered dashboard/index.html.erb within layouts/application (38.0ms)
2016-03-02 07:43:55 [app] [I] Rendered common/_searchbar.html.erb (3.7ms)
2016-03-02 07:43:55 [app] [I] Rendered home/_user_dropdown.html.erb (1.4ms)
2016-03-02 07:43:55 [app] [I] Read fragment views/tabs_and_title_records-3 (0.1ms)
2016-03-02 07:43:55 [app] [I] Rendered home/_org_switcher.html.erb (0.5ms)
2016-03-02 07:43:55 [app] [I] Rendered home/_submenu.html.erb (5.0ms)
2016-03-02 07:43:55 [app] [I] Rendered home/_submenu.html.erb (1.8ms)
2016-03-02 07:43:55 [app] [I] Rendered home/_submenu.html.erb (3.8ms)
2016-03-02 07:43:55 [app] [I] Rendered home/_submenu.html.erb (2.9ms)
2016-03-02 07:43:55 [app] [I] Rendered home/_submenu.html.erb (3.2ms)
2016-03-02 07:43:55 [app] [I] Rendered home/_submenu.html.erb (2.6ms)
2016-03-02 07:43:55 [app] [I] Write fragment views/tabs_and_title_records-3 (0.9ms)
2016-03-02 07:43:55 [app] [I] Rendered home/_topbar.html.erb (32.3ms)
2016-03-02 07:43:55 [app] [I] Rendered layouts/base.html.erb (34.2ms)
2016-03-02 07:43:55 [app] [I] Completed 200 OK in 111ms (Views: 71.0ms | ActiveRecord: 12.0ms)
2016-03-02 07:44:12 [app] [I]
|
| Started GET "/users" for 10.9.80.122 at 2016-03-02 07:44:12 -0500
2016-03-02 07:44:12 [app] [I] Processing by UsersController#index as HTML
2016-03-02 07:44:12 [app] [D] Setting current user thread-local variable to admin
2016-03-02 07:44:12 [app] [I] Rendered users/index.html.erb within layouts/application (12.1ms)
2016-03-02 07:44:12 [app] [I] Rendered common/_searchbar.html.erb (2.7ms)
2016-03-02 07:44:12 [app] [I] Rendered home/_user_dropdown.html.erb (0.9ms)
2016-03-02 07:44:12 [app] [I] Read fragment views/tabs_and_title_records-3 (0.1ms)
2016-03-02 07:44:12 [app] [I] Rendered home/_topbar.html.erb (1.8ms)
2016-03-02 07:44:12 [app] [I] Rendered layouts/base.html.erb (2.9ms)
2016-03-02 07:44:12 [app] [I] Completed 200 OK in 28ms (Views: 17.7ms | ActiveRecord: 3.0ms)
2016-03-02 07:44:12 [app] [I]
|
| Started GET "/users" for 10.9.80.122 at 2016-03-02 07:44:12 -0500
2016-03-02 07:44:12 [app] [I] Processing by UsersController#index as HTML
2016-03-02 07:44:12 [app] [D] Setting current user thread-local variable to admin
2016-03-02 07:44:12 [app] [I] Rendered users/index.html.erb within layouts/application (10.6ms)
2016-03-02 07:44:12 [app] [I] Rendered common/_searchbar.html.erb (3.2ms)
2016-03-02 07:44:12 [app] [I] Rendered home/_user_dropdown.html.erb (0.9ms)
2016-03-02 07:44:12 [app] [I] Read fragment views/tabs_and_title_records-3 (0.1ms)
2016-03-02 07:44:12 [app] [I] Rendered home/_topbar.html.erb (1.9ms)
2016-03-02 07:44:12 [app] [I] Rendered layouts/base.html.erb (3.1ms)
2016-03-02 07:44:12 [app] [I] Completed 200 OK in 25ms (Views: 17.4ms | ActiveRecord: 2.2ms)
2016-03-02 07:44:17 [app] [I]
|
| Started GET "/users/new" for 10.9.80.122 at 2016-03-02 07:44:17 -0500
2016-03-02 07:44:17 [app] [I] Processing by UsersController#new as */*
2016-03-02 07:44:17 [app] [D] Setting current user thread-local variable to admin
2016-03-02 07:44:17 [app] [I] Rendered taxonomies/_loc_org_tabs.html.erb (0.2ms)
2016-03-02 07:44:17 [app] [I] Rendered users/_form.html.erb (24.9ms)
2016-03-02 07:44:17 [app] [I] Rendered users/new.html.erb (25.6ms)
2016-03-02 07:44:17 [app] [I] Completed 200 OK in 32ms (Views: 24.3ms | ActiveRecord: 2.9ms)
2016-03-02 07:50:06 [app] [I]
|
| Started POST "/users" for 10.9.80.122 at 2016-03-02 07:50:06 -0500
2016-03-02 07:50:06 [app] [I] Processing by UsersController#create as */*
2016-03-02 07:50:06 [app] [D] Setting current user thread-local variable to admin
2016-03-02 07:50:06 [app] [W] Action failed
| NoMethodError: undefined method `delete' for nil:NilClass
| /usr/share/foreman/app/controllers/concerns/foreman/controller/users_mixin.rb:16:in `set_admin_on_creation'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:631:in `block (4 levels) in _run__3005759620062086040__process_action__1994271551582463773__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_9110'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:326:in `around'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:310:in `_callback_around_3690'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_9110'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:626:in `block (3 levels) in _run__3005759620062086040__process_action__1994271551582463773__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_9109'
| /usr/share/foreman/app/controllers/concerns/application_shared.rb:13:in `set_timezone'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_9109'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:625:in `block (2 levels) in _run__3005759620062086040__process_action__1994271551582463773__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_9108'
| /usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_9108'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:448:in `block in _run__3005759620062086040__process_action__1994271551582463773__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_9107'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:326:in `around'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:310:in `_callback_around_13'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_9107'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:414:in `_run__3005759620062086040__process_action__1994271551582463773__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
| /opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
| /opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'
| /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.2.6/lib/apipie/static_dispatcher.rb:65:in `call'
| /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.2.6/lib/apipie/extractor/recorder.rb:97:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'
| /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.2.6/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
| /usr/share/foreman/lib/middleware/catch_json_parse_errors.rb:9:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/query_cache.rb:64:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__4456987578337289969__call__2374493030684885429__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in `call_app'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/request_id.rb:22:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/cache/strategy/local_cache.rb:72:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/application.rb:223:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/builder.rb:134:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/urlmap.rb:64:in `block in call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/urlmap.rb:49:in `each'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/urlmap.rb:49:in `call'
| /usr/share/gems/gems/passenger-4.0.18/lib/phusion_passenger/rack/thread_handler_extension.rb:77:in `process_request'
| /usr/share/gems/gems/passenger-4.0.18/lib/phusion_passenger/request_handler/thread_handler.rb:140:in `accept_and_process_next_request'
| /usr/share/gems/gems/passenger-4.0.18/lib/phusion_passenger/request_handler/thread_handler.rb:108:in `main_loop'
| /usr/share/gems/gems/passenger-4.0.18/lib/phusion_passenger/request_handler.rb:441:in `block (3 levels) in start_threads'
| /opt/theforeman/tfm/root/usr/share/gems/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:in `call'
| /opt/theforeman/tfm/root/usr/share/gems/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:in `block in create_with_logging_context'
2016-03-02 07:50:06 [app] [I] Rendered common/500.html.erb (2.2ms)
2016-03-02 07:50:06 [app] [I] Completed 500 Internal Server Error in 8ms (Views: 2.9ms | ActiveRecord: 0.6ms)
(1-1/2)