Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 1449165 Details for
Bug 1589297
[v2v] Migrations Fail with "Object not found" before they could start
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
automation.log
automation.log (text/plain), 129.77 KB, created by
Kedar Kulkarni
on 2018-06-08 15:50:01 UTC
(
hide
)
Description:
automation.log
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 >
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1589297
: 1449165