Description of problem: On CFME-5.8.1 all v2v (Transform VM from RHV to VMWare) requests fail. Even those that have the VM imported successfully. Those requests fail after ~2 days, with this last massage: "[EVM] VM [<imported VM name>] Step [WaitForImport] Status [Error in checking for VM Import completion] Message [Checking for VM Import completion]" Version-Release number of selected component (if applicable): CFME-5.8.1/RHV-4.1.3 Expected: v2v request timeout should be much shorter than 2 days. Addtional info I assume request fail after 100 retries, as after 1 day the retry number is 50.
Created attachment 1296241 [details] automation.log
Created attachment 1296242 [details] production.log
Created attachment 1296243 [details] rhevm.log
Created attachment 1296244 [details] evm.log
It looks like the issue is the same as in BZ #1467795: [----] I, [2017-07-10T10:30:38.981496 #2834:40914c] INFO -- : <AutomationEngine> <AEMethod [/ManageIQ/System/Event/EmsEvent/RHEVM/update_vm_import_status]> Starting [----] I, [2017-07-10T10:30:39.174154 #2865:40914c] INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1. [----] I, [2017-07-10T10:30:39.194829 #2865:40914c] INFO -- : MIQ(MiqQueue.put) Message id: [99457], id: [], Zone: [default], Role: [smartstate], Server: [], Ident: [generic], Target id: [], Instance id: [], Task id: [job_dispatcher], Command: [JobProxyDispatcher.dispatch], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: [] [----] E, [2017-07-10T10:30:39.890373 #2834:7f7b28] ERROR -- : <AutomationEngine> <AEMethod update_vm_import_status> The following error occurred during method evaluation: [----] E, [2017-07-10T10:30:39.895550 #2834:7f7b28] ERROR -- : <AutomationEngine> <AEMethod update_vm_import_status> DRb::DRbRemoteError: Service Model not found (MiqAeException::ServiceNotFound) [----] E, [2017-07-10T10:30:39.939998 #2834:7f7b28] ERROR -- : <AutomationEngine> <AEMethod update_vm_import_status> (druby://127.0.0.1:35931) /var/www/miq/vmdb/lib/miq_automation_engine/engine/miq_ae_method_service/miq_ae_service_model_base.rb:21:in `rescue in method_missing' (druby://127.0.0.1:35931) /var/www/miq/vmdb/lib/miq_automation_engine/engine/miq_ae_method_service/miq_ae_service_model_base.rb:18:in `method_missing' (druby://127.0.0.1:35931) /var/www/miq/vmdb/lib/miq_automation_engine/engine/miq_ae_method_service/miq_ae_service_vmdb.rb:5:in `vmdb' (druby://127.0.0.1:35931) /opt/rh/rh-ruby23/root/usr/share/ruby/drb/drb.rb:1624:in `perform_without_block' (druby://127.0.0.1:35931) /opt/rh/rh-ruby23/root/usr/share/ruby/drb/drb.rb:1584:in `perform' (druby://127.0.0.1:35931) /opt/rh/rh-ruby23/root/usr/share/ruby/drb/drb.rb:1657:in `block (2 levels) in main_loop' (druby://127.0.0.1:35931) /opt/rh/rh-ruby23/root/usr/share/ruby/drb/drb.rb:1653:in `loop' (druby://127.0.0.1:35931) /opt/rh/rh-ruby23/root/usr/share/ruby/drb/drb.rb:1653:in `block in main_loop' /ManageIQ/System/Event/EmsEvent/RHEVM/update_vm_import_status:28:in `vm' /ManageIQ/System/Event/EmsEvent/RHEVM/update_vm_import_status:13:in `main' [----] E, [2017-07-10T10:30:39.958110 #2834:7f7b28] ERROR -- : <AutomationEngine> Method STDERR: (druby://127.0.0.1:35931) /var/www/miq/vmdb/lib/miq_automation_engine/engine/miq_ae_method_service/miq_ae_service_model_base.rb:21:in `rescue in method_missing': Service Model not found (MiqAeException::ServiceNotFound) (DRb::DRbRemoteError)
patch merged to master: https://github.com/ManageIQ/manageiq-content/pull/149
Verified on CFME-5.9.0.14/RHV-4.1.8 CFME Failing VM import requests, do not have such long timeout. For example Windows VM import request, failing to mount, will end up after few minutes.