1664016 – error: Create DHCP Settings for ... task failed with the following error: ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://...:9090/dhcp

Red Hat Satellite engineering is moving the tracking of its product development work on Satellite to Red Hat Jira (issues.redhat.com). If you're a Red Hat customer, please continue to file support cases via the Red Hat customer portal. If you're not, please head to the "Satellite project" in Red Hat Jira and file new tickets here. Individual Bugzilla bugs will be migrated starting at the end of May. If you cannot log in to RH Jira, please consult article #7032570. That failing, please send an e-mail to the RH Jira admins at rh-issues@redhat.com to troubleshoot your issue as a user management inquiry. The email creates a ServiceNow ticket with Red Hat. Individual Bugzilla bugs that are migrated will be moved to status "CLOSED", resolution "MIGRATED", and set with "MigratedToJIRA" in "Keywords". The link to the successor Jira issue will be found under "Links", have a little "two-footprint" icon next to it, and direct you to the "Satellite project" in Red Hat Jira (issue links are of type "https://issues.redhat.com/browse/SAT-XXXX", where "X" is a digit). This same link will be available in a blue banner at the top of the page informing you that that bug has been migrated.

Bug 1664016 - error: Create DHCP Settings for ... task failed with the following error: ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://...:9090/dhcp

Summary: error: Create DHCP Settings for ... task failed with the following error: ERF...

Keywords:
Status:	CLOSED NOTABUG
Alias:	None
Product:	Red Hat Satellite
Classification:	Red Hat
Component:	DHCP & DNS
Sub Component:
Version:	6.5.0
Hardware:	Unspecified
OS:	Unspecified
Priority:	unspecified
Severity:	high
Target Milestone:	Unspecified
Assignee:	Lukas Zapletal
QA Contact:	Jan Hutař
Docs Contact:
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+	depends on / blocked

Reported:	2019-01-07 14:24 UTC by Jan Hutař
Modified:	2019-09-10 14:47 UTC (History)
CC List:	4 users (show)
Fixed In Version:
Doc Type:	If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed:	2019-06-18 11:48:24 UTC
Target Upstream Version:
Embargoed:

Attachments	(Terms of Use)

Description Jan Hutař 2019-01-07 14:24:10 UTC

Description of problem:
When I provision a host and I attempt to re-provision it (clicking "Build") button, I'm getting error message that there is a conflict in the DHCP.


