Bug 1446409 - Service : PXE provisioning for RHEV fails
Summary: Service : PXE provisioning for RHEV fails
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: Red Hat CloudForms Management Engine
Classification: Red Hat
Component: Provisioning
Version: 5.8.0
Hardware: Unspecified
OS: Unspecified
high
high
Target Milestone: GA
: 5.9.0
Assignee: Boriso
QA Contact: Shveta
URL:
Whiteboard: service:pxe:rhev:provision
Depends On:
Blocks: 1447382
TreeView+ depends on / blocked
 
Reported: 2017-04-27 21:49 UTC by Shveta
Modified: 2018-03-06 15:22 UTC (History)
10 users (show)

Fixed In Version: 5.9.0.1
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
: 1447382 (view as bug list)
Environment:
Last Closed: 2018-03-06 15:22:09 UTC
Category: ---
Cloudforms Team: RHEVM
Target Upstream Version:
Embargoed:


Attachments (Terms of Use)
automation logs (7.22 MB, text/plain)
2017-04-27 21:49 UTC, Shveta
no flags Details
pxe-error (16.74 KB, image/png)
2017-11-22 08:51 UTC, Oved Ourfali
no flags Details
provisioned_VM_boot_failure.png (15.50 KB, image/png)
2017-11-22 11:45 UTC, Ilanit Stein
no flags Details
rhel7.4_customize_template (2.54 KB, text/plain)
2017-11-22 11:50 UTC, Ilanit Stein
no flags Details

Description Shveta 2017-04-27 21:49:57 UTC
Created attachment 1274796 [details]
automation logs

Description of problem:


Version-Release number of selected component (if applicable):
5.8.0.12-rc1.20170425180304_4f35996 

How reproducible:


Steps to Reproduce:
1. Create a catalog item for Type RHEV and provision type = PXE
2. Fill all fields and order service . 
3. Request fails .Logs are attached.

Actual results:


Expected results:


