“ERF12-0944 [ForemanChef::ProxyException]: Unable to communicate with Chef proxy, 500 Internal Server Error”

Support
,
1

Hi all,

I’m trying to connect Foreman to our Chef server but I’m getting the error message “ERF12-0944 [ForemanChef::ProxyException]: Unable to communicate with Chef proxy, 500 Internal Server Error” when trying to create a new host.

Foreman (foreman-1.16.0-1.el7.noarch) is used in the context of Katello (katello-3.5.0-2.el7.noarch).

I have prepared the integration with Chef with the command :

foreman-installer --scenario katello --enable-foreman-plugin-chef --enable-foreman-proxy-plugin-chef --foreman-proxy-plugin-chef-client-name=“company-validator” --foreman-proxy-plugin-chef-private-key="/var/katello_chef/validator.pem" --foreman-proxy-plugin-chef-server-url=“https://fqdn_of_our_Chef_server>
/organizations/our_organization” --foreman-proxy-plugin-chef-ssl-verify=false

Foreman was initially installed (and running fine without “Chef”) with the command :
foreman-installer --scenario katello --katello-proxy-url=http://10.91.34.66 --katello-proxy-port=8080 --enable-foreman-plugin-bootdisk

I am currently blocked and I have no idea what I could have done wrong (*) nor the way I could troubleshoot this issue. The integration with Chef is mandatory where I work because Chef is the config management tool in place.

Needless to say that your help will be much appreciated.

Thanks on forehand.

Best regards.

Philippe

(*) the chances are high that I am missing something because I am quite new in the Foreman / Katello world …

2

With Christmas coming I’m guessing responses may be slow from the @chef plugin people (and that’s definitely not me :slight_smile:) but I can ask for some basic debugging

A 500 error generally means the proxy, or Foreman, did something weird and unexpected, so the first question is always going to be what got logged. You will want to update logging to DEBUG on Foreman and the Proxy (see Debugging) - don’t forget to restart the services after.