Version-Release number of selected component (if applicable):
satellite-6.5.0-5.beta.el7sat.noarch (snap #10)


How reproducible:
always


Steps to Reproduce:
1. Discover host
2. Provision it and wait till it is not fully installed and booted
3. Make sure it is not in the build mode
4. Hosts -> All hosts -> <host> -> Build
5. Confirm "Warning: This will delete this host and all of its data!" dialogue


Actual results:
It fails with error message:

Failed to enable mac52540066cc1b.nodhcp.local for installation: ["Create DHCP Settings for mac52540066cc1b.nodhcp.local task failed with the following error: ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp", "Failed to perform rollback on Remove DHCP Settings for mac52540066cc1b.nodhcp.local - ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp"]


Expected results:
It should work


Additional info:
==> /var/log/messages <==
foreman-tail output:

Jan  7 15:22:03 satellite smart-proxy: satellite.nodhcp.local - - [07/Jan/2019:15:22:03 CET] "GET /tftp/serverName HTTP/1.1" 200 29
Jan  7 15:22:03 satellite smart-proxy: - -> /tftp/serverName
Jan  7 15:22:03 satellite smart-proxy: satellite.nodhcp.local - - [07/Jan/2019:15:22:03 CET] "GET /dhcp/192.168.11.3/mac/52:54:00:66:cc:1b HTTP/1.1" 404 59
Jan  7 15:22:03 satellite smart-proxy: - -> /dhcp/192.168.11.3/mac/52:54:00:66:cc:1b
Jan  7 15:22:03 satellite smart-proxy: satellite.nodhcp.local - - [07/Jan/2019:15:22:03 CET] "GET /dhcp/192.168.11.3/ip/192.168.11.17 HTTP/1.1" 404 55
Jan  7 15:22:03 satellite smart-proxy: - -> /dhcp/192.168.11.3/ip/192.168.11.17
Jan  7 15:22:03 satellite smart-proxy: satellite.nodhcp.local - - [07/Jan/2019:15:22:03 CET] "GET /dhcp/192.168.11.3/mac/52:54:00:66:cc:1b HTTP/1.1" 404 59
Jan  7 15:22:03 satellite smart-proxy: - -> /dhcp/192.168.11.3/mac/52:54:00:66:cc:1b

==> /var/log/foreman-proxy/proxy.log <==
2019-01-07T15:22:03 cb18c102 [I] Started GET /serverName 
2019-01-07T15:22:03 cb18c102 [I] Finished GET /serverName with 200 (0.38 ms)
2019-01-07T15:22:03 cb18c102 [I] Started GET /192.168.11.3/mac/52:54:00:66:cc:1b 
2019-01-07T15:22:03 cb18c102 [E] No DHCP record for MAC 192.168.11.3/52:54:00:66:cc:1b found
2019-01-07T15:22:03 cb18c102 [I] Finished GET /192.168.11.3/mac/52:54:00:66:cc:1b with 404 (0.89 ms)
2019-01-07T15:22:03 cb18c102 [I] Started GET /192.168.11.3/ip/192.168.11.17 
2019-01-07T15:22:03 cb18c102 [E] No DHCP records for IP 192.168.11.3/192.168.11.17 found
2019-01-07T15:22:03 cb18c102 [I] Finished GET /192.168.11.3/ip/192.168.11.17 with 404 (1.38 ms)
2019-01-07T15:22:03 cb18c102 [I] Started GET /192.168.11.3/mac/52:54:00:66:cc:1b 
2019-01-07T15:22:03 cb18c102 [E] No DHCP record for MAC 192.168.11.3/52:54:00:66:cc:1b found
2019-01-07T15:22:03 cb18c102 [I] Finished GET /192.168.11.3/mac/52:54:00:66:cc:1b with 404 (0.53 ms)

==> /var/log/foreman/production.log <==
2019-01-07T15:22:03 [I|app|] Started PUT "/hosts/mac52540066cc1b.nodhcp.local/setBuild?auth_object=mac52540066cc1b.nodhcp.local&permission=build_hosts" for 192.168.122.1 at 2019-01-07 15:22:03 +0100
2019-01-07T15:22:03 [I|app|653e37c8] Processing by HostsController#setBuild as HTML
2019-01-07T15:22:03 [I|app|653e37c8]   Parameters: {"utf8"=>"✓", "authenticity_token"=>"0ttOmaVVf38vgBxSNRZ04GA3gSnUyDGYZ+GZxef9beL13dnSQmizuTTeReJ2hENqd8ywobdS/wOL6EotlYaQ9A==", "commit"=>"Build", "auth_object"=>"mac52540066cc1b.nodhcp.local", "permission"=>"build_hosts", "id"=>"mac52540066cc1b.nodhcp.local"}
2019-01-07T15:22:03 [I|app|653e37c8] Current user set to admin (admin)
2019-01-07T15:22:03 [I|app|653e37c8] Fetching DHCP reservation mac52540066cc1b.nodhcp.local for mac52540066cc1b.nodhcp.local-52:54:00:66:cc:1b/192.168.11.17

==> /var/log/messages <==
Jan  7 15:22:03 satellite smart-proxy: satellite.nodhcp.local - - [07/Jan/2019:15:22:03 CET] "GET /tftp/serverName HTTP/1.1" 200 29
Jan  7 15:22:03 satellite smart-proxy: - -> /tftp/serverName
Jan  7 15:22:03 satellite smart-proxy: satellite.nodhcp.local - - [07/Jan/2019:15:22:03 CET] "DELETE /dhcp/192.168.11.3/mac/52:54:00:66:cc:1b HTTP/1.1" 200 0
Jan  7 15:22:03 satellite smart-proxy: - -> /dhcp/192.168.11.3/mac/52:54:00:66:cc:1b
Jan  7 15:22:03 satellite smart-proxy: satellite.nodhcp.local - - [07/Jan/2019:15:22:03 CET] "POST /dhcp/192.168.11.3 HTTP/1.1" 409 48
Jan  7 15:22:03 satellite smart-proxy: - -> /dhcp/192.168.11.3
Jan  7 15:22:04 satellite smart-proxy: satellite.nodhcp.local - - [07/Jan/2019:15:22:03 CET] "POST /dhcp/192.168.11.3 HTTP/1.1" 409 48
Jan  7 15:22:04 satellite smart-proxy: - -> /dhcp/192.168.11.3

==> /var/log/httpd/foreman-ssl_access_ssl.log <==
192.168.122.1 - - [07/Jan/2019:15:22:03 +0100] "POST /hosts/mac52540066cc1b.nodhcp.local/setBuild?auth_object=mac52540066cc1b.nodhcp.local&permission=build_hosts HTTP/1.1" 302 132 "https://sat650snap4.example.com/hosts/mac52540066cc1b.nodhcp.local" "Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:64.0) Gecko/20100101 Firefox/64.0"
192.168.122.1 - - [07/Jan/2019:15:22:04 +0100] "GET /hosts/mac52540066cc1b.nodhcp.local HTTP/1.1" 200 6323 "https://sat650snap4.example.com/hosts/mac52540066cc1b.nodhcp.local" "Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:64.0) Gecko/20100101 Firefox/64.0"

==> /var/log/foreman-proxy/proxy.log <==
2019-01-07T15:22:03 cb18c102 [I] Started GET /serverName 
2019-01-07T15:22:03 cb18c102 [I] Finished GET /serverName with 200 (0.3 ms)
2019-01-07T15:22:03 cb18c102 [I] Started DELETE /192.168.11.3/mac/52:54:00:66:cc:1b 
2019-01-07T15:22:03 cb18c102 [I] Finished DELETE /192.168.11.3/mac/52:54:00:66:cc:1b with 200 (0.55 ms)
2019-01-07T15:22:03 cb18c102 [I] Started POST /192.168.11.3 
2019-01-07T15:22:03 cb18c102 [W] Request to create a conflicting DHCP record
2019-01-07T15:22:03 cb18c102 [E] Record 192.168.11.0/192.168.11.17 already exists
2019-01-07T15:22:03 cb18c102 [I] Finished POST /192.168.11.3 with 409 (1.12 ms)
2019-01-07T15:22:04 cb18c102 [I] Started POST /192.168.11.3 
2019-01-07T15:22:04 cb18c102 [W] Request to create a conflicting DHCP record
2019-01-07T15:22:04 cb18c102 [E] Record 192.168.11.0/192.168.11.17 already exists
2019-01-07T15:22:04 cb18c102 [I] Finished POST /192.168.11.3 with 409 (2.58 ms)

==> /var/log/foreman/production.log <==
2019-01-07T15:22:03 [I|app|653e37c8] Delete DHCP reservation mac52540066cc1b.nodhcp.local for mac52540066cc1b.nodhcp.local-52:54:00:66:cc:1b/192.168.11.17
2019-01-07T15:22:03 [I|app|653e37c8] Create DHCP reservation mac52540066cc1b.nodhcp.local for mac52540066cc1b.nodhcp.local-52:54:00:66:cc:1b/192.168.11.17
2019-01-07T15:22:03 [W|app|653e37c8] Create DHCP Settings for mac52540066cc1b.nodhcp.local task failed with the following error: ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp
ProxyAPI::ProxyException: ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp
/usr/share/foreman/lib/proxy_api/dhcp.rb:89:in `rescue in set'
/usr/share/foreman/lib/proxy_api/dhcp.rb:84:in `set'
/usr/share/foreman/lib/net/dhcp/record.rb:43:in `create'
/usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:57:in `block in set_dhcp'
/usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:57:in `each'
/usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:57:in `all?'
/usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:57:in `set_dhcp'
/usr/share/foreman/app/models/concerns/orchestration.rb:222:in `execute'
/usr/share/foreman/app/models/concerns/orchestration.rb:145:in `block in process'
/usr/share/foreman/app/models/concerns/orchestration.rb:137:in `each'
/usr/share/foreman/app/models/concerns/orchestration.rb:137:in `process'
/usr/share/foreman/app/models/concerns/orchestration.rb:44:in `around_save_orchestration'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:136:in `run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:816:in `_run_save_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/callbacks.rb:342:in `create_or_update'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/persistence.rb:275:in `save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/validations.rb:46:in `save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:310:in `block (2 levels) in save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:387:in `block in with_transaction_returning_status'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `block in transaction'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:239:in `block in within_new_transaction'
/opt/rh/rh-ruby25/root/usr/share/ruby/monitor.rb:226:in `mon_synchronize'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:236:in `within_new_transaction'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `transaction'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:212:in `transaction'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:385:in `with_transaction_returning_status'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:310:in `block in save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:325:in `rollback_active_record_state!'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:309:in `save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/suppressor.rb:44:in `save'
/usr/share/foreman/app/models/concerns/foreman/sti.rb:26:in `save'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:27:in `block in save'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:114:in `dynflow_task_wrap'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:27:in `save'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:27:in `block in save'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:127:in `dynflow_task_wrap'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:27:in `save'
/usr/share/foreman/app/models/host/managed.rb:424:in `setBuild'
/usr/share/foreman/app/controllers/hosts_controller.rb:232:in `setBuild'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:194:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/rendering.rb:30:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:42:in `block in process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:109:in `block in run_callbacks'
/usr/share/foreman/app/controllers/concerns/application_shared.rb:15:in `set_timezone'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/app/controllers/concerns/foreman/controller/topbar_sweeper.rb:12:in `set_topbar_sweeper_controller'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/audited-4.7.1/lib/audited/sweeper.rb:14:in `around'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/audited-4.7.1/lib/audited/sweeper.rb:14:in `around'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:136:in `run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:41:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/rescue.rb:22:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:34:in `block in process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `block in instrument'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications/instrumenter.rb:23:in `instrument'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `instrument'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:32:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/params_wrapper.rb:256:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/railties/controller_runtime.rb:24:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:134:in `process'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionview-5.2.1/lib/action_view/rendering.rb:32:in `process'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal.rb:191:in `dispatch'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal.rb:252:in `dispatch'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:52:in `dispatch'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:34:in `serve'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:52:in `block in serve'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `each'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `serve'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:840:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.14/lib/apipie/static_dispatcher.rb:65:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.14/lib/apipie/extractor/recorder.rb:137:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/usr/share/foreman/lib/middleware/telemetry.rb:9:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.14/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
/usr/share/foreman/lib/middleware/catch_json_parse_errors.rb:8:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/tempfile_reaper.rb:15:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/etag.rb:25:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/conditional_get.rb:38:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/head.rb:12:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/http/content_security_policy.rb:18:in `call'
/usr/share/foreman/lib/middleware/logging_context.rb:23:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/session/abstract/id.rb:232:in `context'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/session/abstract/id.rb:226:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/cookies.rb:670:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:98:in `run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:26:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/debug_exceptions.rb:61:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/rack/logger.rb:38:in `call_app'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/rack/logger.rb:28:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/sprockets-rails-3.2.1/lib/sprockets/rails/quiet_assets.rb:13:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/request_id.rb:27:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0.4/lib/katello/prevent_json_parsing.rb:12:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/method_override.rb:22:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/runtime.rb:22:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/executor.rb:14:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/sendfile.rb:111:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/secure_headers-6.0.0/lib/secure_headers/middleware.rb:13:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/engine.rb:524:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `public_send'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `method_missing'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/urlmap.rb:68:in `block in call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/urlmap.rb:53:in `each'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/urlmap.rb:53: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-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
2019-01-07T15:22:03 [W|app|653e37c8] Rolling back due to a problem: [#<Orchestration::Task:0x00007f75d8cf7c90 @name="Create DHCP Settings for mac52540066cc1b.nodhcp.local", @id="dhcp_create_52:54:00:66:cc:1b", @status="failed", @priority=9, @action=[#<Nic::Managed id: 29, mac: "52:54:00:66:cc:1b", ip: "192.168.11.17", type: "Nic::Managed", name: "mac52540066cc1b.nodhcp.local", host_id: 26, subnet_id: 1, domain_id: 2, attrs: {"netmask"=>"255.255.255.0", "mtu"=>1500, "network"=>"192.168.11.0", "speed"=>"100", "duplex"=>"full", "port"=>"MII", "auto_negotiation"=>"true"}, created_at: "2019-01-07 13:41:24", updated_at: "2019-01-07 13:43:38", provider: nil, username: nil, password: nil, virtual: false, link: true, identifier: "ens3", tag: "", attached_to: "", managed: true, mode: "balance-rr", attached_devices: "", bond_options: "", primary: true, provision: true, compute_attributes: {}, execution: true, ip6: nil, subnet6_id: nil>, :set_dhcp], @created=1546870923.774321, @timestamp=2019-01-07 14:22:03 UTC>]
2019-01-07T15:22:03 [I|app|653e37c8] Create DHCP reservation mac52540066cc1b.nodhcp.local for mac52540066cc1b.nodhcp.local-52:54:00:66:cc:1b/192.168.11.17
2019-01-07T15:22:04 [W|app|653e37c8] Failed to perform rollback on Remove DHCP Settings for mac52540066cc1b.nodhcp.local - ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp
ProxyAPI::ProxyException: ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp
/usr/share/foreman/lib/proxy_api/dhcp.rb:89:in `rescue in set'
/usr/share/foreman/lib/proxy_api/dhcp.rb:84:in `set'
/usr/share/foreman/lib/net/dhcp/record.rb:43:in `create'
/usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:57:in `block in set_dhcp'
/usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:57:in `each'
/usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:57:in `all?'
/usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:57:in `set_dhcp'
/usr/share/foreman/app/models/concerns/orchestration.rb:222:in `execute'
/usr/share/foreman/app/models/concerns/orchestration.rb:192:in `block in fail_queue'
/usr/share/foreman/app/models/concerns/orchestration.rb:188:in `reverse_each'
/usr/share/foreman/app/models/concerns/orchestration.rb:188:in `fail_queue'
/usr/share/foreman/app/models/concerns/orchestration.rb:162:in `process'
/usr/share/foreman/app/models/concerns/orchestration.rb:44:in `around_save_orchestration'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:136:in `run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:816:in `_run_save_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/callbacks.rb:342:in `create_or_update'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/persistence.rb:275:in `save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/validations.rb:46:in `save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:310:in `block (2 levels) in save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:387:in `block in with_transaction_returning_status'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `block in transaction'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:239:in `block in within_new_transaction'
/opt/rh/rh-ruby25/root/usr/share/ruby/monitor.rb:226:in `mon_synchronize'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:236:in `within_new_transaction'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `transaction'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:212:in `transaction'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:385:in `with_transaction_returning_status'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:310:in `block in save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:325:in `rollback_active_record_state!'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:309:in `save'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/suppressor.rb:44:in `save'
/usr/share/foreman/app/models/concerns/foreman/sti.rb:26:in `save'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:27:in `block in save'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:114:in `dynflow_task_wrap'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:27:in `save'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:27:in `block in save'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:127:in `dynflow_task_wrap'
/opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.14.4.2/app/models/foreman_tasks/concerns/action_triggering.rb:27:in `save'
/usr/share/foreman/app/models/host/managed.rb:424:in `setBuild'
/usr/share/foreman/app/controllers/hosts_controller.rb:232:in `setBuild'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:194:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/rendering.rb:30:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:42:in `block in process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:109:in `block in run_callbacks'
/usr/share/foreman/app/controllers/concerns/application_shared.rb:15:in `set_timezone'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/app/controllers/concerns/foreman/controller/topbar_sweeper.rb:12:in `set_topbar_sweeper_controller'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/audited-4.7.1/lib/audited/sweeper.rb:14:in `around'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/audited-4.7.1/lib/audited/sweeper.rb:14:in `around'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:136:in `run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:41:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/rescue.rb:22:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:34:in `block in process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `block in instrument'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications/instrumenter.rb:23:in `instrument'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `instrument'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:32:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/params_wrapper.rb:256:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/railties/controller_runtime.rb:24:in `process_action'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:134:in `process'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionview-5.2.1/lib/action_view/rendering.rb:32:in `process'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal.rb:191:in `dispatch'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal.rb:252:in `dispatch'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:52:in `dispatch'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:34:in `serve'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:52:in `block in serve'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `each'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `serve'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:840:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.14/lib/apipie/static_dispatcher.rb:65:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.14/lib/apipie/extractor/recorder.rb:137:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/usr/share/foreman/lib/middleware/telemetry.rb:9:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.14/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
/usr/share/foreman/lib/middleware/catch_json_parse_errors.rb:8:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/tempfile_reaper.rb:15:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/etag.rb:25:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/conditional_get.rb:38:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/head.rb:12:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/http/content_security_policy.rb:18:in `call'
/usr/share/foreman/lib/middleware/logging_context.rb:23:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/session/abstract/id.rb:232:in `context'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/session/abstract/id.rb:226:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/cookies.rb:670:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:98:in `run_callbacks'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:26:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/debug_exceptions.rb:61:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/rack/logger.rb:38:in `call_app'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/rack/logger.rb:28:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/sprockets-rails-3.2.1/lib/sprockets/rails/quiet_assets.rb:13:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/request_id.rb:27:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.10.0.4/lib/katello/prevent_json_parsing.rb:12:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/method_override.rb:22:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/runtime.rb:22:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/executor.rb:14:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/sendfile.rb:111:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/secure_headers-6.0.0/lib/secure_headers/middleware.rb:13:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/engine.rb:524:in `call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `public_send'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `method_missing'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/urlmap.rb:68:in `block in call'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/urlmap.rb:53:in `each'
/opt/theforeman/tfm-ror52/root/usr/share/gems/gems/rack-2.0.5/lib/rack/urlmap.rb:53: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-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
2019-01-07T15:22:04 [I|app|653e37c8] Processed 1 tasks from queue 'Host::Managed Main', completed 0/7
2019-01-07T15:22:04 [E|app|653e37c8] Task 'Remove DHCP Settings for mac52540066cc1b.nodhcp.local' *rollbacked*
2019-01-07T15:22:04 [E|app|653e37c8] Task 'Create DHCP Settings for mac52540066cc1b.nodhcp.local' *failed*
2019-01-07T15:22:04 [E|app|653e37c8] Task 'Deploy TFTP PXELinux config for mac52540066cc1b.nodhcp.local' *canceled*
2019-01-07T15:22:04 [E|app|653e37c8] Task 'Deploy TFTP PXEGrub2 config for mac52540066cc1b.nodhcp.local' *canceled*
2019-01-07T15:22:04 [E|app|653e37c8] Task 'Deploy TFTP PXEGrub config for mac52540066cc1b.nodhcp.local' *canceled*
2019-01-07T15:22:04 [E|app|653e37c8] Task 'Deploy TFTP iPXE config for mac52540066cc1b.nodhcp.local' *canceled*
2019-01-07T15:22:04 [E|app|653e37c8] Task 'Fetch TFTP boot files for mac52540066cc1b.nodhcp.local' *canceled*
2019-01-07T15:22:04 [W|app|653e37c8] Set build failed: #<ActiveModel::Errors:0x00007f75d93a6e10 @base=#<Host::Managed id: 26, name: "mac52540066cc1b.nodhcp.local", last_compile: nil, last_report: nil, updated_at: "2019-01-07 14:11:05", created_at: "2019-01-07 13:41:24", root_pass: "$5$WllK3NFisus8uZX8$NlFpQSaIVoB5xNcAa7qYZHfRwCiqav...", architecture_id: 1, operatingsystem_id: 11, environment_id: 2, ptable_id: 99, medium_id: nil, build: true, comment: nil, disk: nil, installed_at: "2019-01-07 14:11:05", model_id: 1, hostgroup_id: 3, owner_id: 1, owner_type: "User", enabled: true, puppet_ca_proxy_id: 1, managed: true, use_image: nil, image_file: nil, uuid: nil, compute_resource_id: nil, puppet_proxy_id: 1, certname: nil, image_id: nil, organization_id: 1, location_id: 2, type: "Host::Managed", otp: nil, realm_id: nil, compute_profile_id: nil, provision_method: nil, grub_pass: "$5$WllK3NFisus8uZX8$NlFpQSaIVoB5xNcAa7qYZHfRwCiqav...", discovery_rule_id: nil, global_status: 0, lookup_value_matcher: "fqdn=mac52540066cc1b.nodhcp.local", pxe_loader: nil, initiated_at: "2019-01-07 14:22:03", build_errors: nil, openscap_proxy_id: nil>, @messages={:base=>["Create DHCP Settings for mac52540066cc1b.nodhcp.local task failed with the following error: ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp", "Failed to perform rollback on Remove DHCP Settings for mac52540066cc1b.nodhcp.local - ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp"]}, @details={:base=>[{:error=>"Create DHCP Settings for mac52540066cc1b.nodhcp.local task failed with the following error: ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp"}, {:error=>"Failed to perform rollback on Remove DHCP Settings for mac52540066cc1b.nodhcp.local - ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp"}]}>
2019-01-07T15:22:04 [E|app|653e37c8] Failed to save: Create DHCP Settings for mac52540066cc1b.nodhcp.local task failed with the following error: ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp, Failed to perform rollback on Remove DHCP Settings for mac52540066cc1b.nodhcp.local - ERF12-6899 [ProxyAPI::ProxyException]: Unable to set DHCP entry ([RestClient::Conflict]: 409 Conflict) for Capsule https://satellite.nodhcp.local:9090/dhcp
2019-01-07T15:22:04 [I|app|653e37c8] Redirected to https://sat650snap4.example.com/hosts/mac52540066cc1b.nodhcp.local
2019-01-07T15:22:04 [I|app|653e37c8] Completed 302 Found in 689ms (ActiveRecord: 35.5ms)
2019-01-07T15:22:04 [I|app|] Started GET "/hosts/mac52540066cc1b.nodhcp.local" for 192.168.122.1 at 2019-01-07 15:22:04 +0100
2019-01-07T15:22:04 [I|app|c76fa7d2] Processing by HostsController#show as HTML
2019-01-07T15:22:04 [I|app|c76fa7d2]   Parameters: {"id"=>"mac52540066cc1b.nodhcp.local"}
2019-01-07T15:22:04 [I|app|c76fa7d2] Current user set to admin (admin)
2019-01-07T15:22:04 [I|app|c76fa7d2] Deface: [WARNING] No :original defined for 'add_system_link_to_host', you should change its definition to include:
 :original => '68201d5314be8a5aeb05b063bc5cd0afaf58a6c5' 
2019-01-07T15:22:04 [I|app|c76fa7d2] Deface: [WARNING] No :original defined for 'add_compliance_link_to_host', you should change its definition to include:
 :original => '741da897b0cdc7b1177399ac7a6996c5b61adfa2' 
2019-01-07T15:22:04 [I|app|c76fa7d2]   Rendering hosts/show.html.erb within layouts/application
2019-01-07T15:22:04 [I|app|c76fa7d2]   Rendered hosts/show.html.erb within layouts/application (75.0ms)
2019-01-07T15:22:04 [I|app|c76fa7d2]   Rendered layouts/_application_content.html.erb (1.0ms)
2019-01-07T15:22:04 [I|app|c76fa7d2] Deface: [WARNING] No :original defined for 'theme_resources', you should change its definition to include:
 :original => '6c658d16f641b3b4c3e0a17b89fd7fec3a76b54a' 
2019-01-07T15:22:04 [I|app|c76fa7d2]   Rendering layouts/base.html.erb
2019-01-07T15:22:04 [I|app|c76fa7d2]   Rendered layouts/base.html.erb (31.1ms)
2019-01-07T15:22:04 [I|app|c76fa7d2] Completed 200 OK in 163ms (Views: 100.9ms | ActiveRecord: 21.5ms)

==> /var/log/httpd/foreman-ssl_access_ssl.log <==
192.168.122.1 - - [07/Jan/2019:15:22:04 +0100] "GET /notification_recipients HTTP/1.1" 200 229 "https://sat650snap4.example.com/hosts/mac52540066cc1b.nodhcp.local" "Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:64.0) Gecko/20100101 Firefox/64.0"
192.168.122.1 - - [07/Jan/2019:15:22:04 +0100] "GET /hosts/mac52540066cc1b.nodhcp.local/overview HTTP/1.1" 200 605 "https://sat650snap4.example.com/hosts/mac52540066cc1b.nodhcp.local" "Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:64.0) Gecko/20100101 Firefox/64.0"
192.168.122.1 - - [07/Jan/2019:15:22:04 +0100] "GET /hosts/mac52540066cc1b.nodhcp.local/templates HTTP/1.1" 200 623 "https://sat650snap4.example.com/hosts/mac52540066cc1b.nodhcp.local" "Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:64.0) Gecko/20100101 Firefox/64.0"
192.168.122.1 - - [07/Jan/2019:15:22:04 +0100] "GET /hosts/mac52540066cc1b.nodhcp.local/nics HTTP/1.1" 200 379 "https://sat650snap4.example.com/hosts/mac52540066cc1b.nodhcp.local" "Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:64.0) Gecko/20100101 Firefox/64.0"
192.168.122.1 - - [07/Jan/2019:15:22:04 +0100] "GET /hosts/mac52540066cc1b.nodhcp.local/runtime?range=7 HTTP/1.1" 200 200 "https://sat650snap4.example.com/hosts/mac52540066cc1b.nodhcp.local" "Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:64.0) Gecko/20100101 Firefox/64.0"
192.168.122.1 - - [07/Jan/2019:15:22:04 +0100] "GET /hosts/mac52540066cc1b.nodhcp.local/resources?range=7 HTTP/1.1" 200 240 "https://sat650snap4.example.com/hosts/mac52540066cc1b.nodhcp.local" "Mozilla/5.0 (X11; Fedora; Linux x86_64; rv:64.0) Gecko/20100101 Firefox/64.0"

==> /var/log/foreman/production.log <==
2019-01-07T15:22:04 [I|app|] Started GET "/notification_recipients" for 192.168.122.1 at 2019-01-07 15:22:04 +0100
2019-01-07T15:22:04 [I|app|b027bf3b] Processing by NotificationRecipientsController#index as JSON
2019-01-07T15:22:04 [I|app|b027bf3b] Current user set to admin (admin)
2019-01-07T15:22:04 [I|app|b027bf3b] Completed 200 OK in 8ms (Views: 0.2ms | ActiveRecord: 1.3ms)
2019-01-07T15:22:04 [I|app|] Started GET "/hosts/mac52540066cc1b.nodhcp.local/overview" for 192.168.122.1 at 2019-01-07 15:22:04 +0100
2019-01-07T15:22:04 [I|app|d0380d4d] Processing by HostsController#overview as HTML
2019-01-07T15:22:04 [I|app|d0380d4d]   Parameters: {"id"=>"mac52540066cc1b.nodhcp.local"}
2019-01-07T15:22:04 [I|app|d0380d4d] Current user set to admin (admin)
2019-01-07T15:22:04 [I|app|d0380d4d]   Rendered hosts/_overview.html.erb (43.6ms)
2019-01-07T15:22:04 [I|app|d0380d4d] Completed 200 OK in 62ms (Views: 39.8ms | ActiveRecord: 10.5ms)
2019-01-07T15:22:04 [I|app|] Started GET "/hosts/mac52540066cc1b.nodhcp.local/templates" for 192.168.122.1 at 2019-01-07 15:22:04 +0100
2019-01-07T15:22:04 [I|app|e3cb79b2] Processing by HostsController#templates as HTML
2019-01-07T15:22:04 [I|app|e3cb79b2]   Parameters: {"id"=>"mac52540066cc1b.nodhcp.local"}
2019-01-07T15:22:04 [I|app|e3cb79b2] Current user set to admin (admin)
2019-01-07T15:22:05 [I|app|e3cb79b2]   Rendered hosts/_templates.html.erb (12.9ms)
2019-01-07T15:22:05 [I|app|e3cb79b2] Completed 200 OK in 163ms (Views: 11.6ms | ActiveRecord: 52.2ms)
2019-01-07T15:22:05 [I|app|] Started GET "/hosts/mac52540066cc1b.nodhcp.local/nics" for 192.168.122.1 at 2019-01-07 15:22:05 +0100
2019-01-07T15:22:05 [I|app|bcc81795] Processing by HostsController#nics as HTML
2019-01-07T15:22:05 [I|app|bcc81795]   Parameters: {"id"=>"mac52540066cc1b.nodhcp.local"}
2019-01-07T15:22:05 [I|app|bcc81795] Current user set to admin (admin)
2019-01-07T15:22:05 [I|app|bcc81795]   Rendered hosts/_nics.html.erb (15.8ms)
2019-01-07T15:22:05 [I|app|bcc81795] Completed 200 OK in 31ms (Views: 16.2ms | ActiveRecord: 6.1ms)
2019-01-07T15:22:05 [I|app|] Started GET "/hosts/mac52540066cc1b.nodhcp.local/runtime?range=7" for 192.168.122.1 at 2019-01-07 15:22:05 +0100
2019-01-07T15:22:05 [I|app|72496882] Processing by HostsController#runtime as HTML
2019-01-07T15:22:05 [I|app|72496882]   Parameters: {"range"=>"7", "id"=>"mac52540066cc1b.nodhcp.local"}
2019-01-07T15:22:05 [I|app|72496882] Current user set to admin (admin)
2019-01-07T15:22:05 [I|app|72496882]   Rendered hosts/_runtime.html.erb (3.2ms)
2019-01-07T15:22:05 [I|app|72496882] Completed 200 OK in 16ms (Views: 6.3ms | ActiveRecord: 2.5ms)
2019-01-07T15:22:05 [I|app|] Started GET "/hosts/mac52540066cc1b.nodhcp.local/resources?range=7" for 192.168.122.1 at 2019-01-07 15:22:05 +0100
2019-01-07T15:22:05 [I|app|7213902e] Processing by HostsController#resources as HTML
2019-01-07T15:22:05 [I|app|7213902e]   Parameters: {"range"=>"7", "id"=>"mac52540066cc1b.nodhcp.local"}
2019-01-07T15:22:05 [I|app|7213902e] Current user set to admin (admin)
2019-01-07T15:22:05 [I|app|7213902e]   Rendered hosts/_resources.html.erb (4.3ms)
2019-01-07T15:22:05 [I|app|7213902e] Completed 200 OK in 19ms (Views: 7.5ms | ActiveRecord: 2.9ms)

Comment 3 Jan Hutař 2019-01-07 14:27:25 UTC

Workaround is to remove "host" record from leases file and restart dhcpd:

# vim /var/lib/dhcpd/dhcpd.leases
# The format of this file is documented in the dhcpd.leases(5) manual page.
# This lease file was written by isc-dhcp-4.2.5

server-duid "\000\001\000\001#\306\030\307RT\000\204/+";

host mac52540066cc1b.nodhcp.local {
  dynamic;
  hardware ethernet 52:54:00:66:cc:1b;
  fixed-address 192.168.11.17;
        supersede server.filename = "pxelinux.0";
        supersede server.next-server = c0:a8:0b:03;
        supersede host-name = "mac52540066cc1b.nodhcp.local";
}
# systemctl restart dhcpd

Comment 4 Lukas Zapletal 2019-01-09 09:49:02 UTC

Jan, when you have an existing host and click on Build button, Satellite is not supposed to do anything with DHCP records, it should only orchestrate TFTP files deployment leaving DHCP untouched.

However there is a chance when you change something relevant that it will need to remove and add DHCP entry back - when you change PXELoader, hostname, MAC, IP or next-server option.

Can you compare the host record in dhcpd.leases before and after the run (when you delete it to let is pass)? Is there any change? Different MAC addres, IP address, filename or next-server or host-name option? That would allow us to identify what is causing this.

Comment 5 Lukas Zapletal 2019-05-23 07:57:08 UTC

Jan, have you changed Subnet when editing the host? I just stumbled upon: https://bugzilla.redhat.com/show_bug.cgi?id=1713214 again and it seem this is again a problem.

Comment 6 Jan Hutař 2019-05-23 11:47:29 UTC

(In reply to Lukas Zapletal from comment #5)
> Jan, have you changed Subnet when editing the host? I just stumbled upon:
> https://bugzilla.redhat.com/show_bug.cgi?id=1713214 again and it seem this
> is again a problem.

Hello. Yes, it is very much possible. I was experimenting a lot. Should I close this one then?

Note You need to log in before you can comment on or make changes to this bug.