Bug 1552597

Summary: [RHV] PXE provision with Network "use template nics" fail on creating VM
Product: Red Hat CloudForms Management Engine Reporter: Ilanit Stein <istein>
Component: ProvidersAssignee: Alona Kaplan <alkaplan>
Status: CLOSED CURRENTRELEASE QA Contact: Angelina Vasileva <anikifor>
Severity: high Docs Contact:
Priority: high    
Version: 5.9.0CC: alkaplan, cpelland, gblomqui, jfrey, jhardy, mperina, obarenbo, simaishi
Target Milestone: GAKeywords: TestOnly, ZStream
Target Release: 5.10.0   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard: rhev:pxe provisioning
Fixed In Version: 5.10.0.0 Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of:
: 1561218 (view as bug list) Environment:
Last Closed: 2019-02-11 14:00:45 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: Bug
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: RHEVM Target Upstream Version:
Embargoed:
Bug Depends On:    
Bug Blocks: 1561218    
Attachments:
Description Flags
evm.log
none
evm.log
none
automation.log
none
production.log
none
rhevm.log none

Description Ilanit Stein 2018-03-07 12:22:11 UTC
Description of problem:
VM provision from pxe, with Network set to "use temlate nics" fail on Create VM state.
The provision was done using a RHEL-7.4 template with one nic on "ovirtmgmt" network.

* Provision request (under Services->Requests) last message:

"[EVM] VM [pxe_test] Step [CheckProvisioned] Status [Error Creating VM] Message [Creating VM]"

* evm.log failure:

