Bug #19272: proxy fails to delete existing DNS entry - Foreman

Actions

Copy link

Bug #19272

open

proxy fails to delete existing DNS entry

Added by Jeff Sparrow about 7 years ago. Updated about 7 years ago.

Status:

New

Priority:

Normal

Assignee:

Category:

DNS

Target version:

Difficulty:

Triaged:

Bugzilla link:

Pull request:

Fixed in Releases:

Found in Releases:

Red Hat JIRA:

Description

I am attempting to set an already existing IPMI to managed in foreman. We then wanted to have foreman handle DHCP and DNS entries. Typically at this point (at least for hosts themselves) we would get something back about "existing records found, would you like to override?", followed by a big red button. In this scenario Foreman just comes back failing with:

Unable to save · Create DNS record for virtserv1-idrac.lab.beer.town task failed with the following error: ERF12-2357 [ProxyAPI::ProxyException]: Unable to set DNS entry ([RestClient::Conflict]: 409 Conflict) for proxy https://foreman-deploy.lab.beer.town:8443/dns

looking at both production log and proxy logs, it seems like foreman doesnt even ask or attempt to delete/overwrite it:

production.log

2017-04-13 13:08:40 [app] [I] Started GET "/hosts/virtserv1.lab.beer.town/edit" for 100.91.160.33 at 2017-04-13 13:08:40 -0500