Then tail both /var/log/foreman/production.log and /var/log/foreman-proxy/proxy.log and try the host creation again. Copy both the log snippets here (surround with ``` for readability) and we can take a look and see what happened.

3

Hello,

Thank you for helping me in this matter !

Here is the production.log when I hit the “Submit” button for the creation of the new host. Please note that nothing is logged in the proxy.log file.

I am submitting it in two parts because I am limited to 32000 characters in this post.

Part 1:

2017-12-21 09:28:35 91d602a6 [app] [I] Started POST "/hosts" for 10.63.133.184 at 2017-12-21 09:28:35 +0100
2017-12-21 09:28:35 91d602a6 [app] [I] Processing by HostsController#create as */*
2017-12-21 09:28:35 91d602a6 [app] [I]   Parameters: {"utf8"=>"â "authenticity_token"=>"SkgLL68pRdP1CQaveiZVN2EpY242uCAn3Aog3PMa+FPqUwFA0gxWwv1IvGDcQmPITRAwvKcIcJXlQSZDujA1+g==", "host"=>{"run_list"=>{"0"=>{"type"=>"role", "name"=>"default"}}, "override_chef_attributes"=>"true", "name"=>"ls408940.mux.isinfra.net", "organization_id"=>"1", "location_id"=>"2", "hostgroup_id"=>"", "compute_resource_id"=>"", "content_facet_attributes"=>{"lifecycle_environment_id"=>"1", "content_view_id"=>"1", "content_source_id"=>"1", "kickstart_repository_id"=>"6"}, "environment_id"=>"", "puppet_proxy_id"=>"", "puppet_ca_proxy_id"=>"", "chef_proxy_id"=>"1", "chef_environment_id"=>"", "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"00:50:56:88:63:b6", "identifier"=>"eth0", "name"=>"ls408940.mux.isinfra.net", "domain_id"=>"1", "subnet_id"=>"1", "ip"=>"10.91.6.206", "ip6"=>"", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>""}}, "architecture_id"=>"1", "operatingsystem_id"=>"1", "provision_method"=>"build", "build"=>"1", "ptable_id"=>"88", "pxe_loader"=>"None", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "model_id"=>"", "comment"=>"", "overwrite"=>"false"}, "media_selector"=>"synced_content", "bare_metal_capabilities"=>"build"}
2017-12-21 09:28:35 91d602a6 [app] [I] Current user: admin (administrator)
2017-12-21 09:28:36 91d602a6 [foreman-tasks/action] [E] ERF12-0944 [ForemanChef::ProxyException]: Unable to communicate with Chef proxy, 500 Internal Server Error (ForemanChef::ProxyException)
 | /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_chef-0.5.0/app/lib/actions/foreman_chef/host/create.rb:31:in `rescue in plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_chef-0.5.0/app/lib/actions/foreman_chef/host/create.rb:11:in `plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:468:in `block (3 levels) in execute_plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:26:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:26:in `pass'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware.rb:17:in `pass'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware.rb:34:in `plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:22:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:26:in `pass'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware.rb:17:in `pass'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware.rb:34:in `plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:22:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/world.rb:30:in `execute'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:467:in `block (2 levels) in execute_plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan.rb:342:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan.rb:342:in `switch_flow'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:371:in `concurrence'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:466:in `block in execute_plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:426:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:426:in `block in with_error_handling'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:426:in `catch'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:426:in `with_error_handling'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:465:in `execute_plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/action.rb:267:in `execute'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan/steps/plan_step.rb:54:in `block in execute'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan/steps/abstract.rb:155:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan/steps/abstract.rb:155:in `with_meta_calculation'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan/steps/plan_step.rb:53:in `execute'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan.rb:245:in `block (2 levels) in plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan.rb:342:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan.rb:342:in `switch_flow'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan.rb:332:in `with_planning_scope'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan.rb:244:in `block in plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:26:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:26:in `pass'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware.rb:17:in `pass'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware.rb:42:in `plan_phase'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:22:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:26:in `pass'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware.rb:17:in `pass'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/common/transaction.rb:16:in `block in rollback_on_error'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/connection_adapters/abstract/database_statements.rb:211:in `transaction'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/transactions.rb:220:in `transaction'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/transaction_adapters/active_record.rb:5:in `transaction'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/common/transaction.rb:15:in `rollback_on_error'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/common/transaction.rb:5:in `plan_phase'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/stack.rb:22:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/middleware/world.rb:30:in `execute'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/execution_plan.rb:243:in `plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/world.rb:176:in `block in plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/world.rb:174:in `tap'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/dynflow-0.8.30/lib/dynflow/world.rb:174:in `plan'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.10.0/app/models/foreman_tasks/concerns/action_triggering.rb:85:in `plan_action'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.10.0/app/models/foreman_tasks/concerns/action_triggering.rb:75:in `plan_hook_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:432:in `block in make_lambda'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:228:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:228:in `block in halting_and_conditional'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:506:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:506:in `block in call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:506:in `each'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:506:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:92:in `__run_callbacks__'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:778:in `_run_create_callbacks'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/callbacks.rb:306:in `_create_record'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/timestamp.rb:57:in `_create_record'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/persistence.rb:504:in `create_or_update'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/callbacks.rb:302:in `block in create_or_update'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:117:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:117:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:555:in `block (2 levels) in compile'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:498:in `block (2 levels) in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `block (2 levels) in halting'
 | /usr/share/foreman/app/models/concerns/orchestration.rb:47:in `around_save_orchestration'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:432:in `block in make_lambda'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `block in halting'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `block in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:92:in `__run_callbacks__'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:778:in `_run_save_callbacks'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/callbacks.rb:302:in `create_or_update'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/persistence.rb:120:in `save'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/validations.rb:37:in `save'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/attribute_methods/dirty.rb:21:in `save'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/transactions.rb:286:in `block (2 levels) in save'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/transactions.rb:351:in `block in with_transaction_returning_status'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/connection_adapters/abstract/database_statements.rb:213:in `block in transaction'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/connection_adapters/abstract/transaction.rb:184:in `within_new_transaction'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/connection_adapters/abstract/database_statements.rb:213:in `transaction'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/transactions.rb:220:in `transaction'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/transactions.rb:348:in `with_transaction_returning_status'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/transactions.rb:286:in `block in save'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/transactions.rb:301:in `rollback_active_record_state!'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/transactions.rb:285:in `save'
 | /usr/share/foreman/app/models/concerns/foreman/sti.rb:29:in `save_with_type'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.10.0/app/models/foreman_tasks/concerns/action_triggering.rb:25:in `block in save_with_dynflow_task_wrap'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.10.0/app/models/foreman_tasks/concerns/action_triggering.rb:119:in `dynflow_task_wrap'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.10.0/app/models/foreman_tasks/concerns/action_triggering.rb:25:in `save_with_dynflow_task_wrap'
 | /usr/share/foreman/app/controllers/hosts_controller.rb:106:in `create'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/abstract_controller/base.rb:198:in `process_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_controller/metal/rendering.rb:10:in `process_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:117:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:117:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:555:in `block (2 levels) in compile'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:498:in `block (2 levels) in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `block (2 levels) in halting'
 | /usr/share/foreman/app/controllers/concerns/application_shared.rb:15:in `set_timezone'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:432:in `block in make_lambda'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `block in halting'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `block in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:498:in `block (2 levels) in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `block (2 levels) in halting'
 | /usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:432:in `block in make_lambda'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `block in halting'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `block in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:498:in `block (2 levels) in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `block (2 levels) in halting'
 | /usr/share/foreman/app/controllers/concerns/foreman/controller/topbar_sweeper.rb:12:in `set_topbar_sweeper_controller'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:432:in `block in make_lambda'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `block in halting'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `block in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
4

Part 2

 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:498:in `block (2 levels) in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:313:in `block (2 levels) in halting'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/audited-4.4.1/lib/audited/sweeper.rb:14:in `around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:455:in `public_send'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:455:in `block in make_lambda'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:312:in `block in halting'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:497:in `block in around'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:505:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:92:in `__run_callbacks__'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:778:in `_run_process_action_callbacks'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:81:in `run_callbacks'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/abstract_controller/callbacks.rb:19:in `process_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_controller/metal/rescue.rb:29:in `process_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/notifications.rb:164:in `block in instrument'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/notifications.rb:164:in `instrument'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_controller/metal/params_wrapper.rb:250:in `process_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/abstract_controller/base.rb:137:in `process'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionview-4.2.5.1/lib/action_view/rendering.rb:30:in `process'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_controller/metal.rb:196:in `dispatch'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_controller/metal/rack_delegation.rb:13:in `dispatch'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_controller/metal.rb:237:in `block in action'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/routing/route_set.rb:74:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/routing/route_set.rb:74:in `dispatch'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/routing/route_set.rb:43:in `serve'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/journey/router.rb:43:in `block in serve'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/journey/router.rb:30:in `each'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/journey/router.rb:30:in `serve'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/routing/route_set.rb:815:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/static.rb:116:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/static.rb:116:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.4/lib/apipie/static_dispatcher.rb:65:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.4/lib/apipie/extractor/recorder.rb:136:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/static.rb:116:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/static.rb:116:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/static.rb:116:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/static.rb:116:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/static.rb:116:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/static.rb:116:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.4/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/etag.rb:24:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/conditionalget.rb:38:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/head.rb:13:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/params_parser.rb:27:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.5.0.1/lib/katello/params_parser_wrapper.rb:12:in `call'
 | /usr/share/foreman/lib/middleware/catch_json_parse_errors.rb:8:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/flash.rb:260:in `call'
 | /usr/share/foreman/lib/middleware/session_safe_logging.rb:17:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/session/abstract/id.rb:225:in `context'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/session/abstract/id.rb:220:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/cookies.rb:560:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/query_cache.rb:36:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activerecord-4.2.5.1/lib/active_record/connection_adapters/abstract/connection_pool.rb:653:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/callbacks.rb:29:in `block in call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:88:in `__run_callbacks__'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:778:in `_run_call_callbacks'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/callbacks.rb:81:in `run_callbacks'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/remote_ip.rb:78:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/show_exceptions.rb:30:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/railties-4.2.5.1/lib/rails/rack/logger.rb:38:in `call_app'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/railties-4.2.5.1/lib/rails/rack/logger.rb:22:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/sprockets-rails-3.2.0/lib/sprockets/rails/quiet_assets.rb:13:in `call'
 | /usr/share/foreman/lib/middleware/tagged_logging.rb:18:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/request_id.rb:21:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/methodoverride.rb:22:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/runtime.rb:18:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/activesupport-4.2.5.1/lib/active_support/cache/strategy/local_cache_middleware.rb:28:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/actionpack-4.2.5.1/lib/action_dispatch/middleware/static.rb:116:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/sendfile.rb:113:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/secure_headers-3.4.1/lib/secure_headers/middleware.rb:12:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/railties-4.2.5.1/lib/rails/engine.rb:518:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/railties-4.2.5.1/lib/rails/application.rb:165:in `call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/railties-4.2.5.1/lib/rails/railtie.rb:194:in `public_send'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/railties-4.2.5.1/lib/rails/railtie.rb:194:in `method_missing'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/urlmap.rb:66:in `block in call'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/urlmap.rb:50:in `each'
 | /opt/rh/sclo-ror42/root/usr/share/gems/gems/rack-1.6.2/lib/rack/urlmap.rb:50:in `call'
 | /usr/share/passenger/phusion_passenger/rack/thread_handler_extension.rb:74:in `process_request'
 | /usr/share/passenger/phusion_passenger/request_handler/thread_handler.rb:141:in `accept_and_process_next_request'
 | /usr/share/passenger/phusion_passenger/request_handler/thread_handler.rb:109:in `main_loop'
 | /usr/share/passenger/phusion_passenger/request_handler.rb:455: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'
2017-12-21 09:28:36 91d602a6 [app] [I] Redirected to https://uxit450c.mux.isinfra.net/hosts/new
2017-12-21 09:28:36 91d602a6 [app] [I] Completed 302 Found in 732ms (ActiveRecord: 61.6ms)
2017-12-21 09:28:36 b8f9e19b [app] [I] Started GET "/hosts/new" for 10.63.133.184 at 2017-12-21 09:28:36 +0100
2017-12-21 09:28:36 b8f9e19b [app] [I] Processing by HostsController#new as */*
2017-12-21 09:28:36 b8f9e19b [app] [I] Current user: admin (administrator)
2017-12-21 09:28:36 b8f9e19b [app] [I] Deface: [WARNING] No :original defined for 'add_chef_proxy', you should change its definition to include:
 |  :original => 'e19abe3e364f6c120a7314f48c03fcaf71ecf10a'
2017-12-21 09:28:36 b8f9e19b [app] [I] Deface: [WARNING] No :original defined for 'add_chef_tab', you should change its definition to include:
 |  :original => '467507ec73335f3f1830bac92b5115d9d2b39d30'
2017-12-21 09:28:36 b8f9e19b [app] [I] Deface: [WARNING] No :original defined for 'add_chef_tab_pane', you should change its definition to include:
 |  :original => '44f3395a07cf789a5cda197261b1ab1f55032daf'
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered hosts/_progress.html.erb (1.1ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_chef-0.5.0/app/views/foreman_chef/hosts/_chef_tab.html.erb (3.2ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.5.0.1/app/views/overrides/activation_keys/_host_environment_select.html.erb (17.4ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered hosts/puppet/_main_tab_fields.html.erb (14.5ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered hosts/puppet/_puppet_classes_tab.html.erb (1.0ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered nic/_base_form.html.erb (64.5ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered nic/_virtual_form.html.erb (2.9ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered nic/_provider_specific_form.html.erb (1.0ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered nic/manageds/_managed.html.erb (79.3ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered nic/_base_form.html.erb (47.6ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered nic/_virtual_form.html.erb (2.3ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.2ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered nic/manageds/_managed.html.erb (56.1ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered hosts/_interfaces.html.erb (144.1ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered common/os_selection/_architecture.html.erb (2.2ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_bootdisk-10.0.1/app/views/hosts/provision_method/bootdisk/_form.html.erb (1.3ms)
2017-12-21 09:28:36 b8f9e19b [app] [I] Deface: [WARNING] No :original defined for 'hosts_select_media_type', you should change its definition to include:
 |  :original => '3a9540f85d27c5d1bbd3528d086ba0e19dfbc9be'
2017-12-21 09:28:36 b8f9e19b [app] [I] Deface: [WARNING] No :original defined for 'hosts_select_synced_content', you should change its definition to include:
 |  :original => '3a9540f85d27c5d1bbd3528d086ba0e19dfbc9be'
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered common/os_selection/_operatingsystem.html.erb (42.7ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered hosts/provision_method/build/_form.html.erb (90.3ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered hosts/provision_method/image/_form.html.erb (0.5ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered hosts/_operating_system.html.erb (124.2ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered hosts/_unattended.html.erb (127.1ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered puppetclasses/_class_parameters.html.erb (0.0ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered puppetclasses/_classes_parameters.html.erb (13.2ms)
2017-12-21 09:28:36 b8f9e19b [app] [I]   Rendered common_parameters/_inherited_parameters.html.erb (3.2ms)
2017-12-21 09:28:37 b8f9e19b [app] [I]   Rendered common_parameters/_parameter.html.erb (5.1ms)
2017-12-21 09:28:37 b8f9e19b [app] [I]   Rendered common_parameters/_parameters.html.erb (70.3ms)
2017-12-21 09:28:37 b8f9e19b [app] [I]   Rendered hosts/_form.html.erb (655.3ms)
2017-12-21 09:28:37 b8f9e19b [app] [I]   Rendered hosts/new.html.erb within layouts/application (687.9ms)
2017-12-21 09:28:37 b8f9e19b [app] [I]   Rendered layouts/_application_content.html.erb (2.8ms)
2017-12-21 09:28:37 b8f9e19b [app] [I]   Rendered home/_user_dropdown.html.erb (49.3ms)
2017-12-21 09:28:37 b8f9e19b [app] [I] Read fragment views/tabs_and_title_records-4 (0.2ms)
2017-12-21 09:28:37 b8f9e19b [app] [I]   Rendered home/_topbar.html.erb (63.2ms)
2017-12-21 09:28:37 b8f9e19b [app] [I]   Rendered layouts/base.html.erb (73.4ms)
2017-12-21 09:28:37 b8f9e19b [app] [I] Completed 200 OK in 797ms (Views: 742.7ms | ActiveRecord: 39.2ms)
2017-12-21 09:28:37 c9a2bb69 [app] [I] Started GET "/tasks/626b602e-8b4d-4233-916d-9b0453567689" for 10.63.133.184 at 2017-12-21 09:28:37 +0100
2017-12-21 09:28:37 c9a2bb69 [app] [I] Processing by TasksController#show as */*
2017-12-21 09:28:37 c9a2bb69 [app] [I]   Parameters: {"id"=>"626b602e-8b4d-4233-916d-9b0453567689"}
2017-12-21 09:28:37 c9a2bb69 [app] [I] Current user: admin (administrator)
2017-12-21 09:28:37 c9a2bb69 [app] [I]   Rendered tasks/_list.html.erb (1.2ms)
2017-12-21 09:28:37 c9a2bb69 [app] [I] Completed 200 OK in 60ms (Views: 28.4ms | ActiveRecord: 4.7ms)
2017-12-21 09:28:38 c05ca3b6 [app] [I] Started GET "/notification_recipients" for 10.63.133.184 at 2017-12-21 09:28:38 +0100
2017-12-21 09:28:38 c05ca3b6 [app] [I] Processing by NotificationRecipientsController#index as JSON
2017-12-21 09:28:38 c05ca3b6 [app] [I] Current user: admin (administrator)
2017-12-21 09:28:38 c05ca3b6 [app] [I] Completed 200 OK in 4ms (Views: 0.1ms | ActiveRecord: 0.4ms)
5

I’ve added the ``` to your posts just to improve the readability :slight_smile:

Sadly the actual error is beyond me :cry: , so it’ll have to wait on the Chef plugin guys.

6

Hi Greg and all,

I am still very busy trying to understand where the problem could come from.
If I find it I will let you know. I have had a sudden idea yesterday evening but I still have to investigate it.

Do I have to enclose logs between tripple-single-quotes ? I’ve never done that before …
Between single quotes, double quotes, … but never tripple-single-quotes.
I’m too young probably :slight_smile:

Bye for now.

Philippe

7

Sorry to say this it still doesn’t work.

I hope that “Chef plugin guys” will be able to help me fix this.

Kind regards.

Philippe

8

Beside the original problem reported, I am also very surprised to see the address 10.63.133.184 appearing everywhere in the production.log (you can also see it above) … while I have no clue what this address is !!!

My Foreman/Katello server has the address 10.91.6.210

My server can ping 10.63.133.184 but I cannot traceroute it.

Any clue about what this address can be ?

Philippe

9

Please disregard the last post about the IP address : I know what it is now (but the post cannot be deleted anymore).

The original reported problem (ERF12-0944 …) remains …

Philippe

10

Triple back-ticks, and on new lines. This is a Markdown syntax for code blocks, and you’ll see an example in the template when you create a new issue in Support.

I have no idea if it’s related, but I saw there was a new release of the Chef plugin today - might be worth a go :slight_smile:

https://twitter.com/ares_cz/status/944291348395581440

11

Hello,

I have updated to the latest Chef plugin release 0.6 (yum update => nothing to do, and foreman-installer) but the error still exists.

Just to be sure : this is 0.6, right ?

[root@uxit450c yum.repos.d]# yum list installed | grep -i chef
chef.x86_64 12.21.4-1.el7 @products-7/7.3
rubygem-chef-api.noarch 0.6.0-1.el7 @foreman-plugins
rubygem-smart_proxy_chef.noarch 0.2.0-1.el7 @foreman-plugins
tfm-rubygem-foreman_chef.noarch 0.5.0-1.fm1_15.el7 @foreman-plugins

I still need your help to get out of the ERF12-0944 error I’m afraid …

Philippe

12

Looks like the new gem version was released, but RPM packages still haven’t been built for it. It is currently waiting on this PR to be merged:

I expect this is delayed due to the holidays, hopefully it will be merged next week once everyone comes back from their vacations.

1 Like
13

Thank you Tomer.

14

According to Marek in Bug #19978: Chef Plugin: [foreman-tasks/action] [E] ERF12-0944 [ForemanChef::ProxyException]: Unable to communicate with Chef proxy, 500 Internal Server Error (ForemanChef::ProxyException) - Chef - Foreman, the error is solved on 0.6.0.

I’ve recently merged the RPM packaging for 0.6.0, it will shortly be available on http://yum.theforeman.org/plugins/1.16/el7/x86_64/ - let us know if you still see this problem after updating. Remember to restart httpd after installing the plugin update.

1 Like
15

The updated plugin is now available on the repo:
http://yum.theforeman.org/plugins/1.16/el7/x86_64/tfm-rubygem-foreman_chef-0.6.0-1.fm1_16.el7.noarch.rpm
Please let us know if this solves the issue for you.

16

Hummm : not looking good so far.

A “# yum update” has indeed updated the tfm-rubygem-foreman_chef.

The creation of a host still gave the “ERF12-0944” error, but the new host has been “half created” as I can see it in the list of hosts. But when I try to delete it I get the infamous “Executing dynflow action inside a transaction is not a good idea” error : I have never been able to recover from this … and so far the only way out has always been to reinstall the whole Foreman/Katello server from scratch. This is time consuming (I need one day to get everything back in place) and is possible for a Proof Of Concept (what I’m doing now) but is absolutely out of question if we go in production.

I will initiate the full reinstallation and give more feedback tomorrow.

Kind regards.

Philippe

17

Hopefully next week @Marek_Hulan will be back and will be able to look into why the creation is failing.
Perhaps someone from the @tasks team will be able to help you with the dynflow issue so that you won’t have to reinstall to solve it?

18

Hi Tomer, I have already started the full reinstallation.

I will be off next week but back the week after.
If I can get in touch with someone who would be willing to help me (@Marek_Hulan ?) I can follow very closely any recommentation or instruction he would provide and return all the possible log files. I really want to get the integration with Chef working here.

Concerning the dynflow, this error is for me a real nightmare and again if someone can instruct me about how to get rid of it I am more than willing to follow any instruction provided.

Have a nice day.

19

The Foreman/Katello server is now reinstalled from scratch.

It runs:

  • foreman.noarch 1.16.0-1.el7
  • rubygem-chef-api.noarch 0.6.0-1.el7
  • rubygem-smart_proxy_chef.noarch 0.2.0-1.el7
  • tfm-rubygem-foreman_chef.noarch 0.6.0-1.fm1_16.el7

I will wait for the return of @Marek_Hulan before going any further because he will maybe have some information to collect or some debug flag to activate before adding a host and taking the risk to get the error again.

Philippe

20

Hello, not that I’m fully back but one quick suggestion for now. foreman_chef started to use foreman-tasks for orchestration since 0.5.0. Most likely action subject feature provided by foreman-tasks is not compatible with Katello. Could you comment line ::Host::Managed.send :include, ForemanChef::Concerns::HostActionSubject in lib/foreman_chef/engine.rb, restart Foreman and retry? That should disable some orchestration but if you use validate.pem bootstrapping method, that should be fine. If that helps, at least we’d know what part is broken.

1 Like