Net::HTTPConflict error when provisioning a host from capsule

Jorick_Astrego1 · October 28, 2016, 11:52am

Hi,

After some struggeling, I got the capsule working properly. The hosts
deploy and everything works until it has to inform foreman that it's built.

I've checked a lot of settings but cannot get it to properly announce
the completed built, this puts the host in a provisioning loop.

When I check the url manually, I get the following error:

http://capsule.othernet.test:8000/unattended/built?token=3ac505a5-46a8-4865-a819-45ca103329c9

Failed to retrieve built template for
{&quot;token&quot;=&gt;&quot;3ac505a5-46a8-4865-a819-45ca103329c9&quot;, &quot;splat&quot;=&gt;[],
&quot;captures&quot;=&gt;[&quot;built&quot;], &quot;kind&quot;=&gt;&quot;built&quot;}: Error retrieving built for
{&quot;token&quot;=&gt;&quot;3ac505a5-46a8-4865-a819-45ca103329c9&quot;,
:url=&gt;&quot;http://capsule.othernet.test:8000&quot;} from
katello.netbulae.test: Net::HTTPConflict

Someone have some idea?

Met vriendelijke groet, With kind regards,

Jorick Astrego

Netbulae Virtualization Experts

···

----------------

Tel: 053 20 30 270 	info@netbulae.eu 	Staalsteden 4-3A 	KvK 08198180
Fax: 053 20 30 271 	www.netbulae.eu 	7547 TA Enschede 	BTW NL821234584B01

Gwmngilfen · October 28, 2016, 12:13pm

HTTPConflict is the issue - it's failing to save the change to the host
when the built call comes in.

Put Foreman into debug logs and tail production.log when the host gets to
the end of the install (or when you curl it) - hopefully it'll say what the
conflict is.

Greg

Jorick_Astrego1 · October 28, 2016, 1:33pm

> HTTPConflict is the issue - it's failing to save the change to the
> host when the built call comes in.
>
> Put Foreman into debug logs and tail production.log when the host gets
> to the end of the install (or when you curl it) - hopefully it'll say
> what the conflict is.
>
> Greg
>

2016-10-28 15:18:10 [app] [D] With body: []
>
2016-10-28 15:18:11 [app] [I] Started GET
"/?token=abfb3d43-4a2b-4aee-bfed-719dcc3711ff" for ... at 2016-10-28
15:18:11 +0200
2016-10-28 15:18:11 [app] [I] Processing by DashboardController#index as /
2016-10-28 15:18:11 [app] [I] Parameters:
{"token"=>"abfb3d43-4a2b-4aee-bfed-719dcc3711ff"}
2016-10-28 15:18:11 [app] [I] Authorized user
capsule.othernet.test(capsule.othernet.test)
2016-10-28 15:18:11 [app] [D] Setting current user thread-local variable
to capsule.othernet.test
2016-10-28 15:18:11 [app] [D] Setting current organization thread-local
variable to none
2016-10-28 15:18:11 [app] [D] Setting current location thread-local
variable to none
2016-10-28 15:18:11 [app] [I] Redirected to
https://capsule.othernet.test:8443/users/8-capsule-othernet-test/edit
2016-10-28 15:18:11 [app] [I] Filter chain halted as :require_mail
rendered or redirected
2016-10-28 15:18:11 [app] [I] Completed 302 Found in 185ms
(ActiveRecord: 15.2ms)
2016-10-28 15:18:11 [app] [I] Started GET
"/users/8-capsule-othernet-test/edit" for 10.101.101.24 at 2016-10-28
15:18:11 +0200
2016-10-28 15:18:11 [app] [I] Processing by UsersController#edit as /
2016-10-28 15:18:11 [app] [I] Parameters:
{"id"=>"8-capsule-othernet-test"}
2016-10-28 15:18:11 [app] [D] Setting current user thread-local variable
to capsule.othernet.test
2016-10-28 15:18:11 [app] [D] Setting current organization thread-local
variable to none
2016-10-28 15:18:11 [app] [D] Setting current location thread-local
variable to none
2016-10-28 15:18:12 [app] [I] Rendered common/_edit_habtm.html.erb (2.7ms)
2016-10-28 15:18:12 [app] [I] Rendered
taxonomies/_loc_org_tabs.html.erb (3.9ms)
2016-10-28 15:18:12 [app] [I] Rendered users/_form.html.erb (84.6ms)
2016-10-28 15:18:12 [app] [I] Rendered users/edit.html.erb within
layouts/application (89.7ms)
2016-10-28 15:18:12 [app] [I] Rendered
layouts/_application_content.html.erb (1.8ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_user_dropdown.html.erb
(53.3ms)
2016-10-28 15:18:12 [app] [I] Read fragment
views/tabs_and_title_records-8 (0.2ms)
2016-10-28 15:18:12 [app] [I] Rendered
home/_organization_dropdown.html.erb (18.3ms)
2016-10-28 15:18:12 [app] [I] Rendered
home/_location_dropdown.html.erb (17.0ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_org_switcher.html.erb
(44.8ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb (3.7ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb (2.4ms)
2016-10-28 15:18:12 [app] [I] Write fragment
views/tabs_and_title_records-8 (1.0ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_topbar.html.erb (284.5ms)
2016-10-28 15:18:12 [app] [I] Rendered layouts/base.html.erb (291.9ms)
2016-10-28 15:18:12 [app] [I] Completed 200 OK in 465ms (Views: 398.9ms
> ActiveRecord: 18.0ms)
2016-10-28 15:18:12 [app] [I] Started GET
"/unattended/built?token=abfb3d43-4a2b-4aee-bfed-719dcc3711ff&url=http%3A%2F%2Fcapsule.othernet.test%3A8000"
for capsule.othernet.test at 2016-10-28 15:18:12 +0200
2016-10-28 15:18:12 [app] [I] Processing by UnattendedController#built
as TEXT
2016-10-28 15:18:12 [app] [I] Parameters:
{"token"=>"abfb3d43-4a2b-4aee-bfed-719dcc3711ff",
"url"=>"http://capsule.othernet.test:8000", "unattended"=>{}}
2016-10-28 15:18:12 [app] [D] Found rywehygweary.othernet.test:
2016-10-28 15:18:12 [app] [D] Setting current user thread-local variable
to foreman_api_admin
2016-10-28 15:18:12 [app] [I] unattended: rywehygweary.othernet.test: is
Built!
2016-10-28 15:18:12 [app] [D] Built notice from ..., current host ip
is ..., updating
2016-10-28 15:18:13 [app] [I] Completed 409 Conflict in 922ms
(ActiveRecord: 36.7ms)

Met vriendelijke groet, With kind regards,

Jorick Astrego

Netbulae Virtualization Experts

···

On 10/28/2016 02:13 PM, Greg Sutcliffe wrote:

Tel: 053 20 30 270 	info@netbulae.eu 	Staalsteden 4-3A 	KvK 08198180
Fax: 053 20 30 271 	www.netbulae.eu 	7547 TA Enschede 	BTW NL821234584B01

Jorick_Astrego1 · October 28, 2016, 1:47pm

Hmm, it appears there is a delay as the host is now "installed" and the
pxelinux.cfg has changed to boot from local 2 minutes ago.

So when I power down the vm after provisioning, wait a couple of minutes
and boot it up again; it appears to work.

Met vriendelijke groet, With kind regards,

Jorick Astrego

Netbulae Virtualization Experts

···

On 10/28/2016 03:33 PM, Jorick Astrego wrote: > > > On 10/28/2016 02:13 PM, Greg Sutcliffe wrote: >> HTTPConflict is the issue - it's failing to save the change to the >> host when the built call comes in. >> >> Put Foreman into debug logs and tail production.log when the host >> gets to the end of the install (or when you curl it) - hopefully >> it'll say what the conflict is. >> >> Greg >> > > 2016-10-28 15:18:10 [app] [D] With body: [] > > > 2016-10-28 15:18:11 [app] [I] Started GET > "/?token=abfb3d43-4a2b-4aee-bfed-719dcc3711ff" for *.*.*.* at > 2016-10-28 15:18:11 +0200 > 2016-10-28 15:18:11 [app] [I] Processing by DashboardController#index > as */* > 2016-10-28 15:18:11 [app] [I] Parameters: > {"token"=>"abfb3d43-4a2b-4aee-bfed-719dcc3711ff"} > 2016-10-28 15:18:11 [app] [I] Authorized user > capsule.othernet.test(capsule.othernet.test) > 2016-10-28 15:18:11 [app] [D] Setting current user thread-local > variable to capsule.othernet.test > 2016-10-28 15:18:11 [app] [D] Setting current organization > thread-local variable to none > 2016-10-28 15:18:11 [app] [D] Setting current location thread-local > variable to none > 2016-10-28 15:18:11 [app] [I] Redirected to > https://capsule.othernet.test:8443/users/8-capsule-othernet-test/edit > 2016-10-28 15:18:11 [app] [I] Filter chain halted as :require_mail > rendered or redirected > 2016-10-28 15:18:11 [app] [I] Completed 302 Found in 185ms > (ActiveRecord: 15.2ms) > 2016-10-28 15:18:11 [app] [I] Started GET > "/users/8-capsule-othernet-test/edit" for 10.101.101.24 at 2016-10-28 > 15:18:11 +0200 > 2016-10-28 15:18:11 [app] [I] Processing by UsersController#edit as */* > 2016-10-28 15:18:11 [app] [I] Parameters: > {"id"=>"8-capsule-othernet-test"} > 2016-10-28 15:18:11 [app] [D] Setting current user thread-local > variable to capsule.othernet.test > 2016-10-28 15:18:11 [app] [D] Setting current organization > thread-local variable to none > 2016-10-28 15:18:11 [app] [D] Setting current location thread-local > variable to none > 2016-10-28 15:18:12 [app] [I] Rendered common/_edit_habtm.html.erb > (2.7ms) > 2016-10-28 15:18:12 [app] [I] Rendered > taxonomies/_loc_org_tabs.html.erb (3.9ms) > 2016-10-28 15:18:12 [app] [I] Rendered users/_form.html.erb (84.6ms) > 2016-10-28 15:18:12 [app] [I] Rendered users/edit.html.erb within > layouts/application (89.7ms) > 2016-10-28 15:18:12 [app] [I] Rendered > layouts/_application_content.html.erb (1.8ms) > 2016-10-28 15:18:12 [app] [I] Rendered home/_user_dropdown.html.erb > (53.3ms) > 2016-10-28 15:18:12 [app] [I] Read fragment > views/tabs_and_title_records-8 (0.2ms) > 2016-10-28 15:18:12 [app] [I] Rendered > home/_organization_dropdown.html.erb (18.3ms) > 2016-10-28 15:18:12 [app] [I] Rendered > home/_location_dropdown.html.erb (17.0ms) > 2016-10-28 15:18:12 [app] [I] Rendered home/_org_switcher.html.erb > (44.8ms) > 2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb (3.7ms) > 2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb (2.4ms) > 2016-10-28 15:18:12 [app] [I] Write fragment > views/tabs_and_title_records-8 (1.0ms) > 2016-10-28 15:18:12 [app] [I] Rendered home/_topbar.html.erb (284.5ms) > 2016-10-28 15:18:12 [app] [I] Rendered layouts/base.html.erb (291.9ms) > 2016-10-28 15:18:12 [app] [I] Completed 200 OK in 465ms (Views: > 398.9ms | ActiveRecord: 18.0ms) > 2016-10-28 15:18:12 [app] [I] Started GET > "/unattended/built?token=abfb3d43-4a2b-4aee-bfed-719dcc3711ff&url=http%3A%2F%2Fcapsule.othernet.test%3A8000" > for capsule.othernet.test at 2016-10-28 15:18:12 +0200 > 2016-10-28 15:18:12 [app] [I] Processing by UnattendedController#built > as TEXT > 2016-10-28 15:18:12 [app] [I] Parameters: > {"token"=>"abfb3d43-4a2b-4aee-bfed-719dcc3711ff", > "url"=>"http://capsule.othernet.test:8000", "unattended"=>{}} > 2016-10-28 15:18:12 [app] [D] Found rywehygweary.othernet.test: > 2016-10-28 15:18:12 [app] [D] Setting current user thread-local > variable to foreman_api_admin > 2016-10-28 15:18:12 [app] [I] unattended: rywehygweary.othernet.test: > is Built! > 2016-10-28 15:18:12 [app] [D] Built notice from *.*.*.*, current host > ip is *.*.*.*, updating > 2016-10-28 15:18:13 [app] [I] Completed 409 Conflict in 922ms > (ActiveRecord: 36.7ms) > ----------------

Tel: 053 20 30 270 	info@netbulae.eu 	Staalsteden 4-3A 	KvK 08198180
Fax: 053 20 30 271 	www.netbulae.eu 	7547 TA Enschede 	BTW NL821234584B01

Jorick_Astrego1 · October 28, 2016, 2:41pm

>
>
>>
>>
>>> HTTPConflict is the issue - it's failing to save the change to the
>>> host when the built call comes in.
>>>
>>> Put Foreman into debug logs and tail production.log when the host
>>> gets to the end of the install (or when you curl it) - hopefully
>>> it'll say what the conflict is.
>>>
>>> Greg
>>>
>>
>> 2016-10-28 15:18:10 [app] [D] With body: []
>> >
>> 2016-10-28 15:18:11 [app] [I] Started GET
>> "/?token=abfb3d43-4a2b-4aee-bfed-719dcc3711ff" for ... at
>> 2016-10-28 15:18:11 +0200
>> 2016-10-28 15:18:11 [app] [I] Processing by DashboardController#index
>> as /
>> 2016-10-28 15:18:11 [app] [I] Parameters:
>> {"token"=>"abfb3d43-4a2b-4aee-bfed-719dcc3711ff"}
>> 2016-10-28 15:18:11 [app] [I] Authorized user
>> capsule.othernet.test(capsule.othernet.test)
>> 2016-10-28 15:18:11 [app] [D] Setting current user thread-local
>> variable to capsule.othernet.test
>> 2016-10-28 15:18:11 [app] [D] Setting current organization
>> thread-local variable to none
>> 2016-10-28 15:18:11 [app] [D] Setting current location thread-local
>> variable to none
>> 2016-10-28 15:18:11 [app] [I] Redirected to
>> https://capsule.othernet.test:8443/users/8-capsule-othernet-test/edit
>> 2016-10-28 15:18:11 [app] [I] Filter chain halted as :require_mail
>> rendered or redirected
>> 2016-10-28 15:18:11 [app] [I] Completed 302 Found in 185ms
>> (ActiveRecord: 15.2ms)
>> 2016-10-28 15:18:11 [app] [I] Started GET
>> "/users/8-capsule-othernet-test/edit" for 10.101.101.24 at 2016-10-28
>> 15:18:11 +0200
>> 2016-10-28 15:18:11 [app] [I] Processing by UsersController#edit as /
>> 2016-10-28 15:18:11 [app] [I] Parameters:
>> {"id"=>"8-capsule-othernet-test"}
>> 2016-10-28 15:18:11 [app] [D] Setting current user thread-local
>> variable to capsule.othernet.test
>> 2016-10-28 15:18:11 [app] [D] Setting current organization
>> thread-local variable to none
>> 2016-10-28 15:18:11 [app] [D] Setting current location thread-local
>> variable to none
>> 2016-10-28 15:18:12 [app] [I] Rendered common/_edit_habtm.html.erb
>> (2.7ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered
>> taxonomies/_loc_org_tabs.html.erb (3.9ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered users/_form.html.erb (84.6ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered users/edit.html.erb within
>> layouts/application (89.7ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered
>> layouts/_application_content.html.erb (1.8ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered home/_user_dropdown.html.erb
>> (53.3ms)
>> 2016-10-28 15:18:12 [app] [I] Read fragment
>> views/tabs_and_title_records-8 (0.2ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered
>> home/_organization_dropdown.html.erb (18.3ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered
>> home/_location_dropdown.html.erb (17.0ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered home/_org_switcher.html.erb
>> (44.8ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb (3.7ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb (2.4ms)
>> 2016-10-28 15:18:12 [app] [I] Write fragment
>> views/tabs_and_title_records-8 (1.0ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered home/_topbar.html.erb (284.5ms)
>> 2016-10-28 15:18:12 [app] [I] Rendered layouts/base.html.erb (291.9ms)
>> 2016-10-28 15:18:12 [app] [I] Completed 200 OK in 465ms (Views:
>> 398.9ms | ActiveRecord: 18.0ms)
>> 2016-10-28 15:18:12 [app] [I] Started GET
>> "/unattended/built?token=abfb3d43-4a2b-4aee-bfed-719dcc3711ff&url=http%3A%2F%2Fcapsule.othernet.test%3A8000"
>> for capsule.othernet.test at 2016-10-28 15:18:12 +0200
>> 2016-10-28 15:18:12 [app] [I] Processing by
>> UnattendedController#built as TEXT
>> 2016-10-28 15:18:12 [app] [I] Parameters:
>> {"token"=>"abfb3d43-4a2b-4aee-bfed-719dcc3711ff",
>> "url"=>"http://capsule.othernet.test:8000", "unattended"=>{}}
>> 2016-10-28 15:18:12 [app] [D] Found rywehygweary.othernet.test:
>> 2016-10-28 15:18:12 [app] [D] Setting current user thread-local
>> variable to foreman_api_admin
>> 2016-10-28 15:18:12 [app] [I] unattended: rywehygweary.othernet.test:
>> is Built!
>> 2016-10-28 15:18:12 [app] [D] Built notice from ..., current host
>> ip is ..., updating
>> 2016-10-28 15:18:13 [app] [I] Completed 409 Conflict in 922ms
>> (ActiveRecord: 36.7ms)
>>
> Hmm, it appears there is a delay as the host is now "installed" and
> the pxelinux.cfg has changed to boot from local 2 minutes ago.
>
> So when I power down the vm after provisioning, wait a couple of
> minutes and boot it up again; it appears to work.

Sorry for the confusion but that wasn't it, my co-worker pressed "cancel
build" in between so then it gets in the state "installed" and boot from
local disk.

So still the same problem

2016-10-28 16:35:33 [app] [I] Started GET
"/unattended/built?token=857af846-3824-4651-a2bf-70b5eab6ad61&url=http%3A%2F%2Fcapsule.othernet.test%3A8000"
for capsule.othernet.test at 2016-10-28 16:35:33 +0200
2016-10-28 16:35:33 [app] [I] Processing by UnattendedController#built
as TEXT
2016-10-28 16:35:33 [app] [I] Parameters:
{"token"=>"857af846-3824-4651-a2bf-70b5eab6ad61",
"url"=>"http://capsule.othernet.test:8000", "unattended"=>{}}
2016-10-28 16:35:33 [app] [D] Found 12345test.othernet.test
2016-10-28 16:35:33 [app] [D] Setting current user thread-local variable
to foreman_api_admin
2016-10-28 16:35:33 [app] [I] unattended: 12345test.othernet.test is Built!
2016-10-28 16:35:33 [app] [D] Built notice from ..., current host ip
is ..., updating
2016-10-28 16:35:34 [app] [I] Completed 409 Conflict in 955ms
(ActiveRecord: 38.6ms)

Status Warning
Build Pending installation
Configuration No changes
Errata Could not calculate errata status, ensure host is registered and
katello-agent is installed
Subscription Fully entitled

>
>

Met vriendelijke groet, With kind regards,

Jorick Astrego

Netbulae Virtualization Experts

···

On 10/28/2016 03:47 PM, Jorick Astrego wrote: > On 10/28/2016 03:33 PM, Jorick Astrego wrote: >> On 10/28/2016 02:13 PM, Greg Sutcliffe wrote:

Tel: 053 20 30 270 	info@netbulae.eu 	Staalsteden 4-3A 	KvK 08198180
Fax: 053 20 30 271 	www.netbulae.eu 	7547 TA Enschede 	BTW NL821234584B01

lzap · October 31, 2016, 5:14pm

We recently found a bug in 1.13 when NICs aren't validated correctly, which
causes built call to fail to save. Symtoms are similar, return value is 201
not 409.

Can you check the host and edit it submitting it back without any chances?
Do you see any errors?

LZ

···

On Fri, Oct 28, 2016 at 4:41 PM, Jorick Astrego wrote:

On 10/28/2016 03:47 PM, Jorick Astrego wrote:

On 10/28/2016 03:33 PM, Jorick Astrego wrote:

On 10/28/2016 02:13 PM, Greg Sutcliffe wrote:

HTTPConflict is the issue - it’s failing to save the change to the host
when the built call comes in.

Put Foreman into debug logs and tail production.log when the host gets to
the end of the install (or when you curl it) - hopefully it’ll say what the
conflict is.

Greg

2016-10-28 15:18:10 [app] [D] With body: []

2016-10-28 15:18:11 [app] [I] Started GET "/?token=abfb3d43-4a2b-4aee-bfed-719dcc3711ff"
for ... at 2016-10-28 15:18:11 +0200
2016-10-28 15:18:11 [app] [I] Processing by DashboardController#index as
/
2016-10-28 15:18:11 [app] [I] Parameters: {“token”=>“abfb3d43-4a2b-4aee-bfed-719dcc3711ff”}

2016-10-28 15:18:11 [app] [I] Authorized user
capsule.othernet.test(capsule.othernet.test)
2016-10-28 15:18:11 [app] [D] Setting current user thread-local variable
to capsule.othernet.test
2016-10-28 15:18:11 [app] [D] Setting current organization thread-local
variable to none
2016-10-28 15:18:11 [app] [D] Setting current location thread-local
variable to none
2016-10-28 15:18:11 [app] [I] Redirected to https://capsule.othernet.test:
8443/users/8-capsule-othernet-test/edit
2016-10-28 15:18:11 [app] [I] Filter chain halted as :require_mail
rendered or redirected
2016-10-28 15:18:11 [app] [I] Completed 302 Found in 185ms (ActiveRecord:
15.2ms)
2016-10-28 15:18:11 [app] [I] Started GET "/users/8-capsule-othernet-test/edit"
for 10.101.101.24 at 2016-10-28 15:18:11 +0200
2016-10-28 15:18:11 [app] [I] Processing by UsersController#edit as /
2016-10-28 15:18:11 [app] [I] Parameters: {“id”=>“8-capsule-othernet-test”}

2016-10-28 15:18:11 [app] [D] Setting current user thread-local variable
to capsule.othernet.test
2016-10-28 15:18:11 [app] [D] Setting current organization thread-local
variable to none
2016-10-28 15:18:11 [app] [D] Setting current location thread-local
variable to none
2016-10-28 15:18:12 [app] [I] Rendered common/_edit_habtm.html.erb
(2.7ms)
2016-10-28 15:18:12 [app] [I] Rendered taxonomies/_loc_org_tabs.html.erb
(3.9ms)
2016-10-28 15:18:12 [app] [I] Rendered users/_form.html.erb (84.6ms)
2016-10-28 15:18:12 [app] [I] Rendered users/edit.html.erb within
layouts/application (89.7ms)
2016-10-28 15:18:12 [app] [I] Rendered layouts/_application_content.html.erb
(1.8ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_user_dropdown.html.erb
(53.3ms)
2016-10-28 15:18:12 [app] [I] Read fragment views/tabs_and_title_records-8
(0.2ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_organization_dropdown.html.erb
(18.3ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_location_dropdown.html.erb
(17.0ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_org_switcher.html.erb
(44.8ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb (3.7ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb (2.4ms)
2016-10-28 15:18:12 [app] [I] Write fragment
views/tabs_and_title_records-8 (1.0ms)
2016-10-28 15:18:12 [app] [I] Rendered home/_topbar.html.erb (284.5ms)
2016-10-28 15:18:12 [app] [I] Rendered layouts/base.html.erb (291.9ms)
2016-10-28 15:18:12 [app] [I] Completed 200 OK in 465ms (Views: 398.9ms |
ActiveRecord: 18.0ms)
2016-10-28 15:18:12 [app] [I] Started GET “/unattended/built?token=
abfb3d43-4a2b-4aee-bfed-719dcc3711ff&url=http%3A%2F%
2Fcapsule.othernet.test%3A8000” for capsule.othernet.test at 2016-10-28
15:18:12 +0200
2016-10-28 15:18:12 [app] [I] Processing by UnattendedController#built as
TEXT
2016-10-28 15:18:12 [app] [I] Parameters: {“token”=>“abfb3d43-4a2b-4aee-bfed-719dcc3711ff”,
“url”=>"http://capsule.othernet.test:8000"
http://capsule.othernet.test:8000, “unattended”=>{}}
2016-10-28 15:18:12 [app] [D] Found rywehygweary.othernet.test:
2016-10-28 15:18:12 [app] [D] Setting current user thread-local variable
to foreman_api_admin
2016-10-28 15:18:12 [app] [I] unattended: rywehygweary.othernet.test: is
Built!
2016-10-28 15:18:12 [app] [D] Built notice from ..., current host ip
is ..., updating
2016-10-28 15:18:13 [app] [I] Completed 409 Conflict in 922ms
(ActiveRecord: 36.7ms)

Hmm, it appears there is a delay as the host is now “installed” and the
pxelinux.cfg has changed to boot from local 2 minutes ago.

So when I power down the vm after provisioning, wait a couple of minutes
and boot it up again; it appears to work.

Sorry for the confusion but that wasn’t it, my co-worker pressed “cancel
build” in between so then it gets in the state “installed” and boot from
local disk.

So still the same problem

2016-10-28 16:35:33 [app] [I] Started GET “/unattended/built?token=
857af846-3824-4651-a2bf-70b5eab6ad61&url=http%3A%2F%
2Fcapsule.othernet.test%3A8000” for capsule.othernet.test at 2016-10-28
16:35:33 +0200
2016-10-28 16:35:33 [app] [I] Processing by UnattendedController#built as
TEXT
2016-10-28 16:35:33 [app] [I] Parameters: {“token”=>“857af846-3824-4651-a2bf-70b5eab6ad61”,
“url”=>"http://capsule.othernet.test:8000"
http://capsule.othernet.test:8000, “unattended”=>{}}
2016-10-28 16:35:33 [app] [D] Found 12345test.othernet.test
2016-10-28 16:35:33 [app] [D] Setting current user thread-local variable
to foreman_api_admin
2016-10-28 16:35:33 [app] [I] unattended: 12345test.othernet.test is Built!
2016-10-28 16:35:33 [app] [D] Built notice from ..., current host ip
is ..., updating
2016-10-28 16:35:34 [app] [I] Completed 409 Conflict in 955ms
(ActiveRecord: 38.6ms)

Status Warning
Build Pending installation
Configuration No changes
Errata Could not calculate errata status, ensure host is registered and
katello-agent is installed
Subscription Fully entitled

Met vriendelijke groet, With kind regards,

Jorick Astrego

*Netbulae Virtualization Experts *

Tel: 053 20 30 270 info@netbulae.eu Staalsteden 4-3A KvK 08198180
Fax: 053 20 30 271 www.netbulae.eu 7547 TA Enschede BTW NL821234584B01

–
You received this message because you are subscribed to the Google Groups
"Foreman users" group.
To unsubscribe from this group and stop receiving emails from it, send an
email to foreman-users+unsubscribe@googlegroups.com.
To post to this group, send email to foreman-users@googlegroups.com.
Visit this group at https://groups.google.com/group/foreman-users.
For more options, visit https://groups.google.com/d/optout.

–
Later,
Lukas @lzap Zapletal

Jorick_Astrego1 · November 2, 2016, 9:37am

> We recently found a bug in 1.13 when NICs aren't validated correctly,
> which causes built call to fail to save. Symtoms are similar, return
> value is 201 not 409.
>
> Can you check the host and edit it submitting it back without any
> chances? Do you see any errors?
>
> LZ

I edited the host and submit it again, I only get the warning the dhcp
record already exists the first time. After that editing and saving
doesn't give any errors.

>
>
>
>
>>
>>
>>>
>>>
>>>> HTTPConflict is the issue - it's failing to save the change to
>>>> the host when the built call comes in.
>>>>
>>>> Put Foreman into debug logs and tail production.log when the
>>>> host gets to the end of the install (or when you curl it) -
>>>> hopefully it'll say what the conflict is.
>>>>
>>>> Greg
>>>>
>>>
>>> 2016-10-28 15:18:10 [app] [D] With body: []
>>> >
>>> 2016-10-28 15:18:11 [app] [I] Started GET
>>> "/?token=abfb3d43-4a2b-4aee-bfed-719dcc3711ff" for ... at
>>> 2016-10-28 15:18:11 +0200
>>> 2016-10-28 15:18:11 [app] [I] Processing by
>>> DashboardController#index as /
>>> 2016-10-28 15:18:11 [app] [I] Parameters:
>>> {"token"=>"abfb3d43-4a2b-4aee-bfed-719dcc3711ff"}
>>> 2016-10-28 15:18:11 [app] [I] Authorized user
>>> capsule.othernet.test(capsule.othernet.test)
>>> 2016-10-28 15:18:11 [app] [D] Setting current user thread-local
>>> variable to capsule.othernet.test
>>> 2016-10-28 15:18:11 [app] [D] Setting current organization
>>> thread-local variable to none
>>> 2016-10-28 15:18:11 [app] [D] Setting current location
>>> thread-local variable to none
>>> 2016-10-28 15:18:11 [app] [I] Redirected to
>>> https://capsule.othernet.test:8443/users/8-capsule-othernet-test/edit
>>> <https://capsule.othernet.test:8443/users/8-capsule-othernet-test/edit>
>>>
>>> 2016-10-28 15:18:11 [app] [I] Filter chain halted as
>>> :require_mail rendered or redirected
>>> 2016-10-28 15:18:11 [app] [I] Completed 302 Found in 185ms
>>> (ActiveRecord: 15.2ms)
>>> 2016-10-28 15:18:11 [app] [I] Started GET
>>> "/users/8-capsule-othernet-test/edit" for 10.101.101.24 at
>>> 2016-10-28 15:18:11 +0200
>>> 2016-10-28 15:18:11 [app] [I] Processing by UsersController#edit
>>> as /
>>> 2016-10-28 15:18:11 [app] [I] Parameters:
>>> {"id"=>"8-capsule-othernet-test"}
>>> 2016-10-28 15:18:11 [app] [D] Setting current user thread-local
>>> variable to capsule.othernet.test
>>> 2016-10-28 15:18:11 [app] [D] Setting current organization
>>> thread-local variable to none
>>> 2016-10-28 15:18:11 [app] [D] Setting current location
>>> thread-local variable to none
>>> 2016-10-28 15:18:12 [app] [I] Rendered
>>> common/_edit_habtm.html.erb (2.7ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered
>>> taxonomies/_loc_org_tabs.html.erb (3.9ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered users/_form.html.erb
>>> (84.6ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered users/edit.html.erb
>>> within layouts/application (89.7ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered
>>> layouts/_application_content.html.erb (1.8ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered
>>> home/_user_dropdown.html.erb (53.3ms)
>>> 2016-10-28 15:18:12 [app] [I] Read fragment
>>> views/tabs_and_title_records-8 (0.2ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered
>>> home/_organization_dropdown.html.erb (18.3ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered
>>> home/_location_dropdown.html.erb (17.0ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered
>>> home/_org_switcher.html.erb (44.8ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb
>>> (3.7ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered home/_submenu.html.erb
>>> (2.4ms)
>>> 2016-10-28 15:18:12 [app] [I] Write fragment
>>> views/tabs_and_title_records-8 (1.0ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered home/_topbar.html.erb
>>> (284.5ms)
>>> 2016-10-28 15:18:12 [app] [I] Rendered layouts/base.html.erb
>>> (291.9ms)
>>> 2016-10-28 15:18:12 [app] [I] Completed 200 OK in 465ms (Views:
>>> 398.9ms | ActiveRecord: 18.0ms)
>>> 2016-10-28 15:18:12 [app] [I] Started GET
>>> "/unattended/built?token=abfb3d43-4a2b-4aee-bfed-719dcc3711ff&url=http%3A%2F%2Fcapsule.othernet.test%3A8000"
>>> for capsule.othernet.test at 2016-10-28 15:18:12 +0200
>>> 2016-10-28 15:18:12 [app] [I] Processing by
>>> UnattendedController#built as TEXT
>>> 2016-10-28 15:18:12 [app] [I] Parameters:
>>> {"token"=>"abfb3d43-4a2b-4aee-bfed-719dcc3711ff",
>>> "url"=>"http://capsule.othernet.test:8000"
>>> <http://capsule.othernet.test:8000>, "unattended"=>{}}
>>> 2016-10-28 15:18:12 [app] [D] Found rywehygweary.othernet.test:
>>> 2016-10-28 15:18:12 [app] [D] Setting current user thread-local
>>> variable to foreman_api_admin
>>> 2016-10-28 15:18:12 [app] [I] unattended:
>>> rywehygweary.othernet.test: is Built!
>>> 2016-10-28 15:18:12 [app] [D] Built notice from ..., current
>>> host ip is ..., updating
>>> 2016-10-28 15:18:13 [app] [I] Completed 409 Conflict in 922ms
>>> (ActiveRecord: 36.7ms)
>>>
>> Hmm, it appears there is a delay as the host is now "installed"
>> and the pxelinux.cfg has changed to boot from local 2 minutes ago.
>>
>> So when I power down the vm after provisioning, wait a couple of
>> minutes and boot it up again; it appears to work.
>
> Sorry for the confusion but that wasn't it, my co-worker pressed
> "cancel build" in between so then it gets in the state "installed"
> and boot from local disk.
>
> So still the same problem
>
> 2016-10-28 16:35:33 [app] [I] Started GET
> "/unattended/built?token=857af846-3824-4651-a2bf-70b5eab6ad61&url=http%3A%2F%2Fcapsule.othernet.test%3A8000"
> for capsule.othernet.test at 2016-10-28 16:35:33 +0200
> 2016-10-28 16:35:33 [app] [I] Processing by
> UnattendedController#built as TEXT
> 2016-10-28 16:35:33 [app] [I] Parameters:
> {"token"=>"857af846-3824-4651-a2bf-70b5eab6ad61",
> "url"=>"http://capsule.othernet.test:8000"
> <http://capsule.othernet.test:8000>, "unattended"=>{}}
> 2016-10-28 16:35:33 [app] [D] Found 12345test.othernet.test
> 2016-10-28 16:35:33 [app] [D] Setting current user thread-local
> variable to foreman_api_admin
> 2016-10-28 16:35:33 [app] [I] unattended: 12345test.othernet.test
> is Built!
> 2016-10-28 16:35:33 [app] [D] Built notice from ..., current
> host ip is ..., updating
> 2016-10-28 16:35:34 [app] [I] Completed 409 Conflict in 955ms
> (ActiveRecord: 38.6ms)
>
> Status Warning
> Build Pending installation
> Configuration No changes
> Errata Could not calculate errata status, ensure host is
> registered and katello-agent is installed
> Subscription Fully entitled
>
>
>>
>>
>
>
>
>
>
> Met vriendelijke groet, With kind regards,
>
> Jorick Astrego
> *
> Netbulae Virtualization Experts *
> ------------------------------------------------------------------------
> Tel: 053 20 30 270 info@netbulae.eu <mailto:info@netbulae.eu>
> Staalsteden 4-3A KvK 08198180
> Fax: 053 20 30 271 www.netbulae.eu <http://www.netbulae.eu> 7547
> TA Enschede BTW NL821234584B01
>
>
> ------------------------------------------------------------------------
>
> –
> You received this message because you are subscribed to the Google
> Groups "Foreman users" group.
> To unsubscribe from this group and stop receiving emails from it,
> send an email to foreman-users+unsubscribe@googlegroups.com
> <mailto:foreman-users+unsubscribe@googlegroups.com>.
> To post to this group, send email to
> foreman-users@googlegroups.com
> <mailto:foreman-users@googlegroups.com>.
> Visit this group at https://groups.google.com/group/foreman-users
> <https://groups.google.com/group/foreman-users>.
> For more options, visit https://groups.google.com/d/optout
> <https://groups.google.com/d/optout>.
>
>
>
>
> –
> Later,
> Lukas @lzap Zapletal
> –
> You received this message because you are subscribed to the Google
> Groups "Foreman users" group.
> To unsubscribe from this group and stop receiving emails from it, send
> an email to foreman-users+unsubscribe@googlegroups.com
> <mailto:foreman-users+unsubscribe@googlegroups.com>.
> To post to this group, send email to foreman-users@googlegroups.com
> <mailto:foreman-users@googlegroups.com>.
> Visit this group at https://groups.google.com/group/foreman-users.
> For more options, visit https://groups.google.com/d/optout.

Met vriendelijke groet, With kind regards,

Jorick Astrego

Netbulae Virtualization Experts

···

On 10/31/2016 06:14 PM, Lukas Zapletal wrote: > On Fri, Oct 28, 2016 at 4:41 PM, Jorick Astrego > wrote: > On 10/28/2016 03:47 PM, Jorick Astrego wrote: >> On 10/28/2016 03:33 PM, Jorick Astrego wrote: >>> On 10/28/2016 02:13 PM, Greg Sutcliffe wrote:

Tel: 053 20 30 270 	info@netbulae.eu 	Staalsteden 4-3A 	KvK 08198180
Fax: 053 20 30 271 	www.netbulae.eu 	7547 TA Enschede 	BTW NL821234584B01

Net::HTTPConflict error when provisioning a host from capsule

*Netbulae Virtualization Experts *

Tel: 053 20 30 270 info@netbulae.eu Staalsteden 4-3A KvK 08198180 Fax: 053 20 30 271 www.netbulae.eu 7547 TA Enschede BTW NL821234584B01

Netbulae Virtualization Experts

Tel: 053 20 30 270 info@netbulae.eu Staalsteden 4-3A KvK 08198180
Fax: 053 20 30 271 www.netbulae.eu 7547 TA Enschede BTW NL821234584B01