Additional info:
Request Last message : Server [EVM] Service [TlsvaEwEKJ] Step [checkprovisioned] Status [[NameError]: uninitialized constant ManageIQ::Providers::Redhat::InfraManager::ProvisionViaPxe::StateMachine::OvirtServices] Message [[NameError]: uninitialized constant ManageIQ::Provid...

Comment 2 Dave Johnson 2017-04-28 14:34:58 UTC
Shveta, which version of RHV did you test with?

Comment 5 Shveta 2017-04-28 19:25:02 UTC
Rhevm36 , The provisioning works in 5.7 .

Comment 6 Ilanit Stein 2017-04-30 12:50:18 UTC
Error reproduced on CFME-5.8.0.12/RHV-4.0.4.4-0.1.el7ev,

Pvovision VM from PXE, fail on:

"[EVM] VM [prov_from_pxe] Step [CheckProvisioned] Status [[NameError]: uninitialized constant ManageIQ::Providers::Redhat::InfraManager::OvirtServices::VmNotReadyToBoot] Message [[NameError]: uninitialized constant ManageIQ::Providers::Redhat:..."

Comment 9 Shveta 2017-11-03 00:53:05 UTC
Not able to pxe provision on RHEV .

Logs 
=========
ision_Template%2FCatalogItemInitialization%22%3A%0A%20%20ae_state%3A%20checkprovisioned%0A%20%20ae_state_retries%3A%201%0A%20%20ae_state_started%3A%202017-11-03%2000%3A47%3A59%20UTC%0A&ae_state_retries=1&ae_state_started=2017-11-03%2000%3A47%3A59%20UTC&dialog_checkbox=t&dialog_trigger=true&object_name=CatalogItemInitialization&request=clone_to_service&service_action=Provision&vmdb_object_type=service_template_provision_task  ManageIQ/Service/Provisioning/StateMachines]
[----] I, [2017-11-02T20:49:07.792856 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Updated namespace [/Service/Provisioning/StateMachines/ServiceProvision_Template/CatalogItemInitialization?MiqServer%3A%3Amiq_server=2&Service%3A%3AService=8&ServiceTemplateProvisionTask%3A%3Aservice_template_provision_task=18&User%3A%3Auser=1&ae_state=checkprovisioned&ae_state_previous=---%0A%22%2FManageIQ%2FService%2FProvisioning%2FStateMachines%2FServiceProvision_Template%2FCatalogItemInitialization%22%3A%0A%20%20ae_state%3A%20checkprovisioned%0A%20%20ae_state_retries%3A%202%0A%20%20ae_state_started%3A%202017-11-03%2000%3A46%3A51%20UTC%0A&ae_state_retries=2&ae_state_started=2017-11-03%2000%3A46%3A51%20UTC&dialog_name=1.month&object_name=CatalogItemInitialization&request=clone_to_service&service_action=Provision&vmdb_object_type=service_template_provision_task  ManageIQ/Service/Provisioning/StateMachines]
[----] I, [2017-11-02T20:49:07.857014 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Processing State=[checkprovisioned]
[----] I, [2017-11-02T20:49:07.857299 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Following Relationship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
[----] I, [2017-11-02T20:49:08.124445 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Processing State=[checkprovisioned]
[----] I, [2017-11-02T20:49:08.124684 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Following Relationship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
[----] I, [2017-11-02T20:49:08.136822 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Updated namespace [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create  ManageIQ/Service/Provisioning/StateMachines]
[----] I, [2017-11-02T20:49:08.394914 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Updated namespace [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create  ManageIQ/Service/Provisioning/StateMachines]
[----] I, [2017-11-02T20:49:08.417128 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Updated namespace [Service/Provisioning/StateMachines/Methods/check_provisioned  ManageIQ/Service/Provisioning/StateMachines]
[----] I, [2017-11-02T20:49:08.425202 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Invoking [inline] method [/ManageIQ/Service/Provisioning/StateMachines/Methods/check_provisioned] with inputs [{}]
[----] I, [2017-11-02T20:49:08.427129 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) <AEMethod [/ManageIQ/Service/Provisioning/StateMachines/Methods/check_provisioned]> Starting 
[----] I, [2017-11-02T20:49:08.679114 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Updated namespace [Service/Provisioning/StateMachines/Methods/check_provisioned  ManageIQ/Service/Provisioning/StateMachines]
[----] I, [2017-11-02T20:49:08.687446 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Invoking [inline] method [/ManageIQ/Service/Provisioning/StateMachines/Methods/check_provisioned] with inputs [{}]
[----] I, [2017-11-02T20:49:08.689384 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) <AEMethod [/ManageIQ/Service/Provisioning/StateMachines/Methods/check_provisioned]> Starting 
[----] I, [2017-11-02T20:49:09.076419 #11776:109d480]  INFO -- : <AEMethod check_provisioned> Listing Root Object Attributes:
[----] I, [2017-11-02T20:49:09.098611 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	ae_next_state: 
[----] I, [2017-11-02T20:49:09.099762 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	ae_result: ok
[----] I, [2017-11-02T20:49:09.100772 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	ae_retry_server_affinity: false
[----] I, [2017-11-02T20:49:09.101711 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	ae_state: checkprovisioned
[----] I, [2017-11-02T20:49:09.102735 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	ae_state_max_retries: 100
[----] I, [2017-11-02T20:49:09.103670 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	ae_state_retries: 1
[----] I, [2017-11-02T20:49:09.104567 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	ae_state_started: 2017-11-03 00:47:59 UTC
[----] I, [2017-11-02T20:49:09.105667 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	ae_state_step: main
[----] I, [2017-11-02T20:49:09.106562 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	dialog_checkbox: t
[----] I, [2017-11-02T20:49:09.107459 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	dialog_trigger: true
[----] I, [2017-11-02T20:49:09.109100 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	miq_group: #<MiqAeMethodService::MiqAeServiceMiqGroup:0x00000019f8c500>
[----] I, [2017-11-02T20:49:09.110425 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	miq_server: #<MiqAeMethodService::MiqAeServiceMiqServer:0x0000001e696220>
[----] I, [2017-11-02T20:49:09.111819 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	miq_server_id: 2
[----] I, [2017-11-02T20:49:09.112684 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	object_name: CatalogItemInitialization
[----] I, [2017-11-02T20:49:09.113659 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	request: clone_to_service
[----] I, [2017-11-02T20:49:09.115358 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	service: #<MiqAeMethodService::MiqAeServiceService:0x0000001e7e9a28>
[----] I, [2017-11-02T20:49:09.116196 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	service_action: Provision
[----] I, [2017-11-02T20:49:09.117193 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	service_id: 9
[----] I, [2017-11-02T20:49:09.118819 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	service_template_provision_task: #<MiqAeMethodService::MiqAeServiceServiceTemplateProvisionTask:0x000000102fc320>
[----] I, [2017-11-02T20:49:09.119724 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	service_template_provision_task_id: 21
[----] I, [2017-11-02T20:49:09.121196 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	tenant: #<MiqAeMethodService::MiqAeServiceTenant:0x00000019f8c960>
[----] I, [2017-11-02T20:49:09.122701 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	user: #<MiqAeMethodService::MiqAeServiceUser:0x00000019f8cdc0>
[----] I, [2017-11-02T20:49:09.123717 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	user_id: 1
[----] I, [2017-11-02T20:49:09.124769 #11776:1628aa8]  INFO -- : <AEMethod check_provisioned> 	vmdb_object_type: service_template_provision_task
[----] I, [2017-11-02T20:49:09.125724 #11776:109d480]  INFO -- : <AEMethod check_provisioned> ===========================================
[----] I, [2017-11-02T20:49:09.130411 #11776:109d480]  INFO -- : <AEMethod check_provisioned> Service ProvisionCheck returned <error> for state <finished> and status <Error>
[----] I, [2017-11-02T20:49:09.159260 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) <AEMethod [/ManageIQ/Service/Provisioning/StateMachines/Methods/check_provisioned]> Ending
[----] I, [2017-11-02T20:49:09.159458 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Method exited with rc=MIQ_OK
[----] I, [2017-11-02T20:49:09.159786 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Followed  Relationship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
[----] I, [2017-11-02T20:49:09.159886 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Processed  State=[checkprovisioned] with Result=[error]
[----] W, [2017-11-02T20:49:09.159969 #11776:109d138]  WARN -- : Q-task_id([service_template_provision_task_21]) Error in State=[checkprovisioned]
[----] I, [2017-11-02T20:49:09.160092 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) In State=[checkprovisioned], invoking [on_error] method=[update_serviceprovision_status(status => 'Error Creating Service')]
[----] I, [2017-11-02T20:49:09.265685 #45111:108ed40]  INFO -- : <AEMethod check_provisioned> Listing Root Object Attributes:
[----] I, [2017-11-02T20:49:09.276168 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	ae_next_state: 
[----] I, [2017-11-02T20:49:09.277432 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	ae_result: ok
[----] I, [2017-11-02T20:49:09.278477 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	ae_retry_server_affinity: false
[----] I, [2017-11-02T20:49:09.279358 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	ae_state: checkprovisioned
[----] I, [2017-11-02T20:49:09.280270 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	ae_state_max_retries: 100
[----] I, [2017-11-02T20:49:09.281307 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	ae_state_retries: 2
[----] I, [2017-11-02T20:49:09.282401 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	ae_state_started: 2017-11-03 00:46:51 UTC
[----] I, [2017-11-02T20:49:09.283398 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	ae_state_step: main
[----] I, [2017-11-02T20:49:09.284403 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	dialog_name: 1.month
[----] I, [2017-11-02T20:49:09.285801 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	miq_group: #<MiqAeMethodService::MiqAeServiceMiqGroup:0x0000001d5bb900>
[----] I, [2017-11-02T20:49:09.287161 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	miq_server: #<MiqAeMethodService::MiqAeServiceMiqServer:0x0000001d201aa8>
[----] I, [2017-11-02T20:49:09.288130 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	miq_server_id: 2
[----] I, [2017-11-02T20:49:09.289113 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	object_name: CatalogItemInitialization
[----] I, [2017-11-02T20:49:09.290122 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	request: clone_to_service
[----] I, [2017-11-02T20:49:09.291837 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	service: #<MiqAeMethodService::MiqAeServiceService:0x0000001baa4f40>
[----] I, [2017-11-02T20:49:09.292767 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	service_action: Provision
[----] I, [2017-11-02T20:49:09.293665 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	service_id: 8
[----] I, [2017-11-02T20:49:09.295178 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	service_template_provision_task: #<MiqAeMethodService::MiqAeServiceServiceTemplateProvisionTask:0x0000001ba7f8d0>
[----] I, [2017-11-02T20:49:09.296169 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	service_template_provision_task_id: 18
[----] I, [2017-11-02T20:49:09.297553 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	tenant: #<MiqAeMethodService::MiqAeServiceTenant:0x0000001d5bbd60>
[----] I, [2017-11-02T20:49:09.298947 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	user: #<MiqAeMethodService::MiqAeServiceUser:0x0000001ba7c248>
[----] I, [2017-11-02T20:49:09.299941 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	user_id: 1
[----] I, [2017-11-02T20:49:09.300884 #45111:129d6cc]  INFO -- : <AEMethod check_provisioned> 	vmdb_object_type: service_template_provision_task
[----] I, [2017-11-02T20:49:09.301727 #45111:108ed40]  INFO -- : <AEMethod check_provisioned> ===========================================
[----] I, [2017-11-02T20:49:09.305628 #45111:108ed40]  INFO -- : <AEMethod check_provisioned> Service ProvisionCheck returned <retry> for state <active> and status <Ok>
[----] I, [2017-11-02T20:49:09.326173 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) <AEMethod [/ManageIQ/Service/Provisioning/StateMachines/Methods/check_provisioned]> Ending
[----] I, [2017-11-02T20:49:09.326323 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Method exited with rc=MIQ_OK
[----] I, [2017-11-02T20:49:09.326757 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Followed  Relationship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
[----] I, [2017-11-02T20:49:09.326844 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Processed  State=[checkprovisioned] with Result=[retry]
[----] I, [2017-11-02T20:49:09.327041 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) In State=[checkprovisioned], invoking [on_exit] method=[update_serviceprovision_status(status => 'Creating Service')]
[----] I, [2017-11-02T20:49:09.361520 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Updated namespace [Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status  UnionBank/Service/Provisioning/StateMachines]
[----] I, [2017-11-02T20:49:09.519258 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Updated namespace [Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status  UnionBank/Service/Provisioning/StateMachines]
[----] I, [2017-11-02T20:49:09.563248 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Invoking [inline] method [/UnionBank/Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status] with inputs [{"status"=>"Error Creating Service"}]
[----] I, [2017-11-02T20:49:09.564548 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) <AEMethod [/UnionBank/Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status]> Starting 
[----] I, [2017-11-02T20:49:09.730760 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Invoking [inline] method [/UnionBank/Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status] with inputs [{"status"=>"Creating Service"}]
[----] I, [2017-11-02T20:49:09.732129 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) <AEMethod [/UnionBank/Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status]> Starting 
[----] I, [2017-11-02T20:49:10.086271 #11776:109d480]  INFO -- : <AEMethod update_serviceprovision_status> PROV_LOG- Sending unsuccessful email to <jake.millette> from <cloudforms> subject: <Your Virtual Machine request is NOT completed - VM:>
[----] I, [2017-11-02T20:49:10.109140 #11776:109d480]  INFO -- : <AEMethod update_serviceprovision_status> PROV_LOG- Removed the service
[----] I, [2017-11-02T20:49:10.129877 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) <AEMethod [/UnionBank/Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status]> Ending
[----] I, [2017-11-02T20:49:10.130016 #11776:109d138]  INFO -- : Q-task_id([service_template_provision_task_21]) Method exited with rc=MIQ_OK
[----] I, [2017-11-02T20:49:10.218920 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) <AEMethod [/UnionBank/Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status]> Ending
[----] I, [2017-11-02T20:49:10.219079 #45111:109d138]  INFO -- : Q-task_id([service_template_provision_task_18]) Method exited with rc=MIQ_OK
[----] I, [2017-11-02T20:49:25.279755 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) User [admin] with current group ID [3] name [EvmGroup-super_administrator]
[----] I, [2017-11-02T20:49:25.283599 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Instantiating [/System/Process/AUTOMATION?MiqProvision%3A%3Amiq_provision=20&MiqServer%3A%3Amiq_server=2&User%3A%3Auser=1&ae_state=CheckProvisioned&ae_state_previous=---%0A%22%2FManageIQ%2FCloud%2FVM%2FProvisioning%2FStateMachines%2FVMProvision_vm%2Ftemplate%22%3A%0A%20%20ae_state%3A%20CheckProvisioned%0A%20%20ae_state_retries%3A%201%0A%20%20ae_state_started%3A%202017-11-03%2000%3A48%3A16%20UTC%0A&ae_state_retries=1&ae_state_started=2017-11-03%2000%3A48%3A16%20UTC&object_name=AUTOMATION&request=vm_provision&vmdb_object_type=miq_provision]
[----] I, [2017-11-02T20:49:25.322751 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Updated namespace [/System/Process/AUTOMATION?MiqProvision%3A%3Amiq_provision=20&MiqServer%3A%3Amiq_server=2&User%3A%3Auser=1&ae_state=CheckProvisioned&ae_state_previous=---%0A%22%2FManageIQ%2FCloud%2FVM%2FProvisioning%2FStateMachines%2FVMProvision_vm%2Ftemplate%22%3A%0A%20%20ae_state%3A%20CheckProvisioned%0A%20%20ae_state_retries%3A%201%0A%20%20ae_state_started%3A%202017-11-03%2000%3A48%3A16%20UTC%0A&ae_state_retries=1&ae_state_started=2017-11-03%2000%3A48%3A16%20UTC&object_name=AUTOMATION&request=vm_provision&vmdb_object_type=miq_provision  ManageIQ/System]
[----] I, [2017-11-02T20:49:25.413219 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Updated namespace [System/Process/parse_provider_category  ManageIQ/System]
[----] I, [2017-11-02T20:49:25.419489 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Invoking [builtin] method [/ManageIQ/System/Process/parse_provider_category] with inputs [{}]
[----] I, [2017-11-02T20:49:25.441865 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Setting provider_category to: cloud
[----] I, [2017-11-02T20:49:25.443127 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Setting prepend_namespace to: Openstack
[----] I, [2017-11-02T20:49:25.443213 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Prepend namespace [openstack] during domain search
[----] I, [2017-11-02T20:49:25.520314 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Updated namespace [System/Process/parse_automation_request  ManageIQ/System]
[----] I, [2017-11-02T20:49:25.527963 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Invoking [builtin] method [/ManageIQ/System/Process/parse_automation_request] with inputs [{}]
[----] I, [2017-11-02T20:49:25.528760 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Request:<vm_provision> Target Component:<VM> 
[----] I, [2017-11-02T20:49:25.528834 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Target Class:<Lifecycle> Target Instance:<Provisioning>
[----] I, [2017-11-02T20:49:25.529264 #45111:109d138]  INFO -- : Q-task_id([miq_provision_20]) Following Relationship [miqaedb:/cloud/VM/Lifecycle/Provisioning#create]
[----] I, [2017-11-02T20:49:25.684512



Appliance : https://10.8.198.241

Comment 11 Shveta 2017-11-08 17:52:43 UTC
Will test this in new 5.9.0.7 build and update the BZ .

Comment 12 Oved Ourfali 2017-11-20 17:11:10 UTC
Shveta, any updates?

Comment 13 Oved Ourfali 2017-11-20 17:11:43 UTC
Moving to ON_QA, as no action items at the moment on engineering.

Comment 14 Shveta 2017-11-21 00:50:25 UTC
Tried on build 5.9.8 but still didn't get successful provisioning .
Appliance : https://10.8.197.120.

Error : 
Status [Error Creating Service] Message [Error: HTTP response code is 404. The response body is empty.]

Comment 15 Boriso 2017-11-21 07:25:20 UTC
Hi Shveta, I tried and it worked for me.
Please note one has to do manual power off of the vm on the rhv side after it goes up.
From the log it looks as if the vm was deleted from the rhv side in the middle of the process.

Please write the exact steps including the template name and other configuration you used to reproduce the error.

Comment 16 Shveta 2017-11-21 17:23:30 UTC
Hi , 

The template used is  pxe_prov_template. There is a catalog item under Service - catalog Items called rhev_pxe. 
The details of the catalog item can be seen there .
I think the pxe provisioning is working , real issue as you said is , we have to manually power off the VM at rhev side . If I don't manually power it off , It either times out or fails with some other error..

For Vmware , we don't have to manually power off VM . 

In RHEV too I guess it should happen automatically , right ?

Comment 17 Oved Ourfali 2017-11-22 08:51:09 UTC
Shveta - maybe there is an issue with the image?
I connect to the console of the VM and there I see the attached image (error that the URL is incorrect).

Comment 18 Oved Ourfali 2017-11-22 08:51:37 UTC
Created attachment 1357298 [details]
pxe-error

Comment 19 Oved Ourfali 2017-11-22 09:08:10 UTC
The problem is probably in the customization template.
It relies on $url1 and $url2 variables, but not clear where those should come from. They currently are not set, so the provisioning is stuck for user input. that's why a reboot helped..... but the provisioning itself is stuck.

Shveta, where did you get this customization template from?

Comment 20 Ilanit Stein 2017-11-22 11:36:33 UTC
I tested on CFME-5.8.2.3, CFME-5.7.4.1/RHV-4.1.7.
Provision VM from PXE, along with a rhel7.4 image from pxe server.
In the provision dialog customize tab:
a rhel7.4 customized template was selected (attached "rhel7.4_customize_ template"), and in addition a root password was inserted in upper side of this dialog window. 


The result was the same for both CFME-5.7.4.1 & CFME-5.8.2.3:

The rhel7.4 was installed, then after post installation stage, 
the VM was rebooted, however after reboot, 
VM failed to load from the hard disk on: Error: No configuration file found 
(attached screen shot of the VM console)

On CFME side the provision request ended up successfully,
since it was waiting for VM power off, and that stage did take place.

Comment 21 Ilanit Stein 2017-11-22 11:45:35 UTC
Created attachment 1357419 [details]
provisioned_VM_boot_failure.png

Comment 22 Ilanit Stein 2017-11-22 11:50:40 UTC
Created attachment 1357420 [details]
rhel7.4_customize_template

Comment 23 Oved Ourfali 2017-11-22 12:05:04 UTC
The "no configuration file found" seems to me like an issue with the image.
In order to understand it better, perhaps we should try to install a VM from PXE inside RHV and see what's the result?

Comment 24 Ilanit Stein 2017-11-23 07:44:27 UTC
Moving this bug to verified, as the failure, detailed in the bug description no longer exist.

Further to the current problem we have with pxe provisioning,
detailed in comment 20, filed 
Bug 1516639 - [RHV] VM provisioned from pxe fail to boot post installation

Comment 25 Ilanit Stein 2017-11-23 10:06:32 UTC
Clearing the need info on me, since it was passed to Bug 1516639.


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