----] I, [2018-03-07T03:21:08.678538 #32038:797138]  INFO -- : Q-task_id([miq_provision_1]) Starting Phase <create_pxe_configuration_file>
[----] I, [2018-03-07T03:21:08.693726 #32038:797138]  INFO -- : Q-task_id([miq_provision_1]) MIQ(ManageIQ::Providers::Redhat::InfraManager::ProvisionViaPxe#create_pxe_configuration_file) Generating PXE and Customization Files on PXE Server Vm id: [159], name: [pxe_test]
[----] I, [2018-03-07T03:21:08.790712 #32038:797138]  INFO -- : Q-task_id([miq_provision_1]) MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [jenkins-vm-11]
[----] W, [2018-03-07T03:21:08.843065 #32038:797138]  WARN -- : Q-task_id([miq_provision_1]) MIQ(ManageIQ::Providers::Redhat::InfraManager::OvirtServices::Strategies::V4#find_mac_address_on_network) Cannot find NIC with vnic_profile=<Template>
[----] I, [2018-03-07T03:21:08.843690 #32038:797138]  INFO -- : Q-task_id([miq_provision_1]) Starting Phase <provision_error>
[----] E, [2018-03-07T03:21:08.870942 #32038:797138] ERROR -- : Q-task_id([miq_provision_1]) MIQ(ManageIQ::Providers::Redhat::InfraManager::ProvisionViaPxe#provision_error) [[RuntimeError]: MAC Address is nil] encountered during phase [create_pxe_configuration_file]
[----] E, [2018-03-07T03:21:08.871059 #32038:797138] ERROR -- : Q-task_id([miq_provision_1]) /var/www/miq/vmdb/app/models/miq_provision/pxe.rb:62:in `create_pxe_files'
/opt/rh/cfme-gemset/bundler/gems/manageiq-providers-ovirt-bf9a9b838244/app/models/manageiq/providers/redhat/infra_manager/provision_via_pxe/state_machine.rb:14:in `create_pxe_configuration_file'
/var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:21:in `signal'
/opt/rh/cfme-gemset/bundler/gems/manageiq-providers-ovirt-bf9a9b838244/app/models/manageiq/providers/redhat/infra_manager/provision_via_pxe/state_machine.rb:7:in `customize_guest'
/var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:21:in `signal'
/opt/rh/cfme-gemset/bundler/gems/manageiq-providers-ovirt-bf9a9b838244/app/models/manageiq/providers/redhat/infra_manager/provision/state_machine.rb:57:in `configure_disks'
/var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:21:in `signal'
/opt/rh/cfme-gemset/bundler/gems/manageiq-providers-ovirt-bf9a9b838244/app/models/manageiq/providers/redhat/infra_manager/provision/state_machine.rb:45:in `customize_destination'
/var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:21:in `signal'
/var/www/miq/vmdb/app/models/miq_provision/state_machine.rb:50:in `poll_destination_in_vmdb'
/var/www/miq/vmdb/app/models/miq_queue.rb:449:in `block in dispatch_method'
/opt/rh/rh-ruby23/root/usr/share/ruby/timeout.rb:91:in `block in timeout'
/opt/rh/rh-ruby23/root/usr/share/ruby/timeout.rb:33:in `block in catch'
/opt/rh/rh-ruby23/root/usr/share/ruby/timeout.rb:33:in `catch'
/opt/rh/rh-ruby23/root/usr/share/ruby/timeout.rb:33:in `catch'
/opt/rh/rh-ruby23/root/usr/share/ruby/timeout.rb:106:in `timeout'
/var/www/miq/vmdb/app/models/miq_queue.rb:448:in `dispatch_method'
/var/www/miq/vmdb/app/models/miq_queue.rb:425:in `block in deliver'
/var/www/miq/vmdb/app/models/user.rb:261:in `with_user_group'
/var/www/miq/vmdb/app/models/miq_queue.rb:425:in `deliver'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:104:in `deliver_queue_message'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:134:in `deliver_message'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:152:in `block in do_work'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:146:in `loop'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:146:in `do_work'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:329:in `block in do_work_loop'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:326:in `loop'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:326:in `do_work_loop'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:153:in `run'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:127:in `start'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:22:in `start_worker'
/var/www/miq/vmdb/app/models/miq_worker.rb:375:in `block in start_runner_via_fork'
/opt/rh/cfme-gemset/gems/nakayoshi_fork-0.0.3/lib/nakayoshi_fork.rb:24:in `fork'
/opt/rh/cfme-gemset/gems/nakayoshi_fork-0.0.3/lib/nakayoshi_fork.rb:24:in `fork'
/var/www/miq/vmdb/app/models/miq_worker.rb:373:in `start_runner_via_fork'
/var/www/miq/vmdb/app/models/miq_worker.rb:367:in `start_runner'
/var/www/miq/vmdb/app/models/miq_worker.rb:414:in `start'
/var/www/miq/vmdb/app/models/miq_worker.rb:266:in `start_worker'
/var/www/miq/vmdb/app/models/miq_worker.rb:153:in `block in sync_workers'
/var/www/miq/vmdb/app/models/miq_worker.rb:153:in `times'
/var/www/miq/vmdb/app/models/miq_worker.rb:153:in `sync_workers'
/var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:53:in `block in sync_workers'
/var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:50:in `each'
/var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:50:in `sync_workers'
/var/www/miq/vmdb/app/models/miq_server.rb:141:in `start'
/var/www/miq/vmdb/app/models/miq_server.rb:233:in `start'
/var/www/miq/vmdb/lib/workers/evm_server.rb:27:in `start'
/var/www/miq/vmdb/lib/workers/evm_server.rb:48:in `start'
/var/www/miq/vmdb/lib/workers/bin/evm_server.rb:4:in `<main>'


Version-Release number of selected component (if applicable):
CFME-5.9.0.22
RHV-4.1.10

Expected results:
VM provision from pxe should succeed, and use the template nic network ("ovirtmgmt")

Comment 2 Ilanit Stein 2018-03-07 12:33:50 UTC
Created attachment 1405307 [details]
evm.log

Comment 3 Ilanit Stein 2018-03-07 12:34:43 UTC
Created attachment 1405308 [details]
evm.log

Comment 4 Ilanit Stein 2018-03-07 12:36:10 UTC
Created attachment 1405309 [details]
automation.log

Comment 5 Ilanit Stein 2018-03-07 12:39:12 UTC
Created attachment 1405312 [details]
production.log

Comment 6 Ilanit Stein 2018-03-07 12:39:50 UTC
Created attachment 1405314 [details]
rhevm.log

Comment 9 Jan Zmeskal 2018-09-17 13:57:52 UTC
Verified on: CFME 5.10.0.15

The VM was provisioned successfully. I also checked it on RHV side and it has the same NIC as the used template.