Attachment 1449165 Details for Bug 1589297 – automation.log

automation.log

automation.log (text/plain), 129.77 KB, created by Kedar Kulkarni on 2018-06-08 15:50:01 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Kedar Kulkarni

Created: 2018-06-08 15:50:01 UTC

Size: 129.77 KB

patch

obsolete

>[----] I, [2018-06-08T11:48:46.813405 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Invoking [inline] method [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] with inputs [{}]
>[----] I, [2018-06-08T11:48:46.813498 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Updated namespace [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:48:46.814880 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Starting 
>[----] I, [2018-06-08T11:48:46.858986 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Instance [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:46.885277 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Updated namespace [Transformation/Infrastructure/VM/Common/poweroff  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:48:46.896057 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Invoking [inline] method [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] with inputs [{}]
>[----] I, [2018-06-08T11:48:46.897462 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Starting 
>[----] I, [2018-06-08T11:48:47.260930 #25229:52e8b28]  INFO -- : <AEMethod poweroff> VM 'kkulkarn-v2v-rhel' is already off. Nothing to do.
>[----] I, [2018-06-08T11:48:47.284943 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Ending
>[----] I, [2018-06-08T11:48:47.285168 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:47.285854 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Followed  Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:48:47.285997 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Processed State=[State3] with Result=[ok]
>[----] I, [2018-06-08T11:48:47.286127 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Processed State=[State3]
>[----] I, [2018-06-08T11:48:47.286297 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) In State=[State3], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:47.295540 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:47.336735 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:47.337515 #34080:948ab08]  INFO -- : <AEMethod poweroff> VM 'kkulkarn-v2v-win2016' is already off. Nothing to do.
>[----] I, [2018-06-08T11:48:47.338300 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:47.359588 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Ending
>[----] I, [2018-06-08T11:48:47.359790 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:47.360256 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Followed  Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:48:47.360450 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Processed State=[State3] with Result=[ok]
>[----] I, [2018-06-08T11:48:47.360552 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Processed State=[State3]
>[----] I, [2018-06-08T11:48:47.360817 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) In State=[State3], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:47.371299 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:47.412667 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:47.414001 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:47.761477 #25229:570b1d8]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Power off", "message"=>"State3 is finished.", "started_on"=>2018-06-08 15:48:46 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:47 UTC}
>[----] I, [2018-06-08T11:48:47.835144 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:47.835301 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:47.835806 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Next State=[State4]
>[----] I, [2018-06-08T11:48:47.836091 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) In State=[State4], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:47.842504 #34080:98491a4]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Power off", "message"=>"State3 is finished.", "started_on"=>2018-06-08 15:48:46 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:47 UTC}
>[----] I, [2018-06-08T11:48:47.844687 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:47.876252 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:47.877813 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:47.911289 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:47.911476 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:47.911966 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Next State=[State4]
>[----] I, [2018-06-08T11:48:47.912302 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) In State=[State4], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:47.920641 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:47.953164 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:47.954920 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:48.425408 #25229:adf78c]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Collapse Snapshots", "started_on"=>2018-06-08 15:48:48 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:48.500575 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:48.500840 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Method exited with rc=MIQ_OK
>[----] E, [2018-06-08T11:48:48.501473 #25229:aed10c] ERROR -- : Q-task_id([service_template_transformation_plan_task_12]) State=<State4> running  raised exception: <Object Not Found for path=[state_var]>
>[----] W, [2018-06-08T11:48:48.501591 #25229:aed10c]  WARN -- : Q-task_id([service_template_transformation_plan_task_12]) Error in State=[State4]
>[----] I, [2018-06-08T11:48:48.501800 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) In State=[State4], invoking [on_error] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:48.510837 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:48.518532 #34080:adf82c]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Collapse Snapshots", "started_on"=>2018-06-08 15:48:48 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:48.542216 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:48.543580 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:48.585961 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:48.586091 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Method exited with rc=MIQ_OK
>[----] E, [2018-06-08T11:48:48.586540 #34080:aed10c] ERROR -- : Q-task_id([service_template_transformation_plan_task_13]) State=<State4> running  raised exception: <Object Not Found for path=[state_var]>
>[----] W, [2018-06-08T11:48:48.586689 #34080:aed10c]  WARN -- : Q-task_id([service_template_transformation_plan_task_13]) Error in State=[State4]
>[----] I, [2018-06-08T11:48:48.586856 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) In State=[State4], invoking [on_error] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:48.594290 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:48.622804 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:48.624174 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:48.952403 #25229:3fe6480]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"failed", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Failed to Collapse Snapshots.", "started_on"=>2018-06-08 15:48:48 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:48 UTC}
>[----] I, [2018-06-08T11:48:49.002468 #34080:49920d8]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"failed", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Failed to Collapse Snapshots.", "started_on"=>2018-06-08 15:48:48 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:49 UTC}
>[----] I, [2018-06-08T11:48:49.039342 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:49.039538 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_12]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:49.090243 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:49.090433 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_13]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:49.126319 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-08T11:48:49.132983 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Instantiating [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=14&User%3A%3Auser=1&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task]
>[----] I, [2018-06-08T11:48:49.315862 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-08T11:48:49.320242 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Instantiating [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=15&User%3A%3Auser=1&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task]
>[----] I, [2018-06-08T11:48:49.444942 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=14&User%3A%3Auser=1&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task  ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-08T11:48:49.611559 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=15&User%3A%3Auser=1&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task  ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-08T11:48:49.656961 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) In State=[State1], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-08T11:48:49.670905 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:49.699030 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-08T11:48:49.700289 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:49.819237 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) In State=[State1], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-08T11:48:49.834131 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:49.860911 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-08T11:48:49.862231 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:50.246521 #25229:adf78c]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Assess Migration", "message"=>"Assess Migration", "started_on"=>2018-06-08 15:48:50 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:50.295401 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:50.295563 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:50.295981 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Processing State=[State1]
>[----] I, [2018-06-08T11:48:50.296259 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Following Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-08T11:48:50.313648 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [miqaedb:/Transformation/Common/AssessTransformation#create  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:50.325658 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Instance [/ManageIQ/Transformation/Common/AssessTransformation] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:50.336186 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [Transformation/Common/assesstransformation  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:50.343186 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/Transformation/Common/AssessTransformation] with inputs [{}]
>[----] I, [2018-06-08T11:48:50.344618 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Starting 
>[----] I, [2018-06-08T11:48:50.434314 #34080:adf82c]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Assess Migration", "message"=>"Assess Migration", "started_on"=>2018-06-08 15:48:50 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:50.477435 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:50.477592 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:50.478092 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Processing State=[State1]
>[----] I, [2018-06-08T11:48:50.478324 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Following Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-08T11:48:50.496856 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [miqaedb:/Transformation/Common/AssessTransformation#create  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:50.508270 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Instance [/ManageIQ/Transformation/Common/AssessTransformation] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:50.517611 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [Transformation/Common/assesstransformation  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:50.524335 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/Transformation/Common/AssessTransformation] with inputs [{}]
>[----] I, [2018-06-08T11:48:50.525828 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Starting 
>[----] I, [2018-06-08T11:48:50.837548 #25229:12c3750]  INFO -- : <AEMethod assesstransformation> Network mappings: [{:source=>"VM Network", :destination=>"ovirtmgmt"}]
>[----] I, [2018-06-08T11:48:50.886906 #25229:12c3750]  INFO -- : <AEMethod assesstransformation> Source VM Disks [{:path=>"[NFS_Datastore_1] kkulkarn-v2v-rhel/kkulkarn-v2v-rhel.vmdk", :size=>64424509440, :percent=>0, :weight=>100.0}]
>[----] I, [2018-06-08T11:48:50.978467 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Ending
>[----] I, [2018-06-08T11:48:50.978640 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:50.979120 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Followed  Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-08T11:48:50.979230 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Processed State=[State1] with Result=[ok]
>[----] I, [2018-06-08T11:48:50.979304 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Processed State=[State1]
>[----] I, [2018-06-08T11:48:50.979435 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) In State=[State1], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-08T11:48:50.988522 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:51.015924 #34080:3567c5c]  INFO -- : <AEMethod assesstransformation> Network mappings: [{:source=>"VM Network", :destination=>"ovirtmgmt"}]
>[----] I, [2018-06-08T11:48:51.025482 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-08T11:48:51.026921 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:51.064834 #34080:3567c5c]  INFO -- : <AEMethod assesstransformation> Source VM Disks [{:path=>"[NFS_Datastore_1] ytale-v2v-rhel1/ytale-v2v-rhel1.vmdk", :size=>64424509440, :percent=>0, :weight=>100.0}]
>[----] I, [2018-06-08T11:48:51.147765 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Ending
>[----] I, [2018-06-08T11:48:51.147914 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:51.148230 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Followed  Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-08T11:48:51.148307 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Processed State=[State1] with Result=[ok]
>[----] I, [2018-06-08T11:48:51.148364 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Processed State=[State1]
>[----] I, [2018-06-08T11:48:51.148505 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) In State=[State1], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-08T11:48:51.157701 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:51.190287 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-08T11:48:51.191675 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:51.422229 #25229:52e8dd0]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State1 is finished.", "started_on"=>2018-06-08 15:48:50 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:51 UTC}
>[----] I, [2018-06-08T11:48:51.471326 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:51.471470 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:51.471861 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Next State=[State2]
>[----] I, [2018-06-08T11:48:51.472149 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) In State=[State2], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:51.483272 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:51.517852 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:51.519384 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:51.584173 #34080:9b0f190]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State1 is finished.", "started_on"=>2018-06-08 15:48:50 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:51 UTC}
>[----] I, [2018-06-08T11:48:51.628993 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:51.629153 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:51.629557 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Next State=[State2]
>[----] I, [2018-06-08T11:48:51.629896 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) In State=[State2], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:51.639283 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:51.671581 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:51.673030 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:51.937620 #25229:595c8d8]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-08 15:48:51 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:51.985677 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:51.985893 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:51.987002 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Processing State=[State2]
>[----] I, [2018-06-08T11:48:51.987285 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Following Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-08T11:48:51.997689 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [miqaedb:/Transformation/Common/AcquireTransformationHost#create  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:51.998160 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Instance [/ManageIQ/Transformation/Common/AcquireTransformationHost] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:52.004255 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [Transformation/Common/acquiretransformationhost  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:52.012135 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/Transformation/Common/AcquireTransformationHost] with inputs [{}]
>[----] I, [2018-06-08T11:48:52.029509 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [Transformation/TransformationHosts/Common/Utils  ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-08T11:48:52.049044 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Loading embedded method ManageIQ/Transformation/TransformationHosts/Common/Utils
>[----] I, [2018-06-08T11:48:52.050873 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Starting 
>[----] I, [2018-06-08T11:48:52.077664 #34080:92da4d4]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-08 15:48:52 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:52.127419 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:52.127607 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:52.128089 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Processing State=[State2]
>[----] I, [2018-06-08T11:48:52.128355 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Following Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-08T11:48:52.138893 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [miqaedb:/Transformation/Common/AcquireTransformationHost#create  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:52.139313 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Instance [/ManageIQ/Transformation/Common/AcquireTransformationHost] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:52.145227 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [Transformation/Common/acquiretransformationhost  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:52.152923 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/Transformation/Common/AcquireTransformationHost] with inputs [{}]
>[----] I, [2018-06-08T11:48:52.171687 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [Transformation/TransformationHosts/Common/Utils  ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-08T11:48:52.190656 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Loading embedded method ManageIQ/Transformation/TransformationHosts/Common/Utils
>[----] I, [2018-06-08T11:48:52.192560 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Starting 
>[----] I, [2018-06-08T11:48:52.632248 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Task: #<MiqAeServiceServiceTemplateTransformationPlanTask:0x66019bc @object=#<ServiceTemplateTransformationPlanTask id: 14, description: "Transforming VM [kkulkarn-v2v-rhel]", state: "finished", request_type: "transformation_plan", userid: "admin", options: {:dialog=>nil, :workflow_settings=>{:resource_action_id=>21}, :initiator=>nil, :src_id=>1, :cart_state=>"ordered", :requester_group=>"EvmGroup-super_administrator", :delivered_on=>2018-06-08 15:48:34 UTC, :progress=>{"current_state"=>"/State2", "current_description"=>"Acquire Transformation Host", "percent"=>1.0, "states"=>{"/State1"=>{"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State1 is finished.", "started_on"=>2018-06-08 15:48:50 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:51 UTC}, "/State2"=>{"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-08 15:48:51 UTC, "percent"=>0.0}}}, :virtv2v_networks=>[{:source=>"VM Network", :destination=>"ovirtmgmt"}], :virtv2v_disks=>[{:path=>"[NFS_Datastore_1] kkulkarn-v2v-rhel/kkulkarn-v2v-rhel.vmdk", :size=>64424509440, :percent=>0, :weight=>100.0}], :collapse_snapshots=>true, :power_off=>true}, created_on: "2018-06-08 15:48:34", updated_on: "2018-06-08 15:48:51", message: "Error in Request: 5. Setting pending Task: 13 to f...", status: "Warn", type: "ServiceTemplateTransformationPlanTask", miq_request_id: 5, source_id: 58, source_type: "VmOrTemplate", destination_id: nil, destination_type: nil, miq_request_task_id: nil, phase: nil, phase_context: {}, tenant_id: 1>, @virtual_columns=["href_slug", "region_description", "region_number"], @associations=["destination", "miq_request", "miq_request_task", "miq_request_tasks", "service_resource", "source", "tenant"]>
>[----] I, [2018-06-08T11:48:52.636814 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Source VM: kkulkarn-v2v-rhel
>[----] I, [2018-06-08T11:48:52.640225 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Source Cluster: Cluster
>[----] I, [2018-06-08T11:48:52.654023 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Destination Cluster: Default
>[----] I, [2018-06-08T11:48:52.714029 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Transformation Host: cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com.
>[----] I, [2018-06-08T11:48:52.783538 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Ending
>[----] I, [2018-06-08T11:48:52.783751 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:52.784025 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Followed  Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-08T11:48:52.784133 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Processed State=[State2] with Result=[ok]
>[----] I, [2018-06-08T11:48:52.784207 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Processed State=[State2]
>[----] I, [2018-06-08T11:48:52.784328 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) In State=[State2], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:52.793005 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:52.802482 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Task: #<MiqAeServiceServiceTemplateTransformationPlanTask:0x7967a88 @object=#<ServiceTemplateTransformationPlanTask id: 15, description: "Transforming VM [ytale-v2v-rhel1]", state: "finished", request_type: "transformation_plan", userid: "admin", options: {:dialog=>nil, :workflow_settings=>{:resource_action_id=>21}, :initiator=>nil, :src_id=>1, :cart_state=>"ordered", :requester_group=>"EvmGroup-super_administrator", :delivered_on=>2018-06-08 15:48:34 UTC, :progress=>{"current_state"=>"/State2", "current_description"=>"Acquire Transformation Host", "percent"=>1.0, "states"=>{"/State1"=>{"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State1 is finished.", "started_on"=>2018-06-08 15:48:50 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:51 UTC}, "/State2"=>{"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-08 15:48:52 UTC, "percent"=>0.0}}}, :virtv2v_networks=>[{:source=>"VM Network", :destination=>"ovirtmgmt"}], :virtv2v_disks=>[{:path=>"[NFS_Datastore_1] ytale-v2v-rhel1/ytale-v2v-rhel1.vmdk", :size=>64424509440, :percent=>0, :weight=>100.0}], :collapse_snapshots=>true, :power_off=>true}, created_on: "2018-06-08 15:48:34", updated_on: "2018-06-08 15:48:52", message: "Error in Request: 5. Setting pending Task: 13 to f...", status: "Warn", type: "ServiceTemplateTransformationPlanTask", miq_request_id: 5, source_id: 46, source_type: "VmOrTemplate", destination_id: nil, destination_type: nil, miq_request_task_id: nil, phase: nil, phase_context: {}, tenant_id: 1>, @virtual_columns=["href_slug", "region_description", "region_number"], @associations=["destination", "miq_request", "miq_request_task", "miq_request_tasks", "service_resource", "source", "tenant"]>
>[----] I, [2018-06-08T11:48:52.806398 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Source VM: ytale-v2v-rhel1
>[----] I, [2018-06-08T11:48:52.810087 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Source Cluster: Cluster
>[----] I, [2018-06-08T11:48:52.820749 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:52.821870 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:52.823318 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Destination Cluster: Default
>[----] I, [2018-06-08T11:48:52.876540 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Transformation Host: cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com.
>[----] I, [2018-06-08T11:48:52.940628 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Ending
>[----] I, [2018-06-08T11:48:52.940800 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:52.941069 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Followed  Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-08T11:48:52.941154 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Processed State=[State2] with Result=[ok]
>[----] I, [2018-06-08T11:48:52.941231 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Processed State=[State2]
>[----] I, [2018-06-08T11:48:52.941376 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) In State=[State2], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:52.949156 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:52.977166 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:52.978445 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:53.214160 #25229:4c5b1fc]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"State2 is finished.", "started_on"=>2018-06-08 15:48:51 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:53 UTC}
>[----] I, [2018-06-08T11:48:53.263405 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:53.263593 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:53.264074 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Next State=[State3]
>[----] I, [2018-06-08T11:48:53.264421 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) In State=[State3], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:53.273058 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:53.311363 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:53.313020 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:53.373533 #34080:5401064]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"State2 is finished.", "started_on"=>2018-06-08 15:48:52 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:53 UTC}
>[----] I, [2018-06-08T11:48:53.422213 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:53.422358 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:53.422914 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Next State=[State3]
>[----] I, [2018-06-08T11:48:53.423266 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) In State=[State3], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:53.433112 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:53.476891 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:53.478666 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:53.773624 #25229:5204f18]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Power off", "message"=>"Power off", "started_on"=>2018-06-08 15:48:53 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:53.828916 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:53.829111 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:53.829467 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Processing State=[State3]
>[----] I, [2018-06-08T11:48:53.829698 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Following Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:48:53.892240 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:48:53.922445 #34080:9b291e4]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Power off", "message"=>"Power off", "started_on"=>2018-06-08 15:48:53 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:53.925067 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Instance [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:53.946248 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [Transformation/Infrastructure/VM/Common/poweroff  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:48:53.956002 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] with inputs [{}]
>[----] I, [2018-06-08T11:48:53.957295 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Starting 
>[----] I, [2018-06-08T11:48:53.973737 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:53.973939 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:53.974485 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Processing State=[State3]
>[----] I, [2018-06-08T11:48:53.974757 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Following Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:48:54.038542 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:48:54.072525 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Instance [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:54.094626 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [Transformation/Infrastructure/VM/Common/poweroff  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:48:54.103810 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] with inputs [{}]
>[----] I, [2018-06-08T11:48:54.105042 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Starting 
>[----] I, [2018-06-08T11:48:54.378935 #25229:94f3f68]  INFO -- : <AEMethod poweroff> VM 'kkulkarn-v2v-rhel' is already off. Nothing to do.
>[----] I, [2018-06-08T11:48:54.404658 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Ending
>[----] I, [2018-06-08T11:48:54.404842 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:54.405383 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Followed  Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:48:54.405521 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Processed State=[State3] with Result=[ok]
>[----] I, [2018-06-08T11:48:54.405609 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Processed State=[State3]
>[----] I, [2018-06-08T11:48:54.405784 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) In State=[State3], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:54.414919 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:54.449221 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:54.450855 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:54.555272 #34080:99a2cbc]  INFO -- : <AEMethod poweroff> VM 'ytale-v2v-rhel1' is already off. Nothing to do.
>[----] I, [2018-06-08T11:48:54.577888 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Ending
>[----] I, [2018-06-08T11:48:54.578043 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:54.578537 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Followed  Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:48:54.578657 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Processed State=[State3] with Result=[ok]
>[----] I, [2018-06-08T11:48:54.578740 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Processed State=[State3]
>[----] I, [2018-06-08T11:48:54.578873 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) In State=[State3], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:54.587881 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:54.624729 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:54.626191 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:55.023665 #25229:adf78c]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Power off", "message"=>"State3 is finished.", "started_on"=>2018-06-08 15:48:53 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:55 UTC}
>[----] I, [2018-06-08T11:48:55.072251 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:55.072423 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:55.072888 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Next State=[State4]
>[----] I, [2018-06-08T11:48:55.073226 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) In State=[State4], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:55.081264 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:55.112078 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:55.113416 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:55.205847 #34080:adf7f0]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Power off", "message"=>"State3 is finished.", "started_on"=>2018-06-08 15:48:53 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:55 UTC}
>[----] I, [2018-06-08T11:48:55.254352 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:55.254541 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:55.255020 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Next State=[State4]
>[----] I, [2018-06-08T11:48:55.255465 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) In State=[State4], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:55.263927 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:55.298243 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:55.299773 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:55.526697 #25229:4149afc]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Collapse Snapshots", "started_on"=>2018-06-08 15:48:55 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:55.591691 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:55.591937 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] E, [2018-06-08T11:48:55.592401 #25229:aed10c] ERROR -- : Q-task_id([service_template_transformation_plan_task_14]) State=<State4> running  raised exception: <Object Not Found for path=[state_var]>
>[----] W, [2018-06-08T11:48:55.592520 #25229:aed10c]  WARN -- : Q-task_id([service_template_transformation_plan_task_14]) Error in State=[State4]
>[----] I, [2018-06-08T11:48:55.592631 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) In State=[State4], invoking [on_error] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:55.603467 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:55.642520 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:55.644274 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:55.729201 #34080:409f5c0]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Collapse Snapshots", "started_on"=>2018-06-08 15:48:55 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:55.787640 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:55.787827 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] E, [2018-06-08T11:48:55.788487 #34080:aed10c] ERROR -- : Q-task_id([service_template_transformation_plan_task_15]) State=<State4> running  raised exception: <Object Not Found for path=[state_var]>
>[----] W, [2018-06-08T11:48:55.788609 #34080:aed10c]  WARN -- : Q-task_id([service_template_transformation_plan_task_15]) Error in State=[State4]
>[----] I, [2018-06-08T11:48:55.788774 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) In State=[State4], invoking [on_error] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:55.798686 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:55.834088 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:55.835875 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:56.061509 #25229:4c64e50]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"failed", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Failed to Collapse Snapshots.", "started_on"=>2018-06-08 15:48:55 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:56 UTC}
>[----] I, [2018-06-08T11:48:56.120410 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:56.120604 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_14]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:56.184175 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-08T11:48:56.189798 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Instantiating [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=16&User%3A%3Auser=1&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task]
>[----] I, [2018-06-08T11:48:56.263205 #34080:540e430]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"failed", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Failed to Collapse Snapshots.", "started_on"=>2018-06-08 15:48:55 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:56 UTC}
>[----] I, [2018-06-08T11:48:56.314312 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:56.314500 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_15]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:56.374847 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-08T11:48:56.380473 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Instantiating [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=17&User%3A%3Auser=1&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task]
>[----] I, [2018-06-08T11:48:56.501638 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=16&User%3A%3Auser=1&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task  ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-08T11:48:56.708909 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=17&User%3A%3Auser=1&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task  ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-08T11:48:56.757116 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) In State=[State1], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-08T11:48:56.775159 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:56.803207 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-08T11:48:56.804450 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:56.936462 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) In State=[State1], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-08T11:48:56.952318 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:56.980521 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-08T11:48:56.981790 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:57.350055 #25229:adf78c]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Assess Migration", "message"=>"Assess Migration", "started_on"=>2018-06-08 15:48:57 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:57.392537 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:57.392788 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:57.393123 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Processing State=[State1]
>[----] I, [2018-06-08T11:48:57.393328 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Following Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-08T11:48:57.411407 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [miqaedb:/Transformation/Common/AssessTransformation#create  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:57.422640 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Instance [/ManageIQ/Transformation/Common/AssessTransformation] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:57.431767 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [Transformation/Common/assesstransformation  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:57.437994 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/Transformation/Common/AssessTransformation] with inputs [{}]
>[----] I, [2018-06-08T11:48:57.439376 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Starting 
>[----] I, [2018-06-08T11:48:57.571949 #34080:adf7f0]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Assess Migration", "message"=>"Assess Migration", "started_on"=>2018-06-08 15:48:57 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:57.613322 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:57.613518 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:57.613968 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Processing State=[State1]
>[----] I, [2018-06-08T11:48:57.614276 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Following Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-08T11:48:57.632516 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [miqaedb:/Transformation/Common/AssessTransformation#create  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:57.644396 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Instance [/ManageIQ/Transformation/Common/AssessTransformation] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:57.654870 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [Transformation/Common/assesstransformation  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:57.662437 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/Transformation/Common/AssessTransformation] with inputs [{}]
>[----] I, [2018-06-08T11:48:57.663997 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Starting 
>[----] I, [2018-06-08T11:48:57.923322 #25229:3f3f298]  INFO -- : <AEMethod assesstransformation> Network mappings: [{:source=>"VM Network", :destination=>"ovirtmgmt"}]
>[----] I, [2018-06-08T11:48:57.990700 #25229:3f3f298]  INFO -- : <AEMethod assesstransformation> Source VM Disks [{:path=>"[NFS_Datastore_1] ytale-v2v-ubuntu1/ytale-v2v-ubuntu1.vmdk", :size=>21474836480, :percent=>0, :weight=>100.0}]
>[----] I, [2018-06-08T11:48:58.094303 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Ending
>[----] I, [2018-06-08T11:48:58.094488 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:58.094854 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Followed  Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-08T11:48:58.094951 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Processed State=[State1] with Result=[ok]
>[----] I, [2018-06-08T11:48:58.095043 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Processed State=[State1]
>[----] I, [2018-06-08T11:48:58.095217 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) In State=[State1], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-08T11:48:58.104621 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:58.140573 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-08T11:48:58.142000 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:58.150163 #34080:412a058]  INFO -- : <AEMethod assesstransformation> Network mappings: [{:source=>"VM Network", :destination=>"ovirtmgmt"}]
>[----] I, [2018-06-08T11:48:58.204026 #34080:412a058]  INFO -- : <AEMethod assesstransformation> Source VM Disks [{:path=>"[NFS_Datastore_1] ytale-v2v-rhel74-23-6/ytale-v2v-rhel74-23-6.vmdk", :size=>64424509440, :percent=>0, :weight=>100.0}]
>[----] I, [2018-06-08T11:48:58.299362 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Ending
>[----] I, [2018-06-08T11:48:58.299542 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:58.299847 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Followed  Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-08T11:48:58.299953 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Processed State=[State1] with Result=[ok]
>[----] I, [2018-06-08T11:48:58.300025 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Processed State=[State1]
>[----] I, [2018-06-08T11:48:58.300174 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) In State=[State1], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-08T11:48:58.310503 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:58.345146 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-08T11:48:58.346524 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:58.548110 #25229:5320654]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State1 is finished.", "started_on"=>2018-06-08 15:48:57 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:58 UTC}
>[----] I, [2018-06-08T11:48:58.593488 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:58.593631 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:58.594093 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Next State=[State2]
>[----] I, [2018-06-08T11:48:58.594475 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) In State=[State2], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:58.603605 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:58.636386 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:58.637852 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:58.754251 #34080:a3733e0]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State1 is finished.", "started_on"=>2018-06-08 15:48:57 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:58 UTC}
>[----] I, [2018-06-08T11:48:58.799182 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:58.799333 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:58.799748 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Next State=[State2]
>[----] I, [2018-06-08T11:48:58.800139 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) In State=[State2], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:58.809044 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:58.842342 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:58.843679 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:59.045538 #25229:58ef8dc]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-08 15:48:59 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:59.111257 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:59.111448 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:59.111897 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Processing State=[State2]
>[----] I, [2018-06-08T11:48:59.112120 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Following Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-08T11:48:59.123366 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [miqaedb:/Transformation/Common/AcquireTransformationHost#create  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:59.123883 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Instance [/ManageIQ/Transformation/Common/AcquireTransformationHost] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:59.130194 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [Transformation/Common/acquiretransformationhost  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:59.138315 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/Transformation/Common/AcquireTransformationHost] with inputs [{}]
>[----] I, [2018-06-08T11:48:59.155425 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [Transformation/TransformationHosts/Common/Utils  ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-08T11:48:59.172026 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Loading embedded method ManageIQ/Transformation/TransformationHosts/Common/Utils
>[----] I, [2018-06-08T11:48:59.173547 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Starting 
>[----] I, [2018-06-08T11:48:59.272854 #34080:5b28270]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-08 15:48:59 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:48:59.321603 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:48:59.321818 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:59.322324 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Processing State=[State2]
>[----] I, [2018-06-08T11:48:59.322585 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Following Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-08T11:48:59.333801 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [miqaedb:/Transformation/Common/AcquireTransformationHost#create  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:59.334206 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Instance [/ManageIQ/Transformation/Common/AcquireTransformationHost] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:48:59.341406 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [Transformation/Common/acquiretransformationhost  ManageIQ/Transformation]
>[----] I, [2018-06-08T11:48:59.349083 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/Transformation/Common/AcquireTransformationHost] with inputs [{}]
>[----] I, [2018-06-08T11:48:59.366852 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [Transformation/TransformationHosts/Common/Utils  ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-08T11:48:59.385296 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Loading embedded method ManageIQ/Transformation/TransformationHosts/Common/Utils
>[----] I, [2018-06-08T11:48:59.387042 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Starting 
>[----] I, [2018-06-08T11:48:59.734981 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Task: #<MiqAeServiceServiceTemplateTransformationPlanTask:0x79d5c54 @object=#<ServiceTemplateTransformationPlanTask id: 16, description: "Transforming VM [ytale-v2v-ubuntu1]", state: "finished", request_type: "transformation_plan", userid: "admin", options: {:dialog=>nil, :workflow_settings=>{:resource_action_id=>21}, :initiator=>nil, :src_id=>1, :cart_state=>"ordered", :requester_group=>"EvmGroup-super_administrator", :delivered_on=>2018-06-08 15:48:34 UTC, :progress=>{"current_state"=>"/State2", "current_description"=>"Acquire Transformation Host", "percent"=>1.0, "states"=>{"/State1"=>{"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State1 is finished.", "started_on"=>2018-06-08 15:48:57 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:58 UTC}, "/State2"=>{"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-08 15:48:59 UTC, "percent"=>0.0}}}, :virtv2v_networks=>[{:source=>"VM Network", :destination=>"ovirtmgmt"}], :virtv2v_disks=>[{:path=>"[NFS_Datastore_1] ytale-v2v-ubuntu1/ytale-v2v-ubuntu1.vmdk", :size=>21474836480, :percent=>0, :weight=>100.0}], :collapse_snapshots=>true, :power_off=>true}, created_on: "2018-06-08 15:48:35", updated_on: "2018-06-08 15:48:59", message: "Error in Request: 5. Setting pending Task: 13 to f...", status: "Warn", type: "ServiceTemplateTransformationPlanTask", miq_request_id: 5, source_id: 45, source_type: "VmOrTemplate", destination_id: nil, destination_type: nil, miq_request_task_id: nil, phase: nil, phase_context: {}, tenant_id: 1>, @virtual_columns=["href_slug", "region_description", "region_number"], @associations=["destination", "miq_request", "miq_request_task", "miq_request_tasks", "service_resource", "source", "tenant"]>
>[----] I, [2018-06-08T11:48:59.739142 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Source VM: ytale-v2v-ubuntu1
>[----] I, [2018-06-08T11:48:59.742866 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Source Cluster: Cluster
>[----] I, [2018-06-08T11:48:59.756940 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Destination Cluster: Default
>[----] I, [2018-06-08T11:48:59.814225 #25229:adf78c]  INFO -- : <AEMethod acquiretransformationhost> Transformation Host: cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com.
>[----] I, [2018-06-08T11:48:59.887268 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Ending
>[----] I, [2018-06-08T11:48:59.887453 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:48:59.887812 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Followed  Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-08T11:48:59.887966 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Processed State=[State2] with Result=[ok]
>[----] I, [2018-06-08T11:48:59.888045 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Processed State=[State2]
>[----] I, [2018-06-08T11:48:59.888169 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) In State=[State2], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:48:59.896981 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:48:59.933619 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:48:59.935106 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:48:59.968810 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Task: #<MiqAeServiceServiceTemplateTransformationPlanTask:0x77edb08 @object=#<ServiceTemplateTransformationPlanTask id: 17, description: "Transforming VM [ytale-v2v-rhel74-23-6]", state: "finished", request_type: "transformation_plan", userid: "admin", options: {:dialog=>nil, :workflow_settings=>{:resource_action_id=>21}, :initiator=>nil, :src_id=>1, :cart_state=>"ordered", :requester_group=>"EvmGroup-super_administrator", :delivered_on=>2018-06-08 15:48:34 UTC, :progress=>{"current_state"=>"/State2", "current_description"=>"Acquire Transformation Host", "percent"=>1.0, "states"=>{"/State1"=>{"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State1 is finished.", "started_on"=>2018-06-08 15:48:57 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:48:58 UTC}, "/State2"=>{"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-08 15:48:59 UTC, "percent"=>0.0}}}, :virtv2v_networks=>[{:source=>"VM Network", :destination=>"ovirtmgmt"}], :virtv2v_disks=>[{:path=>"[NFS_Datastore_1] ytale-v2v-rhel74-23-6/ytale-v2v-rhel74-23-6.vmdk", :size=>64424509440, :percent=>0, :weight=>100.0}], :collapse_snapshots=>true, :power_off=>true}, created_on: "2018-06-08 15:48:35", updated_on: "2018-06-08 15:48:59", message: "Error in Request: 5. Setting pending Task: 13 to f...", status: "Warn", type: "ServiceTemplateTransformationPlanTask", miq_request_id: 5, source_id: 40, source_type: "VmOrTemplate", destination_id: nil, destination_type: nil, miq_request_task_id: nil, phase: nil, phase_context: {}, tenant_id: 1>, @virtual_columns=["href_slug", "region_description", "region_number"], @associations=["destination", "miq_request", "miq_request_task", "miq_request_tasks", "service_resource", "source", "tenant"]>
>[----] I, [2018-06-08T11:48:59.973260 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Source VM: ytale-v2v-rhel74-23-6
>[----] I, [2018-06-08T11:48:59.977486 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Source Cluster: Cluster
>[----] I, [2018-06-08T11:48:59.994433 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Destination Cluster: Default
>[----] I, [2018-06-08T11:49:00.063812 #34080:adf82c]  INFO -- : <AEMethod acquiretransformationhost> Transformation Host: cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com.
>[----] I, [2018-06-08T11:49:00.136140 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Ending
>[----] I, [2018-06-08T11:49:00.136334 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:00.136649 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Followed  Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-08T11:49:00.136769 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Processed State=[State2] with Result=[ok]
>[----] I, [2018-06-08T11:49:00.136852 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Processed State=[State2]
>[----] I, [2018-06-08T11:49:00.136976 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) In State=[State2], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:49:00.145899 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:49:00.181567 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:49:00.182950 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:49:00.407969 #25229:4d39524]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"State2 is finished.", "started_on"=>2018-06-08 15:48:59 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:49:00 UTC}
>[----] I, [2018-06-08T11:49:00.477553 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:00.477782 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:00.478335 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Next State=[State3]
>[----] I, [2018-06-08T11:49:00.478873 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) In State=[State3], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:49:00.494192 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:49:00.504802 #11966:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:00.507868 #11966:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47098&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47098&event_stream_id=47098&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:00.534319 #11966:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47098&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47098&event_stream_id=47098&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:00.540554 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:49:00.542343 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:49:00.600273 #11966:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:00.628829 #11966:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:00.637734 #11975:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:00.640790 #11975:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47099&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47099&event_stream_id=47099&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:00.648118 #34080:55945d4]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"State2 is finished.", "started_on"=>2018-06-08 15:48:59 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:49:00 UTC}
>[----] I, [2018-06-08T11:49:00.669044 #11975:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47099&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47099&event_stream_id=47099&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:00.682675 #11966:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:00.694012 #11966:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:00.715626 #11966:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:00.717967 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:00.718152 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:00.718225 #11966:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47100&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47100&event_stream_id=47100&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:00.718997 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Next State=[State3]
>[----] I, [2018-06-08T11:49:00.719514 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) In State=[State3], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:49:00.734976 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:49:00.743141 #11966:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47100&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47100&event_stream_id=47100&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:00.805401 #11966:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:00.807808 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:49:00.810076 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:49:00.833473 #11966:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:00.860436 #11975:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:00.882274 #11966:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:00.887031 #11975:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:00.942825 #11975:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:00.954331 #11975:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:00.984585 #11975:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:00.987282 #11975:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47101&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47101&event_stream_id=47101&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:01.013176 #11975:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47101&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47101&event_stream_id=47101&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:01.017331 #11966:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.041341 #11966:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:01.046047 #11966:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47102&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47102&event_stream_id=47102&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:01.070510 #11966:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47102&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47102&event_stream_id=47102&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:01.072468 #11975:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.099782 #11975:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:01.106092 #25229:5242e08]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Power off", "message"=>"Power off", "started_on"=>2018-06-08 15:49:01 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:49:01.136431 #11966:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.150778 #11975:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.161267 #11975:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.162862 #11966:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:01.166174 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:01.166326 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:01.166644 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Processing State=[State3]
>[----] I, [2018-06-08T11:49:01.166873 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Following Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:49:01.181659 #11975:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:01.184214 #11975:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47103&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47103&event_stream_id=47103&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:01.207355 #11975:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47103&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47103&event_stream_id=47103&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:01.208089 #11966:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.217746 #11966:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.231648 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:49:01.238102 #11966:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:01.242297 #11966:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47104&Host%3A%3Ahost=1&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47104&event_stream_id=47104&event_type=VDS_BROKER_COMMAND_FAILURE&host_id=1&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:01.263421 #11975:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.264916 #11966:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47104&Host%3A%3Ahost=1&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47104&event_stream_id=47104&event_type=VDS_BROKER_COMMAND_FAILURE&host_id=1&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:01.265242 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Instance [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.288469 #11975:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:01.288855 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [Transformation/Infrastructure/VM/Common/poweroff  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:49:01.298472 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] with inputs [{}]
>[----] I, [2018-06-08T11:49:01.299721 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Starting 
>[----] I, [2018-06-08T11:49:01.312329 #34080:8906da4]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Power off", "message"=>"Power off", "started_on"=>2018-06-08 15:49:01 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:49:01.321460 #11966:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/VDS_BROKER_COMMAND_FAILURE#create]
>[----] I, [2018-06-08T11:49:01.333512 #11975:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.343896 #11975:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.346908 #11966:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/VDS_BROKER_COMMAND_FAILURE#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:01.374225 #11975:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:01.376579 #11975:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47105&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47105&event_stream_id=47105&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:01.380893 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:01.381043 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:01.384320 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Processing State=[State3]
>[----] I, [2018-06-08T11:49:01.384574 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Following Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:49:01.392425 #11966:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/VDS_BROKER_COMMAND_FAILURE] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.402074 #11966:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/VDS_BROKER_COMMAND_FAILURE#create]
>[----] I, [2018-06-08T11:49:01.404836 #11975:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47105&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47105&event_stream_id=47105&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:01.424069 #11966:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:01.426757 #11966:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47106&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47106&event_stream_id=47106&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:01.449593 #11966:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47106&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47106&event_stream_id=47106&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:01.450509 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:49:01.459612 #11975:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.485176 #11975:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:01.487838 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Instance [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.505690 #11966:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.511646 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [Transformation/Infrastructure/VM/Common/poweroff  ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-08T11:49:01.522337 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] with inputs [{}]
>[----] I, [2018-06-08T11:49:01.526054 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Starting 
>[----] I, [2018-06-08T11:49:01.529879 #11975:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.531539 #11966:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:01.540456 #11975:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.566082 #11975:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:01.573986 #11975:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47107&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47107&event_stream_id=47107&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:01.576423 #11966:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.586499 #11966:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.606648 #11975:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47107&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47107&event_stream_id=47107&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:01.611627 #11966:aed10c]  INFO -- : User [admin] with current group ID [1] name [Tenant My Company access]
>[----] I, [2018-06-08T11:49:01.614149 #11966:aed10c]  INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=47108&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47108&event_stream_id=47108&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream]
>[----] I, [2018-06-08T11:49:01.640152 #11966:aed10c]  INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=47108&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_id=47108&event_stream_id=47108&event_type=UNKNOWN&object_name=Event&vmdb_object_type=event_stream  ManageIQ/System]
>[----] I, [2018-06-08T11:49:01.682943 #11975:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.696662 #11966:aed10c]  INFO -- : Following Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.706422 #11975:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:01.722513 #11966:aed10c]  INFO -- : Updated namespace [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create  ManageIQ/System/Event/EmsEvent]
>[----] I, [2018-06-08T11:49:01.750244 #25229:9a9bd1c]  INFO -- : <AEMethod poweroff> VM 'ytale-v2v-ubuntu1' is already off. Nothing to do.
>[----] I, [2018-06-08T11:49:01.751138 #11975:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.761104 #11975:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.767034 #11966:aed10c]  INFO -- : Instance [/ManageIQ/System/Event/EmsEvent/RHEVM/UNKNOWN] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-08T11:49:01.777378 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Ending
>[----] I, [2018-06-08T11:49:01.777547 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:01.777510 #11966:aed10c]  INFO -- : Followed  Relationship [miqaedb:/System/Event/EmsEvent/RHEVM/UNKNOWN#create]
>[----] I, [2018-06-08T11:49:01.778050 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Followed  Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:49:01.778154 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Processed State=[State3] with Result=[ok]
>[----] I, [2018-06-08T11:49:01.778220 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Processed State=[State3]
>[----] I, [2018-06-08T11:49:01.778364 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) In State=[State3], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:49:01.787762 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:49:01.827570 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:49:01.829168 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:49:01.965361 #34080:a1b4784]  INFO -- : <AEMethod poweroff> VM 'ytale-v2v-rhel74-23-6' is already off. Nothing to do.
>[----] I, [2018-06-08T11:49:01.989428 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Ending
>[----] I, [2018-06-08T11:49:01.989595 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:01.989968 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Followed  Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-08T11:49:01.990071 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Processed State=[State3] with Result=[ok]
>[----] I, [2018-06-08T11:49:01.990137 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Processed State=[State3]
>[----] I, [2018-06-08T11:49:01.990280 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) In State=[State3], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:49:02.001074 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:49:02.043221 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:49:02.044849 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:49:02.430456 #25229:adf728]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Power off", "message"=>"State3 is finished.", "started_on"=>2018-06-08 15:49:01 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:49:02 UTC}
>[----] I, [2018-06-08T11:49:02.482337 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:02.482495 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:02.482992 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Next State=[State4]
>[----] I, [2018-06-08T11:49:02.483364 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) In State=[State4], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:49:02.492844 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:49:02.524893 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:49:02.527365 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:49:02.646235 #34080:adf82c]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Power off", "message"=>"State3 is finished.", "started_on"=>2018-06-08 15:49:01 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:49:02 UTC}
>[----] I, [2018-06-08T11:49:02.696914 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:02.697123 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:02.697637 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Next State=[State4]
>[----] I, [2018-06-08T11:49:02.698065 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) In State=[State4], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:49:02.706417 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:49:02.741751 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:49:02.743238 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:49:02.943511 #25229:355b63c]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Collapse Snapshots", "started_on"=>2018-06-08 15:49:02 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:49:03.006531 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:03.006802 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] E, [2018-06-08T11:49:03.007373 #25229:aed10c] ERROR -- : Q-task_id([service_template_transformation_plan_task_16]) State=<State4> running  raised exception: <Object Not Found for path=[state_var]>
>[----] W, [2018-06-08T11:49:03.007509 #25229:aed10c]  WARN -- : Q-task_id([service_template_transformation_plan_task_16]) Error in State=[State4]
>[----] I, [2018-06-08T11:49:03.007626 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) In State=[State4], invoking [on_error] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:49:03.017924 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:49:03.057522 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:49:03.059229 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:49:03.148344 #34080:44d0fe0]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Collapse Snapshots", "started_on"=>2018-06-08 15:49:03 UTC, "percent"=>0.0}
>[----] I, [2018-06-08T11:49:03.201622 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:03.201799 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>[----] E, [2018-06-08T11:49:03.202398 #34080:aed10c] ERROR -- : Q-task_id([service_template_transformation_plan_task_17]) State=<State4> running  raised exception: <Object Not Found for path=[state_var]>
>[----] W, [2018-06-08T11:49:03.202530 #34080:aed10c]  WARN -- : Q-task_id([service_template_transformation_plan_task_17]) Error in State=[State4]
>[----] I, [2018-06-08T11:49:03.202649 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) In State=[State4], invoking [on_error] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-08T11:49:03.211545 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus  ManageIQ/system/commonmethods]
>[----] I, [2018-06-08T11:49:03.247505 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-08T11:49:03.249089 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-08T11:49:03.456793 #25229:4d474d0]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"failed", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Failed to Collapse Snapshots.", "started_on"=>2018-06-08 15:49:02 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:49:03 UTC}
>[----] I, [2018-06-08T11:49:03.517412 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:03.517600 #25229:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_16]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-08T11:49:03.655450 #34080:55c7e5c]  INFO -- : <AEMethod weightedupdatestatus> State Hash: {"status"=>"failed", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Failed to Collapse Snapshots.", "started_on"=>2018-06-08 15:49:03 UTC, "percent"=>100.0, "updated_on"=>2018-06-08 15:49:03 UTC}
>[----] I, [2018-06-08T11:49:03.709183 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-08T11:49:03.709403 #34080:aed10c]  INFO -- : Q-task_id([service_template_transformation_plan_task_17]) Method exited with rc=MIQ_OK
>

Actions: View

Attachments on bug 1589297: 1449165