2017-04-13 13:08:40 [app] [I] Processing by HostsController#edit as HTML
2017-04-13 13:08:40 [app] [I]   Parameters: {"id"=>"virtserv1.lab.beer.town"}
2017-04-13 13:08:40 [app] [D] Setting current user thread-local variable to pbussler
2017-04-13 13:08:40 [app] [I]   Rendered hosts/_progress.html.erb (0.4ms)
2017-04-13 13:08:40 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:08:40 [app] [D] Setting current location thread-local variable to none

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (26.7ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (14.7ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.6ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (11.6ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.6ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.2ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (13.0ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (16.9ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.4ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.2ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (13.1ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.5ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.5ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (13.1ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.3ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (13.4ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.4ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (14.9ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (14.7ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.7ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (11.7ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (11.0ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (11.3ms)

2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.0ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (11.8ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (11.7ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (11.7ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.1ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_group.html.erb (12.6ms)
2017-04-13 13:08:40 [app] [I]   Rendered config_groups/_config_groups_selection.html.erb (421.7ms)
2017-04-13 13:08:40 [app] [I]   Rendered puppetclasses/_selectedClasses.html.erb (0.0ms)
2017-04-13 13:08:40 [app] [I]   Rendered puppetclasses/_classes_in_groups.html.erb (0.0ms)
2017-04-13 13:08:41 [app] [I]   Rendered puppetclasses/_classes.html.erb (120.7ms)
2017-04-13 13:08:41 [app] [I]   Rendered puppetclasses/_class_selection.html.erb (597.1ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_base_form.html.erb (13.6ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_virtual_form.html.erb (1.4ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/manageds/_managed.html.erb (17.0ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_base_form.html.erb (11.4ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_virtual_form.html.erb (1.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.1ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/manageds/_managed.html.erb (14.5ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_base_form.html.erb (12.4ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_virtual_form.html.erb (1.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.2ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/manageds/_managed.html.erb (15.4ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_base_form.html.erb (10.5ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_virtual_form.html.erb (1.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.1ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/manageds/_managed.html.erb (13.4ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_base_form.html.erb (11.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.1ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/bmcs/_bmc.html.erb (14.1ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_base_form.html.erb (11.4ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_virtual_form.html.erb (1.4ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.2ms)
2017-04-13 13:08:41 [app] [I]   Rendered nic/manageds/_managed.html.erb (14.8ms)
2017-04-13 13:08:41 [app] [I]   Rendered hosts/_interfaces.html.erb (93.1ms)
2017-04-13 13:08:41 [app] [I]   Rendered common/os_selection/_architecture.html.erb (3.9ms)
2017-04-13 13:08:41 [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_bootdisk-7.0.1/app/views/hosts/provision_method/bootdisk/_form.html.erb (0.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered common/os_selection/_operatingsystem.html.erb (7.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered hosts/provision_method/build/_form.html.erb (8.2ms)
2017-04-13 13:08:41 [app] [I]   Rendered hosts/provision_method/image/_form.html.erb (0.2ms)
2017-04-13 13:08:41 [app] [I]   Rendered hosts/_operating_system.html.erb (17.2ms)
2017-04-13 13:08:41 [app] [I]   Rendered hosts/_unattended.html.erb (17.6ms)
2017-04-13 13:08:41 [app] [I]   Rendered puppetclasses/_class_parameters.html.erb (78.2ms)
2017-04-13 13:08:41 [app] [I]   Rendered puppetclasses/_classes_parameters.html.erb (88.2ms)
2017-04-13 13:08:41 [app] [I]   Rendered common_parameters/_inherited_parameters.html.erb (14.2ms)
2017-04-13 13:08:41 [app] [I]   Rendered common_parameters/_parameter.html.erb (2.0ms)
2017-04-13 13:08:41 [app] [I]   Rendered common_parameters/_parameters.html.erb (3.7ms)
2017-04-13 13:08:41 [app] [D] Setting current location thread-local variable to none
2017-04-13 13:08:41 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:08:41 [app] [I]   Rendered hosts/_form.html.erb (1199.5ms)
2017-04-13 13:08:41 [app] [I]   Rendered hosts/edit.html.erb within layouts/application (1203.7ms)
2017-04-13 13:08:41 [app] [I]   Rendered layouts/_application_content.html.erb (0.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered home/_user_dropdown.html.erb (1.1ms)
2017-04-13 13:08:41 [app] [I] Read fragment views/tabs_and_title_records-66 (0.2ms)
2017-04-13 13:08:41 [app] [I]   Rendered home/_topbar.html.erb (2.0ms)
2017-04-13 13:08:41 [app] [I]   Rendered layouts/base.html.erb (2.9ms)
2017-04-13 13:08:41 [app] [I] Completed 200 OK in 1223ms (Views: 1181.6ms | ActiveRecord: 28.7ms)
2017-04-13 13:08:41 [app] [I] Started GET "/hosts/virtserv1.lab.beer.town/edit" for 100.91.160.33 at 2017-04-13 13:08:41 -0500
2017-04-13 13:08:41 [app] [I] Processing by HostsController#edit as HTML
2017-04-13 13:08:41 [app] [I]   Parameters: {"id"=>"virtserv1.lab.beer.town"}
2017-04-13 13:08:41 [app] [D] Setting current user thread-local variable to pbussler
2017-04-13 13:08:41 [app] [I]   Rendered hosts/_progress.html.erb (0.3ms)
2017-04-13 13:08:41 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:08:41 [app] [D] Setting current location thread-local variable to none
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (17.9ms)
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (12.4ms)
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (10.4ms)
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (10.0ms)
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (9.8ms)
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (9.8ms)
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (10.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (10.3ms)
2017-04-13 13:08:41 [app] [I]   Rendered config_groups/_config_group.html.erb (9.8ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.8ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.7ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.5ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.7ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.5ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.4ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.3ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.3ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (10.1ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered config_groups/_config_groups_selection.html.erb (324.7ms)
2017-04-13 13:08:42 [app] [I]   Rendered puppetclasses/_selectedClasses.html.erb (0.0ms)
2017-04-13 13:08:42 [app] [I]   Rendered puppetclasses/_classes_in_groups.html.erb (0.0ms)
2017-04-13 13:08:42 [app] [I]   Rendered puppetclasses/_classes.html.erb (100.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered puppetclasses/_class_selection.html.erb (466.3ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_base_form.html.erb (14.0ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_virtual_form.html.erb (1.2ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.2ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/manageds/_managed.html.erb (17.1ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_base_form.html.erb (9.8ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_virtual_form.html.erb (1.1ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.1ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/manageds/_managed.html.erb (12.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_base_form.html.erb (16.8ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_virtual_form.html.erb (23.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.2ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/manageds/_managed.html.erb (42.4ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_base_form.html.erb (10.1ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_virtual_form.html.erb (1.1ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.1ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/manageds/_managed.html.erb (12.9ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_base_form.html.erb (10.0ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.3ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/bmcs/_bmc.html.erb (12.7ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_base_form.html.erb (10.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_virtual_form.html.erb (1.2ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.2ms)
2017-04-13 13:08:42 [app] [I]   Rendered nic/manageds/_managed.html.erb (13.5ms)
2017-04-13 13:08:42 [app] [I]   Rendered hosts/_interfaces.html.erb (115.2ms)
2017-04-13 13:08:42 [app] [I]   Rendered common/os_selection/_architecture.html.erb (3.5ms)
2017-04-13 13:08:42 [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_bootdisk-7.0.1/app/views/hosts/provision_method/bootdisk/_form.html.erb (0.5ms)
2017-04-13 13:08:42 [app] [I]   Rendered common/os_selection/_operatingsystem.html.erb (7.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered hosts/provision_method/build/_form.html.erb (8.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered hosts/provision_method/image/_form.html.erb (0.2ms)
2017-04-13 13:08:42 [app] [I]   Rendered hosts/_operating_system.html.erb (16.9ms)
2017-04-13 13:08:42 [app] [I]   Rendered hosts/_unattended.html.erb (17.2ms)
2017-04-13 13:08:42 [app] [I]   Rendered puppetclasses/_class_parameters.html.erb (69.1ms)
2017-04-13 13:08:42 [app] [I]   Rendered puppetclasses/_classes_parameters.html.erb (78.3ms)
2017-04-13 13:08:42 [app] [I]   Rendered common_parameters/_inherited_parameters.html.erb (12.7ms)
2017-04-13 13:08:42 [app] [I]   Rendered common_parameters/_parameter.html.erb (1.9ms)
2017-04-13 13:08:42 [app] [I]   Rendered common_parameters/_parameters.html.erb (3.8ms)
2017-04-13 13:08:42 [app] [D] Setting current location thread-local variable to none
2017-04-13 13:08:42 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:08:42 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:08:42 [app] [I]   Rendered hosts/_form.html.erb (750.6ms)
2017-04-13 13:08:42 [app] [I]   Rendered hosts/edit.html.erb within layouts/application (752.3ms)
2017-04-13 13:08:42 [app] [I]   Rendered layouts/_application_content.html.erb (0.4ms)
2017-04-13 13:08:42 [app] [I]   Rendered home/_user_dropdown.html.erb (1.3ms)
2017-04-13 13:08:42 [app] [I] Read fragment views/tabs_and_title_records-66 (0.1ms)
2017-04-13 13:08:42 [app] [I]   Rendered home/_topbar.html.erb (2.2ms)
2017-04-13 13:08:42 [app] [I]   Rendered layouts/base.html.erb (3.3ms)
2017-04-13 13:08:42 [app] [I] Completed 200 OK in 772ms (Views: 742.9ms | ActiveRecord: 15.9ms)
2017-04-13 13:08:43 [app] [I] Started GET "/assets/foreman_bootdisk/host_edit-9c7ad907fb288ac4445bffbcd2411eb60d562e8424615bad78750e968771d13c.js" for 100.91.160.33 at 2017-04-13 13:08:43 -0500
2017-04-13 13:08:43 [app] [I] Started GET "/assets/execution_interface-32241667bb32bbb121638ab31efcd33da7976accf8a3bcfc856e9e786b630a26.js" for 100.91.160.33 at 2017-04-13 13:08:43 -0500
2017-04-13 13:08:57 [app] [I] Started POST "/subnets/freeip" for 100.91.160.33 at 2017-04-13 13:08:57 -0500
2017-04-13 13:08:57 [app] [I] Processing by SubnetsController#freeip as JSON
2017-04-13 13:08:57 [app] [I]   Parameters: {"subnet_id"=>"22", "host_mac"=>"f0:1f:af:d7:d4:b0", "taken_ips"=>["100.89.25.130", "", "", "", "172.20.25.81", ""]}
2017-04-13 13:08:57 [app] [I]   Parameters: {"subnet_id"=>"22", "host_mac"=>"f0:1f:af:d7:d4:b0", "taken_ips"=>["100.89.25.130", "", "", "", "172.20.25.81", ""]}
2017-04-13 13:08:57 [app] [D] Setting current user thread-local variable to pbussler
2017-04-13 13:08:57 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:08:57 [app] [D] Setting current location thread-local variable to none
2017-04-13 13:09:16 [app] [I] Started GET "/" for 100.91.160.25 at 2017-04-13 13:09:16 -0500
2017-04-13 13:09:16 [app] [I] Processing by DashboardController#index as HTML
2017-04-13 13:09:16 [app] [D] Setting current user thread-local variable to jsparrow
2017-04-13 13:09:16 [app] [I]   Rendered dashboard/_status_links.html.erb (2.5ms)
2017-04-13 13:09:16 [app] [I]   Rendered dashboard/_status_widget.html.erb (2.9ms)
2017-04-13 13:09:16 [app] [I]   Rendered dashboard/_status_links.html.erb (2.5ms)
2017-04-13 13:09:16 [app] [I]   Rendered dashboard/_status_chart_widget.html.erb (3.3ms)
2017-04-13 13:09:16 [app] [I]   Rendered dashboard/_reports_widget.html.erb (13.7ms)
2017-04-13 13:09:16 [app] [I]   Rendered dashboard/_distribution_widget.html.erb (14.0ms)
2017-04-13 13:09:16 [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_discovery-5.0.2/app/views/dashboard/_discovery_widget_host.html.erb (1.2ms)
2017-04-13 13:09:16 [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_discovery-5.0.2/app/views/dashboard/_discovery_widget_host_list.html.erb (4.2ms)
2017-04-13 13:09:16 [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_discovery-5.0.2/app/views/dashboard/_discovery_widget.html.erb (7.2ms)
2017-04-13 13:09:16 [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman-tasks-0.7.18/app/views/foreman_tasks/tasks/dashboard/_latest_tasks_in_error_warning.html.erb (27.9ms)
2017-04-13 13:09:16 [app] [I]   Rendered dashboard/index.html.erb within layouts/application (76.7ms)
2017-04-13 13:09:16 [app] [I]   Rendered common/_searchbar.html.erb (2.0ms)
2017-04-13 13:09:16 [app] [I]   Rendered layouts/_application_content.html.erb (2.4ms)
2017-04-13 13:09:16 [app] [I]   Rendered home/_user_dropdown.html.erb (1.1ms)
2017-04-13 13:09:16 [app] [I] Read fragment views/tabs_and_title_records-50 (0.1ms)
2017-04-13 13:09:16 [app] [I]   Rendered home/_topbar.html.erb (1.8ms)
2017-04-13 13:09:16 [app] [I]   Rendered layouts/base.html.erb (2.6ms)
2017-04-13 13:09:16 [app] [I] Completed 200 OK in 141ms (Views: 74.8ms | ActiveRecord: 36.1ms)
2017-04-13 13:09:19 [app] [D] Setting current location thread-local variable to none
2017-04-13 13:09:19 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:09:19 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:09:19 [app] [I] Completed 200 OK in 22282ms (Views: 0.2ms | ActiveRecord: 1.0ms)
2017-04-13 13:09:21 [app] [I] Started PATCH "/hosts/virtserv1.lab.beer.town" for 100.91.160.33 at 2017-04-13 13:09:21 -0500
2017-04-13 13:09:21 [app] [I] Processing by HostsController#update as */*
2017-04-13 13:09:21 [app] [I]   Parameters: {"utf8"=>"✓", "authenticity_token"=>"6JMbfXz6eCLuM+QDsLnyc9pWn8KcwUK4nrlgbHODQE0=", "host"=>{"name"=>"virtserv1", "hostgroup_id"=>"127", "environment_id"=>"1", "puppet_ca_proxy_id"=>"1", "puppet_proxy_id"=>"1", "config_group_ids"=>[""], "puppetclass_ids"=>[""], "managed"=>"1", "progress_report_id"=>"[FILTERED]", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "mac"=>"f0:1f:af:d7:d4:ae", "identifier"=>"eno1", "name"=>"virtserv1", "domain_id"=>"2", "subnet_id"=>"22", "ip"=>"100.89.25.130", "managed"=>"1", "primary"=>"1", "provision"=>"1", "execution"=>"1", "tag"=>"", "attached_to"=>"", "id"=>"3852"}, "1"=>{"_destroy"=>"0", "mac"=>"f0:1f:af:d7:d4:af", "identifier"=>"eno2", "name"=>"", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"0", "primary"=>"0", "provision"=>"0", "execution"=>"0", "tag"=>"", "attached_to"=>"", "id"=>"3853"}, "2"=>{"_destroy"=>"0", "mac"=>"00:0a:f7:2a:3b:f6", "identifier"=>"enp8s0f0", "name"=>"", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"0", "primary"=>"0", "provision"=>"0", "execution"=>"0", "tag"=>"", "attached_to"=>"", "id"=>"3854"}, "3"=>{"_destroy"=>"0", "mac"=>"00:0a:f7:2a:3b:f7", "identifier"=>"enp8s0f1", "name"=>"", "domain_id"=>"", "subnet_id"=>"", "ip"=>"", "managed"=>"0", "primary"=>"0", "provision"=>"0", "execution"=>"0", "tag"=>"", "attached_to"=>"", "id"=>"3855"}, "4"=>{"_destroy"=>"0", "mac"=>"f0:1f:af:d7:d4:b0", "identifier"=>"ipmi", "name"=>"virtserv1-idrac", "domain_id"=>"2", "subnet_id"=>"22", "ip"=>"100.89.25.11", "managed"=>"1", "primary"=>"0", "provision"=>"0", "execution"=>"0", "username"=>"root", "provider"=>"IPMI", "id"=>"3856"}}, "architecture_id"=>"3", "operatingsystem_id"=>"2", "medium_id"=>"9", "ptable_id"=>"92", "disk"=>"", "is_owned_by"=>"66-Users", "enabled"=>"1", "model_id"=>"2", "comment"=>"", "overwrite"=>"false"}, "bare_metal_capabilities"=>"build", "id"=>"virtserv1.lab.beer.town"}
2017-04-13 13:09:21 [app] [D] Setting current user thread-local variable to pbussler
2017-04-13 13:09:21 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:09:21 [app] [D] Setting current location thread-local variable to none
2017-04-13 13:09:22 [app] [I] Started GET "/tasks/225f51be-4ad2-4ec9-843b-e051aa6460a7" for 100.91.160.33 at 2017-04-13 13:09:22 -0500
2017-04-13 13:09:22 [app] [I] Processing by TasksController#show as */*
2017-04-13 13:09:22 [app] [I]   Parameters: {"id"=>"225f51be-4ad2-4ec9-843b-e051aa6460a7"}
2017-04-13 13:09:22 [app] [D] Setting current user thread-local variable to pbussler
2017-04-13 13:09:22 [app] [I]   Rendered tasks/_list.html.erb (0.2ms)
2017-04-13 13:09:22 [app] [I] Completed 200 OK in 22ms (Views: 0.7ms | ActiveRecord: 0.6ms)
2017-04-13 13:09:24 [app] [I] Started GET "/tasks/225f51be-4ad2-4ec9-843b-e051aa6460a7" for 100.91.160.33 at 2017-04-13 13:09:24 -0500
2017-04-13 13:09:24 [app] [I] Processing by TasksController#show as */*
2017-04-13 13:09:24 [app] [I]   Parameters: {"id"=>"225f51be-4ad2-4ec9-843b-e051aa6460a7"}
2017-04-13 13:09:24 [app] [D] Setting current user thread-local variable to pbussler
2017-04-13 13:09:24 [app] [I]   Rendered tasks/_list.html.erb (0.1ms)
2017-04-13 13:09:24 [app] [I] Completed 200 OK in 6ms (Views: 0.6ms | ActiveRecord: 0.6ms)
2017-04-13 13:09:24 [app] [I] Create DHCP reservation for virtserv1-idrac.lab.beer.town-f0:1f:af:d7:d4:b0/100.89.25.11
2017-04-13 13:09:24 [app] [D] DHCP reservation on net 100.89.25.0 with attrs: {:hostname=>"virtserv1-idrac.lab.beer.town", :mac=>"f0:1f:af:d7:d4:b0", :ip=>"100.89.25.11", :network=>"100.89.25.0"}
2017-04-13 13:09:26 [app] [I] Add DNS A record for virtserv1-idrac.lab.beer.town/100.89.25.11
2017-04-13 13:09:26 [app] [I] Started GET "/tasks/225f51be-4ad2-4ec9-843b-e051aa6460a7" for 100.91.160.33 at 2017-04-13 13:09:26 -0500
2017-04-13 13:09:26 [app] [I] Processing by TasksController#show as */*
2017-04-13 13:09:26 [app] [I]   Parameters: {"id"=>"225f51be-4ad2-4ec9-843b-e051aa6460a7"}
2017-04-13 13:09:26 [app] [D] Setting current user thread-local variable to pbussler
2017-04-13 13:09:26 [app] [I]   Rendered tasks/_list.html.erb (0.4ms)
2017-04-13 13:09:26 [app] [I] Completed 200 OK in 6ms (Views: 0.7ms | ActiveRecord: 0.5ms)
2017-04-13 13:09:26 [app] [W] Create DNS record for virtserv1-idrac.lab.beer.town task failed with the following error: ERF12-2357 [ProxyAPI::ProxyException]: Unable to set DNS entry ([RestClient::Conflict]: 409 Conflict) for proxy https://foreman-deploy.lab.beer.town:8443/dns
 | ProxyAPI::ProxyException: ERF12-2357 [ProxyAPI::ProxyException]: Unable to set DNS entry ([RestClient::Conflict]: 409 Conflict) for proxy https://foreman-deploy.lab.beer.town:8443/dns
 | /usr/share/foreman/lib/proxy_api/dns.rb:15:in `rescue in set'
 | /usr/share/foreman/lib/proxy_api/dns.rb:13:in `set'
 | /usr/share/foreman/lib/net/dns/a_record.rb:20:in `create'
 | /usr/share/foreman/app/models/concerns/orchestration/dns.rb:80:in `set_dns_a_record'
 | /usr/share/foreman/app/models/concerns/orchestration.rb:162:in `execute'
 | /usr/share/foreman/app/models/concerns/orchestration.rb:107:in `block in process'
 | /usr/share/foreman/app/models/concerns/orchestration.rb:99:in `each'
 | /usr/share/foreman/app/models/concerns/orchestration.rb:99:in `process'
 | /usr/share/foreman/app/models/concerns/orchestration.rb:35:in `on_save'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:424:in `block in make_lambda'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:160:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:160:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:215:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:215:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:215:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:215:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:86:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:86:in `run_callbacks'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/callbacks.rb:302:in `create_or_update'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/persistence.rb:103:in `save'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/validations.rb:51:in `save'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/attribute_methods/dirty.rb:21:in `save'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:268:in `block (2 levels) in save'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:329:in `block in with_transaction_returning_status'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/connection_adapters/abstract/database_statements.rb:199:in `transaction'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:208:in `transaction'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:326:in `with_transaction_returning_status'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:268:in `block in save'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:283:in `rollback_active_record_state!'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:267:in `save'
 | /usr/share/foreman/app/models/concerns/foreman/sti.rb:29:in `save_with_type'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/protected_attributes-1.1.3/lib/active_record/mass_assignment_security/persistence.rb:65:in `block in update'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:329:in `block in with_transaction_returning_status'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/connection_adapters/abstract/database_statements.rb:201:in `block in transaction'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/connection_adapters/abstract/database_statements.rb:209:in `within_new_transaction'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/connection_adapters/abstract/database_statements.rb:201:in `transaction'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:208:in `transaction'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/transactions.rb:326:in `with_transaction_returning_status'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/protected_attributes-1.1.3/lib/active_record/mass_assignment_security/persistence.rb:63:in `update'
 | /usr/share/foreman/app/controllers/hosts_controller.rb:109:in `block in update'
 | /usr/share/foreman/app/models/taxonomy.rb:76:in `block in no_taxonomy_scope'
 | /usr/share/foreman/app/models/taxonomy.rb:83:in `block (2 levels) in as_taxonomy'
 | /usr/share/foreman/app/models/concerns/foreman/thread_session.rb:147:in `as_location'
 | /usr/share/foreman/app/models/taxonomy.rb:82:in `block in as_taxonomy'
 | /usr/share/foreman/app/models/concerns/foreman/thread_session.rb:112:in `as_org'
 | /usr/share/foreman/app/models/taxonomy.rb:81:in `as_taxonomy'
 | /usr/share/foreman/app/models/taxonomy.rb:75:in `no_taxonomy_scope'
 | /usr/share/foreman/app/controllers/hosts_controller.rb:106:in `update'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/abstract_controller/base.rb:189:in `process_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_controller/metal/rendering.rb:10:in `process_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:113:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:113:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:299:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:299:in `block (2 levels) in halting'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/rails-observers-0.1.2/lib/rails/observers/action_controller/caching/sweeping.rb:73:in `around'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:447:in `public_send'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:447:in `block in make_lambda'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:298:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:298:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:299:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:299:in `block (2 levels) in halting'
 | /usr/share/foreman/app/controllers/concerns/application_shared.rb:13:in `set_timezone'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:424:in `block in make_lambda'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:298:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:298:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:229:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:229:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:299:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:299:in `block (2 levels) in halting'
 | /usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:424:in `block in make_lambda'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:298:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:298:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:229:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:229:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:166:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:299:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:299:in `block (2 levels) in halting'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/rails-observers-0.1.2/lib/rails/observers/action_controller/caching/sweeping.rb:73:in `around'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:447:in `public_send'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:447:in `block in make_lambda'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:298:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:298:in `block in halting'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:149:in `block in halting_and_conditional'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:86:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:86:in `run_callbacks'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/abstract_controller/callbacks.rb:19:in `process_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_controller/metal/rescue.rb:29:in `process_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_controller/metal/instrumentation.rb:31:in `block in process_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/notifications.rb:159:in `block in instrument'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/notifications.rb:159:in `instrument'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_controller/metal/params_wrapper.rb:250:in `process_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/abstract_controller/base.rb:136:in `process'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionview-4.1.5/lib/action_view/rendering.rb:30:in `process'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_controller/metal.rb:196:in `dispatch'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_controller/metal/rack_delegation.rb:13:in `dispatch'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_controller/metal.rb:232:in `block in action'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/routing/route_set.rb:82:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/routing/route_set.rb:82:in `dispatch'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/routing/route_set.rb:50:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/journey/router.rb:71:in `block in call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/journey/router.rb:59:in `each'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/journey/router.rb:59:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/routing/route_set.rb:678:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.3.6/lib/apipie/static_dispatcher.rb:65:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.3.6/lib/apipie/extractor/recorder.rb:132:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/static.rb:64:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/static.rb:64:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/static.rb:64:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/static.rb:64:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/static.rb:64:in `call'
 | /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.3.6/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/etag.rb:23:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/conditionalget.rb:35:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/head.rb:11:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/params_parser.rb:27:in `call'
 | /usr/share/foreman/lib/middleware/catch_json_parse_errors.rb:9:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/flash.rb:254:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/session/abstract/id.rb:225:in `context'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/session/abstract/id.rb:220:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/cookies.rb:560:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/query_cache.rb:36:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activerecord-4.1.5/lib/active_record/connection_adapters/abstract/connection_pool.rb:621:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/callbacks.rb:29:in `block in call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/callbacks.rb:82:in `run_callbacks'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/remote_ip.rb:76:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/debug_exceptions.rb:17:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/show_exceptions.rb:30:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/railties-4.1.5/lib/rails/rack/logger.rb:38:in `call_app'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/railties-4.1.5/lib/rails/rack/logger.rb:22:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/request_id.rb:21:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/methodoverride.rb:21:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/runtime.rb:17:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/activesupport-4.1.5/lib/active_support/cache/strategy/local_cache_middleware.rb:26:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/actionpack-4.1.5/lib/action_dispatch/middleware/static.rb:64:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/sendfile.rb:112:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/railties-4.1.5/lib/rails/engine.rb:514:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/railties-4.1.5/lib/rails/application.rb:144:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/railties-4.1.5/lib/rails/railtie.rb:194:in `public_send'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/railties-4.1.5/lib/rails/railtie.rb:194:in `method_missing'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/builder.rb:138:in `call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/urlmap.rb:65:in `block in call'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/urlmap.rb:50:in `each'
 | /opt/rh/rh-ror41/root/usr/share/gems/gems/rack-1.5.2/lib/rack/urlmap.rb:50:in `call'
 | /usr/lib/ruby/gems/1.8/gems/passenger-5.0.28/src/ruby_supportlib/phusion_passenger/rack/thread_handler_extension.rb:97:in `process_request'
 | /usr/lib/ruby/gems/1.8/gems/passenger-5.0.28/src/ruby_supportlib/phusion_passenger/request_handler/thread_handler.rb:160:in `accept_and_process_next_request'
 | /usr/lib/ruby/gems/1.8/gems/passenger-5.0.28/src/ruby_supportlib/phusion_passenger/request_handler/thread_handler.rb:113:in `main_loop'
 | /usr/lib/ruby/gems/1.8/gems/passenger-5.0.28/src/ruby_supportlib/phusion_passenger/request_handler.rb:416:in `block (3 levels) in start_threads'
 | /usr/lib/ruby/gems/1.8/gems/passenger-5.0.28/src/ruby_supportlib/phusion_passenger/utils.rb:113:in `block in create_thread_and_abort_on_exception'
 | /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-04-13 13:09:26 [app] [I] Delete DHCP reservation for virtserv1-idrac.lab.beer.town-f0:1f:af:d7:d4:b0/100.89.25.11
2017-04-13 13:09:26 [app] [I] Delete DHCP reservation for virtserv1-idrac.lab.beer.town-f0:1f:af:d7:d4:b0/100.89.25.11
2017-04-13 13:09:27 [app] [I] Failed to save: Create DNS record for virtserv1-idrac.lab.beer.town task failed with the following error: ERF12-2357 [ProxyAPI::ProxyException]: Unable to set DNS entry ([RestClient::Conflict]: 409 Conflict) for proxy https://foreman-deploy.lab.beer.town:8443/dns
2017-04-13 13:09:27 [app] [D] Deface: 1 overrides found for 'hosts/_form'
2017-04-13 13:09:27 [app] [D] Deface: 'add_execution_interface_js' matched 1 times with 'div#primary'
2017-04-13 13:09:27 [app] [I] Deface: [WARNING] No :original defined for 'add_execution_interface_js', you should change its definition to include:
 |  :original => 'de30e68c260490c129e19064153f33af9de45728'
2017-04-13 13:09:27 [app] [I]   Rendered hosts/_progress.html.erb (0.7ms)
2017-04-13 13:09:27 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:09:27 [app] [D] Setting current location thread-local variable to none
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (19.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (12.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.6ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.4ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.4ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.4ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.7ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.4ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.7ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (11.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.5ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.7ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.4ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.6ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.4ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.4ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (10.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (9.8ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (9.9ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_group.html.erb (9.6ms)
2017-04-13 13:09:27 [app] [I]   Rendered config_groups/_config_groups_selection.html.erb (344.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered puppetclasses/_selectedClasses.html.erb (0.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered puppetclasses/_classes_in_groups.html.erb (0.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered puppetclasses/_classes.html.erb (94.8ms)
2017-04-13 13:09:27 [app] [I]   Rendered puppetclasses/_class_selection.html.erb (489.4ms)
2017-04-13 13:09:27 [app] [D] Deface: 1 overrides found for 'nic/_base_form'
2017-04-13 13:09:27 [app] [D] Deface: 'add_execution_interface' matched 1 times with 'erb[loud]:contains("interface_provision")'
2017-04-13 13:09:27 [app] [I] Deface: [WARNING] No :original defined for 'add_execution_interface', you should change its definition to include:
 |  :original => '2edbaf87b55d7218675ecce162bd23377142c8c1'
2017-04-13 13:09:27 [app] [I]   Rendered nic/_base_form.html.erb (12.7ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_virtual_form.html.erb (1.8ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.6ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/manageds/_managed.html.erb (32.4ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_base_form.html.erb (9.7ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_virtual_form.html.erb (1.3ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/manageds/_managed.html.erb (12.7ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_base_form.html.erb (9.4ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_virtual_form.html.erb (1.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/manageds/_managed.html.erb (12.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_base_form.html.erb (9.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_virtual_form.html.erb (1.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/manageds/_managed.html.erb (11.8ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_base_form.html.erb (9.8ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/bmcs/_bmc.html.erb (15.9ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_base_form.html.erb (9.9ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_virtual_form.html.erb (1.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/_provider_specific_form.html.erb (0.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered nic/manageds/_managed.html.erb (15.7ms)
2017-04-13 13:09:27 [app] [I]   Rendered hosts/_interfaces.html.erb (111.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered common/os_selection/_architecture.html.erb (4.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/foreman_bootdisk-7.0.1/app/views/hosts/provision_method/bootdisk/_form.html.erb (0.8ms)
2017-04-13 13:09:27 [app] [I]   Rendered common/os_selection/_operatingsystem.html.erb (6.6ms)
2017-04-13 13:09:27 [app] [I]   Rendered hosts/provision_method/build/_form.html.erb (10.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered hosts/provision_method/image/_form.html.erb (0.6ms)
2017-04-13 13:09:27 [app] [I]   Rendered hosts/_operating_system.html.erb (33.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered hosts/_unattended.html.erb (36.0ms)
2017-04-13 13:09:27 [app] [I] Started GET "/tasks/225f51be-4ad2-4ec9-843b-e051aa6460a7" for 100.91.160.33 at 2017-04-13 13:09:27 -0500
2017-04-13 13:09:27 [app] [I] Processing by TasksController#show as */*
2017-04-13 13:09:27 [app] [I]   Parameters: {"id"=>"225f51be-4ad2-4ec9-843b-e051aa6460a7"}
2017-04-13 13:09:27 [app] [D] Setting current user thread-local variable to pbussler
2017-04-13 13:09:27 [app] [I]   Rendered tasks/_list.html.erb (0.3ms)
2017-04-13 13:09:27 [app] [I] Completed 200 OK in 6ms (Views: 0.7ms | ActiveRecord: 0.5ms)
2017-04-13 13:09:27 [app] [I]   Rendered puppetclasses/_class_parameters.html.erb (65.9ms)
2017-04-13 13:09:27 [app] [I]   Rendered puppetclasses/_classes_parameters.html.erb (75.5ms)
2017-04-13 13:09:27 [app] [I]   Rendered common_parameters/_inherited_parameters.html.erb (11.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered common_parameters/_parameter.html.erb (2.5ms)
2017-04-13 13:09:27 [app] [I]   Rendered common_parameters/_parameters.html.erb (6.9ms)
2017-04-13 13:09:27 [app] [D] Setting current location thread-local variable to none
2017-04-13 13:09:27 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:09:27 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:09:27 [app] [I]   Rendered hosts/_form.html.erb (793.2ms)
2017-04-13 13:09:27 [app] [I]   Rendered hosts/edit.html.erb within layouts/application (805.9ms)
2017-04-13 13:09:27 [app] [I]   Rendered layouts/_application_content.html.erb (1.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered home/_user_dropdown.html.erb (1.7ms)
2017-04-13 13:09:27 [app] [I] Read fragment views/tabs_and_title_records-66 (0.1ms)
2017-04-13 13:09:27 [app] [I]   Rendered home/_topbar.html.erb (5.0ms)
2017-04-13 13:09:27 [app] [I]   Rendered layouts/base.html.erb (8.2ms)
2017-04-13 13:09:27 [app] [D] Setting current location thread-local variable to none
2017-04-13 13:09:27 [app] [D] Setting current organization thread-local variable to none
2017-04-13 13:09:27 [app] [I] Completed 200 OK in 6373ms (Views: 808.1ms | ActiveRecord: 34.2ms)
2017-04-13 13:09:29 [app] [I] Started GET "/tasks/225f51be-4ad2-4ec9-843b-e051aa6460a7" for 100.91.160.33 at 2017-04-13 13:09:29 -0500
2017-04-13 13:09:29 [app] [I] Processing by TasksController#show as */*
2017-04-13 13:09:29 [app] [I]   Parameters: {"id"=>"225f51be-4ad2-4ec9-843b-e051aa6460a7"}
2017-04-13 13:09:29 [app] [D] Setting current user thread-local variable to pbussler
2017-04-13 13:09:29 [app] [I]   Rendered tasks/_list.html.erb (0.5ms)
2017-04-13 13:09:29 [app] [I] Completed 200 OK in 6ms (Views: 0.9ms | ActiveRecord: 0.6ms)
2017-04-13 13:09:32 [app] [I] Started GET "/api/v2/hosts/mosley" for 172.20.70.43 at 2017-04-13 13:09:32 -0500
2017-04-13 13:09:32 [app] [I] Processing by Api::V2::HostsController#show as JSON
2017-04-13 13:09:32 [app] [I]   Parameters: {"apiv"=>"v2", "id"=>"mosley"}
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to foreman_admin
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to nil
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to foreman_admin
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to nil
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to cmlapiuser
2017-04-13 13:09:32 [app] [I] Authorized user cmlapiuser(Compellent API)
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to cmlapiuser
2017-04-13 13:09:32 [app] [I]   Rendered api/v2/hosts/show.json.rabl (42.4ms)
2017-04-13 13:09:32 [app] [D] Body: {"ip":"172.20.70.43","environment_id":1,"environment_name":"production","last_report":"2017-03-02 08:49:04 UTC","mac":"90:b1:1c:09:0b:ae","realm_id":null,"realm_name":null,"sp_mac":null,"sp_ip":null,"sp_name":null,"domain_id":null,"domain_name":null,"architecture_id":3,"architecture_name":"x64","operatingsystem_id":15,"operatingsystem_name":"2008R2 Server","subnet_id":null,"subnet_name":null,"sp_subnet_id":null,"ptable_id":null,"ptable_name":null,"medium_id":null,"medium_name":null,"build":false,"comment":null,"disk":null,"installed_at":null,"model_id":2,"hostgroup_id":null,"hostgroup_name":null,"owner_id":null,"owner_type":null,"enabled":true,"puppet_ca_proxy_id":1,"managed":false,"use_image":null,"image_file":"","uuid":null,"compute_resource_id":null,"compute_resource_name":null,"compute_profile_id":null,"compute_profile_name":null,"capabilities":["build"],"provision_method":"build","puppet_proxy_id":1,"certname":"mosley","image_id":null,"image_name":null,"created_at":"2017-03-01 17:44:40 UTC","updated_at":"2017-03-02 08:51:30 UTC","last_compile":"2017-03-02 08:49:39 UTC","global_status":0,"global_status_label":"Error","puppet_status":654331905,"model_name":"PowerEdge R320","configuration_status":654331905,"configuration_status_label":"Error","name":"mosley","id":2420,"parameters":[],"interfaces":[{"id":3653,"name":"mosley","ip":"172.20.70.43","mac":"90:b1:1c:09:0b:ae","identifier":"local_area_connection","primary":true,"provision":true,"type":"interface"}],"puppetclasses":[],"config_groups":[],"all_parameters":[{"id":6,"name":"windowsLicenseOwner","value":"Dell"},{"id":19,"name":"windowsDefaultPassword","value":"Password5"},{"id":8,"name":"time-zone","value":"US/Central"},{"id":7,"name":"ntp-server","value":"time.lab.beer.town"},{"id":14,"name":"deploymentShareUser","value":"windowsinstall"},{"id":13,"name":"deploymentSharePassword","value":"windowsinstall"},{"id":3,"name":"deploymentShare","value":"\\\\puppet.lab.beer.town\\sources"},{"id":2,"name":"choco-repo","value":"http://es-artifactory.lab.beer.town/artifactory/api/nuget/chocolatey/"}],"all_puppetclasses":[],"permissions":{"view_hosts":true,"create_hosts":true,"edit_hosts":true,"destroy_hosts":true,"build_hosts":true,"power_hosts":true,"console_hosts":true,"ipmi_boot":true,"puppetrun_hosts":true,"view_discovered_hosts":true,"provision_discovered_hosts":true,"edit_discovered_hosts":true,"destroy_discovered_hosts":true,"submit_discovered_hosts":true,"auto_provision_discovered_hosts":true}}
2017-04-13 13:09:32 [app] [I] Completed 200 OK in 62ms (Views: 38.3ms | ActiveRecord: 10.3ms)
2017-04-13 13:09:32 [app] [I] Started GET "/api/v2/hosts?search=name=mosley" for 172.20.70.43 at 2017-04-13 13:09:32 -0500
2017-04-13 13:09:32 [app] [I] Processing by Api::V2::HostsController#index as JSON
2017-04-13 13:09:32 [app] [I]   Parameters: {"search"=>"name=mosley", "apiv"=>"v2"}
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to foreman_admin
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to nil
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to foreman_admin
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to nil
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to cmlapiuser
2017-04-13 13:09:32 [app] [I] Authorized user cmlapiuser(Compellent API)
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to cmlapiuser
2017-04-13 13:09:32 [app] [I]   Rendered api/v2/hosts/index.json.rabl within api/v2/layouts/index_layout (20.1ms)
2017-04-13 13:09:32 [app] [D] Body: {
 |   "total": 1126,
 |   "subtotal": 1,
 |   "page": 1,
 |   "per_page": 50,
 |   "search": "name=mosley",
 |   "sort": {
 |     "by": null,
 |     "order": null
 |   },
 |   "results": [{"ip":"172.20.70.43","environment_id":1,"environment_name":"production","last_report":"2017-03-02 08:49:04 UTC","mac":"90:b1:1c:09:0b:ae","realm_id":null,"realm_name":null,"sp_mac":null,"sp_ip":null,"sp_name":null,"domain_id":null,"domain_name":null,"architecture_id":3,"architecture_name":"x64","operatingsystem_id":15,"operatingsystem_name":"2008R2 Server","subnet_id":null,"subnet_name":null,"sp_subnet_id":null,"ptable_id":null,"ptable_name":null,"medium_id":null,"medium_name":null,"build":false,"comment":null,"disk":null,"installed_at":null,"model_id":2,"hostgroup_id":null,"hostgroup_name":null,"owner_id":null,"owner_type":null,"enabled":true,"puppet_ca_proxy_id":1,"managed":false,"use_image":null,"image_file":"","uuid":null,"compute_resource_id":null,"compute_resource_name":null,"compute_profile_id":null,"compute_profile_name":null,"capabilities":["build"],"provision_method":"build","puppet_proxy_id":1,"certname":"mosley","image_id":null,"image_name":null,"created_at":"2017-03-01 17:44:40 UTC","updated_at":"2017-03-02 08:51:30 UTC","last_compile":"2017-03-02 08:49:39 UTC","global_status":0,"global_status_label":"Error","puppet_status":654331905,"model_name":"PowerEdge R320","configuration_status":654331905,"configuration_status_label":"Error","name":"mosley","id":2420}]
 | }
 |
2017-04-13 13:09:32 [app] [I] Completed 200 OK in 46ms (Views: 20.9ms | ActiveRecord: 6.8ms)
2017-04-13 13:09:32 [app] [I] Started GET "/api/v2/hosts/mosley/puppetclasses" for 172.20.70.43 at 2017-04-13 13:09:32 -0500
2017-04-13 13:09:32 [app] [I] Processing by Api::V2::PuppetclassesController#index as JSON
2017-04-13 13:09:32 [app] [I]   Parameters: {"apiv"=>"v2", "host_id"=>"mosley"}
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to foreman_admin
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to nil
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to foreman_admin
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to nil
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to cmlapiuser
2017-04-13 13:09:32 [app] [I] Authorized user cmlapiuser(Compellent API)
2017-04-13 13:09:32 [app] [D] Setting current user thread-local variable to cmlapiuser
2017-04-13 13:09:32 [app] [I]   Rendered api/v2/puppetclasses/index.json.rabl within api/v2/layouts/index_layout (0.7ms)
2017-04-13 13:09:32 [app] [D] Body: {
 |   "total": 0,
 |   "subtotal": 0,
 |   "page": 1,
 |   "per_page": 50,
 |   "search": null,
 |   "sort": {
 |     "by": null,
 |     "order": null
 |   },
 |   "results": {}
 | }
 |
2017-04-13 13:09:32 [app] [I] Completed 200 OK in 27ms (Views: 1.6ms | ActiveRecord: 6.3ms)
^C

Where we can see Create DNS record for virtserv1-idrac.lab.beer.town task failed with the following error: ERF12-2357 [ProxyAPI::ProxyException]: Unable to set DNS entry ([RestClient::Conflict]: 409 Conflict) for proxy https://foreman-deploy.lab.beer.town:8443/dns followed by the proxy failure:

proxy.log

D, [2017-04-13T13:09:25.264499 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 show client 1
D, [2017-04-13T13:09:25.339505 #1728] DEBUG -- : Enumerated hosts on 100.89.25.0
D, [2017-04-13T13:09:25.339505 #1728] DEBUG -- : {:subnet=>#<Proxy::DHCP::Subnet:0x00000003dd4368 @network="100.89.25.0", @netmask="255.255.255.0", @ipaddr=#<IPAddr: IPv4:100.89.25.0/255.255.255.0>, @options={}, @timestamp=2017-04-13 13:09:25 -0500>, :ip=>"100.89.25.4", :mac=>"f0:1f:af:e0:a1:21", :name=>"virtserv3-idrac.lab.beer.town"}
D, [2017-04-13T13:09:25.340499 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 Show ReservedOptionValue 100.89.25.4
D, [2017-04-13T13:09:25.417506 #1728] DEBUG -- : Queried 100.89.25.4 options
D, [2017-04-13T13:09:25.417506 #1728] DEBUG -- : {:subnet=>#<Proxy::DHCP::Subnet:0x00000003dd4368 @network="100.89.25.0", @netmask="255.255.255.0", @ipaddr=#<IPAddr: IPv4:100.89.25.0/255.255.255.0>, @options={}, @timestamp=2017-04-13 13:09:25 -0500>, :ip=>"100.89.25.7", :mac=>"c8:1f:66:b8:be:84", :name=>"virtserv4-idrac.lab.beer.town"}
D, [2017-04-13T13:09:25.418503 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 Show ReservedOptionValue 100.89.25.7
D, [2017-04-13T13:09:25.486505 #1728] DEBUG -- : Queried 100.89.25.7 options
D, [2017-04-13T13:09:25.486505 #1728] DEBUG -- : {:subnet=>#<Proxy::DHCP::Subnet:0x00000003dd4368 @network="100.89.25.0", @netmask="255.255.255.0", @ipaddr=#<IPAddr: IPv4:100.89.25.0/255.255.255.0>, @options={}, @timestamp=2017-04-13 13:09:25 -0500>, :ip=>"100.89.25.130", :mac=>"f0:1f:af:d7:d4:ae", :name=>"virtserv1.lab.beer.town"}
D, [2017-04-13T13:09:25.486505 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 Show ReservedOptionValue 100.89.25.130
D, [2017-04-13T13:09:25.551518 #1728] DEBUG -- : Queried 100.89.25.130 options
D, [2017-04-13T13:09:25.552514 #1728] DEBUG -- : {:subnet=>#<Proxy::DHCP::Subnet:0x00000003dd4368 @network="100.89.25.0", @netmask="255.255.255.0", @ipaddr=#<IPAddr: IPv4:100.89.25.0/255.255.255.0>, @options={}, @timestamp=2017-04-13 13:09:25 -0500>, :ip=>"100.89.25.131", :mac=>"f0:1f:af:e0:a1:1f", :name=>"virtserv3.lab.beer.town"}
D, [2017-04-13T13:09:25.552514 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 Show ReservedOptionValue 100.89.25.131
D, [2017-04-13T13:09:25.618511 #1728] DEBUG -- : Queried 100.89.25.131 options
D, [2017-04-13T13:09:25.619513 #1728] DEBUG -- : {:subnet=>#<Proxy::DHCP::Subnet:0x00000003dd4368 @network="100.89.25.0", @netmask="255.255.255.0", @ipaddr=#<IPAddr: IPv4:100.89.25.0/255.255.255.0>, @options={}, @timestamp=2017-04-13 13:09:25 -0500>, :ip=>"100.89.25.132", :mac=>"c8:1f:66:b8:be:82", :name=>"virtserv4.lab.beer.town"}
D, [2017-04-13T13:09:25.619513 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 Show ReservedOptionValue 100.89.25.132
D, [2017-04-13T13:09:25.685517 #1728] DEBUG -- : Queried 100.89.25.132 options
D, [2017-04-13T13:09:25.685517 #1728] DEBUG -- : Added a reservation: 100.89.25.4:f0:1f:af:e0:a1:21:virtserv3-idrac.lab.beer.town
D, [2017-04-13T13:09:25.685517 #1728] DEBUG -- : Added a reservation: 100.89.25.7:c8:1f:66:b8:be:84:virtserv4-idrac.lab.beer.town
D, [2017-04-13T13:09:25.685517 #1728] DEBUG -- : Added a reservation: 100.89.25.130:f0:1f:af:d7:d4:ae:virtserv1.lab.beer.town
D, [2017-04-13T13:09:25.685517 #1728] DEBUG -- : Added a reservation: 100.89.25.131:f0:1f:af:e0:a1:1f:virtserv3.lab.beer.town
D, [2017-04-13T13:09:25.686516 #1728] DEBUG -- : Added a reservation: 100.89.25.132:c8:1f:66:b8:be:82:virtserv4.lab.beer.town
D, [2017-04-13T13:09:25.686516 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 add reservedip 100.89.25.11 f01fafd7d4b0 virtserv1-idrac.lab.beer.town
D, [2017-04-13T13:09:25.761523 #1728] DEBUG -- : Added DHCP reservation for virtserv1-idrac.lab.beer.town (100.89.25.11 / f0:1f:af:d7:d4:b0)
D, [2017-04-13T13:09:25.761523 #1728] DEBUG -- : key: "PXEClient" 
D, [2017-04-13T13:09:25.761523 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 set reservedoptionvalue 100.89.25.11 60 String "" 
D, [2017-04-13T13:09:25.838525 #1728] DEBUG -- : key: :hostname
D, [2017-04-13T13:09:25.838525 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 set reservedoptionvalue 100.89.25.11 12 String "virtserv1-idrac.lab.beer.town" 
D, [2017-04-13T13:09:25.911530 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 scope 100.89.25.0 set dnsconfig 100.89.25.11 0 0 0 0
I, [2017-04-13T13:09:25.980531 #1728]  INFO -- : 100.90.131.25 - - [13/Apr/2017 13:09:25] "POST /dhcp/100.89.25.0 HTTP/1.1" 200 - 1.0281

D, [2017-04-13T13:09:25.992544 #1728] DEBUG -- : close: 100.90.131.25:51698
D, [2017-04-13T13:09:26.028536 #1728] DEBUG -- : accept: 100.90.131.25:51700
D, [2017-04-13T13:09:26.040538 #1728] DEBUG -- : Rack::Handler::WEBrick is invoked.
E, [2017-04-13T13:09:26.055540 #1728] ERROR -- : 'virtserv1-idrac.lab.beer.town 'is already in use
D, [2017-04-13T13:09:26.055540 #1728] DEBUG -- : 'virtserv1-idrac.lab.beer.town 'is already in use (Proxy::Dns::Collision)
C:/foreman-proxy/smart-proxy-l1_2/modules/dns_dnscmd/dns_dnscmd_main.rb:15:in `create_a_record'
C:/foreman-proxy/smart-proxy-l1_2/modules/dns/dns_api.rb:25:in `block in <class:Api>'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1611:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1611:in `block in compile!'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:994:in `route_eval'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1015:in `block in process_route'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1013:in `catch'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1013:in `process_route'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:973:in `block in route!'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:972:in `each'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:972:in `route!'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1085:in `block in dispatch!'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1067:in `block in invoke'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1067:in `catch'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1067:in `invoke'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1082:in `dispatch!'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:907:in `block in call!'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1067:in `block in invoke'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1067:in `catch'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1067:in `invoke'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:907:in `call!'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:895:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-1.5.5/lib/rack/commonlogger.rb:33:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:219:in `call'
C:/foreman-proxy/smart-proxy-l1_2/lib/proxy/log.rb:63:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-1.5.5/lib/rack/nulllogger.rb:9:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-1.5.5/lib/rack/head.rb:11:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/show_exceptions.rb:25:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:182:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:2013:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1487:in `block in call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1787:in `synchronize'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/sinatra-1.4.8/lib/sinatra/base.rb:1487:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-1.5.5/lib/rack/builder.rb:138:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-1.5.5/lib/rack/urlmap.rb:65:in `block in call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-1.5.5/lib/rack/urlmap.rb:50:in `each'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-1.5.5/lib/rack/urlmap.rb:50:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-1.5.5/lib/rack/builder.rb:138:in `call'
C:/Ruby23-x64/lib/ruby/gems/2.3.0/gems/rack-1.5.5/lib/rack/handler/webrick.rb:60:in `service'
C:/Ruby23-x64/lib/ruby/2.3.0/webrick/httpserver.rb:140:in `service'
C:/Ruby23-x64/lib/ruby/2.3.0/webrick/httpserver.rb:96:in `run'
C:/Ruby23-x64/lib/ruby/2.3.0/webrick/server.rb:296:in `block in start_thread'
I, [2017-04-13T13:09:26.056537 #1728]  INFO -- : 100.90.131.25 - - [13/Apr/2017 13:09:26] "POST /dns/ HTTP/1.1" 409 49 0.0150

D, [2017-04-13T13:09:26.100540 #1728] DEBUG -- : close: 100.90.131.25:51700
D, [2017-04-13T13:09:26.136540 #1728] DEBUG -- : accept: 100.90.131.25:51702
D, [2017-04-13T13:09:26.148543 #1728] DEBUG -- : Rack::Handler::WEBrick is invoked.
D, [2017-04-13T13:09:26.149541 #1728] DEBUG -- : Loading subnets for 100.88.0.121
D, [2017-04-13T13:09:26.149541 #1728] DEBUG -- : executing: c:\windows\system32\cmd.exe /c c:\Windows\System32\netsh.exe -c dhcp server 100.88.0.121 show scope

Seems to me like something isnt being handled here correctly? Shouldnt Foreman ask to overwrite it, and then delete it and recreate?

Thanks.

Actions

Copy link

Updated by Jeff Sparrow about 7 years ago

not sure why markup didnt work :(

Actions

Copy link

Updated by Dominic Cleal about 7 years ago

Description updated (diff)

It looks like you're running a very outdated version of Foreman, I'd strongly suggest re-testing this on a supported version.

Actions

Copy link

Updated by Jeff Sparrow about 7 years ago

Alright. Unfortunately I dont know if I'll be able to go past 1.12 since the server is running 6x rhel right now. Proxy is on 1.14, Foreman is on 1.11.

Actions

Copy link

Updated by Dominic Cleal about 7 years ago

If there is an existing record, does it have precisely the same hostname, IP address and type as the new record being created by Foreman, or does it have different attributes?

2017-04-13 13:09:26 [app] [I] Add DNS A record for virtserv1-idrac.lab.beer.town/100.89.25.11

Actions

Copy link

Also available in: Atom PDF

Project

General

Profile

Custom queries

Bug #19272

proxy fails to delete existing DNS entry

Updated by Jeff Sparrow about 7 years ago

Updated by Dominic Cleal about 7 years ago

Updated by Jeff Sparrow about 7 years ago

Updated by Dominic Cleal about 7 years ago