Attachment 1455230 Details for Bug 1596143 – punycode_automation.log

punycode_automation.log

punycode_automation.log (text/plain), 200.33 KB, created by Yadnyawalk Tale on 2018-06-28 10:12:59 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Yadnyawalk Tale

Created: 2018-06-28 10:12:59 UTC

Size: 200.33 KB

patch

obsolete

>[----] I, [2018-06-28T06:00:46.301558 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:00:46.313571 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7674&MiqRequest%3A%3Amiq_request=18&MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanRequest%3A%3Aservice_template_transformation_plan_request=18&User%3A%3Auser=1&event_stream_id=7674&event_type=request_created&object_name=Event&vmdb_object_type=service_template_transformation_plan_request]
>[----] I, [2018-06-28T06:00:46.329232 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7674&MiqRequest%3A%3Amiq_request=18&MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanRequest%3A%3Aservice_template_transformation_plan_request=18&User%3A%3Auser=1&event_stream_id=7674&event_type=request_created&object_name=Event&vmdb_object_type=service_template_transformation_plan_request ManageIQ/System]
>[----] I, [2018-06-28T06:00:46.392656 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/RequestEvent/Request/request_created#create]
>[----] I, [2018-06-28T06:00:46.404893 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/RequestEvent/Request/request_created#create ManageIQ/System/Event/RequestEvent]
>[----] I, [2018-06-28T06:00:46.426406 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Policy/request_created#create]
>[----] I, [2018-06-28T06:00:46.429976 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Policy/request_created#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:46.441313 #12245:e0513c] INFO -- : Updated namespace [System/Policy/get_request_type ManageIQ/System]
>[----] I, [2018-06-28T06:00:46.446992 #12245:e0513c] INFO -- : Invoking [inline] method [/ManageIQ/System/Policy/get_request_type] with inputs [{}]
>[----] I, [2018-06-28T06:00:46.448232 #12245:e0513c] INFO -- : <AEMethod [/ManageIQ/System/Policy/get_request_type]> Starting 
>[----] I, [2018-06-28T06:00:47.080026 #12245:e06c58] INFO -- : <AEMethod get_request_type> Request Type:<ServiceTemplateTransformationPlanRequest>
>[----] I, [2018-06-28T06:00:47.100908 #12245:e0513c] INFO -- : <AEMethod [/ManageIQ/System/Policy/get_request_type]> Ending
>[----] I, [2018-06-28T06:00:47.101098 #12245:e0513c] INFO -- : Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:00:47.105138 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Process/parse_provider_category#create]
>[----] I, [2018-06-28T06:00:47.108183 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Process/parse_provider_category#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:47.116049 #12245:e0513c] INFO -- : Updated namespace [System/Process/parse_provider_category ManageIQ/System]
>[----] I, [2018-06-28T06:00:47.122624 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/Process/parse_provider_category] with inputs [{}]
>[----] I, [2018-06-28T06:00:47.126749 #12245:e0513c] INFO -- : Setting provider_category to: 
>[----] I, [2018-06-28T06:00:47.128076 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Process/parse_provider_category#create]
>[----] I, [2018-06-28T06:00:47.128410 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Policy/ServiceTemplateTransformationPlanRequest_created#create]
>[----] I, [2018-06-28T06:00:47.130135 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Policy/ServiceTemplateTransformationPlanRequest_created#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:47.134888 #12245:e0513c] INFO -- : Evaluating substituted assertion [true]
>[----] I, [2018-06-28T06:00:47.135630 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/Transformation/StateMachines/TransformationPlanRequestApproval/Default#create]
>[----] I, [2018-06-28T06:00:47.291456 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/Transformation/StateMachines/TransformationPlanRequestApproval/Default#create ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-28T06:00:47.295987 #41070:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<"evm_worker_start"> inputs=<{:event_details=>"Worker started: ID [63], PID [44858], GUID [399a80d8-b527-4d57-b8af-00f0ef9faea4]", :type=>"ManageIQ::Providers::Redhat::InfraManager::RefreshWorker", "MiqEvent::miq_event"=>7675, :miq_event_id=>7675, "EventStream::event_stream"=>7675, :event_stream_id=>7675}>
>[----] I, [2018-06-28T06:00:47.317640 #41070:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<"evm_worker_start"> inputs=<{:event_details=>"Worker started: ID [64], PID [44867], GUID [dbbca9dc-2d9b-4752-8d66-09fc4b0f85c3]", :type=>"ManageIQ::Providers::Redhat::NetworkManager::RefreshWorker", "MiqEvent::miq_event"=>7676, :miq_event_id=>7676, "EventStream::event_stream"=>7676, :event_stream_id=>7676}>
>[----] I, [2018-06-28T06:00:47.339436 #41070:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<"ems_auth_valid"> inputs=<{"MiqEvent::miq_event"=>7677, :miq_event_id=>7677, "EventStream::event_stream"=>7677, :event_stream_id=>7677}>
>[----] I, [2018-06-28T06:00:47.365785 #41070:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<"ems_auth_valid"> inputs=<{"MiqEvent::miq_event"=>7678, :miq_event_id=>7678, "EventStream::event_stream"=>7678, :event_stream_id=>7678}>
>[----] I, [2018-06-28T06:00:47.487397 #12245:e0513c] INFO -- : In State=[ValidateRequest], invoking [on_entry] method=[validate_request]
>[----] I, [2018-06-28T06:00:47.489172 #12245:e0513c] INFO -- : Updated namespace [Transformation/StateMachines/TransformationPlanRequestApproval/validate_request ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-28T06:00:47.494446 #12245:e0513c] INFO -- : Invoking [inline] method [/ManageIQ/Transformation/StateMachines/TransformationPlanRequestApproval/validate_request] with inputs [{}]
>[----] I, [2018-06-28T06:00:47.495465 #12245:e0513c] INFO -- : <AEMethod [/ManageIQ/Transformation/StateMachines/TransformationPlanRequestApproval/validate_request]> Starting 
>[----] I, [2018-06-28T06:00:47.974078 #12245:e0513c] INFO -- : <AEMethod [/ManageIQ/Transformation/StateMachines/TransformationPlanRequestApproval/validate_request]> Ending
>[----] I, [2018-06-28T06:00:47.974283 #12245:e0513c] INFO -- : Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:00:47.975662 #12245:e0513c] INFO -- : Processed State=[ValidateRequest]
>[----] I, [2018-06-28T06:00:47.975931 #12245:e0513c] INFO -- : Next State=[ApproveRequest]
>[----] I, [2018-06-28T06:00:47.976257 #12245:e0513c] INFO -- : In State=[ApproveRequest], invoking [on_entry] method=[approve_request]
>[----] I, [2018-06-28T06:00:47.979379 #12245:e0513c] INFO -- : Updated namespace [Transformation/StateMachines/TransformationPlanRequestApproval/approve_request ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-28T06:00:47.987304 #12245:e0513c] INFO -- : Invoking [inline] method [/ManageIQ/Transformation/StateMachines/TransformationPlanRequestApproval/approve_request] with inputs [{}]
>[----] I, [2018-06-28T06:00:47.988453 #12245:e0513c] INFO -- : <AEMethod [/ManageIQ/Transformation/StateMachines/TransformationPlanRequestApproval/approve_request]> Starting 
>[----] I, [2018-06-28T06:00:48.320257 #12245:43428b8] INFO -- : <AEMethod approve_request> Checking for auto_approval
>[----] I, [2018-06-28T06:00:48.322181 #12245:43428b8] INFO -- : <AEMethod approve_request> AUTO-APPROVING
>[----] I, [2018-06-28T06:00:48.383996 #12245:e0513c] INFO -- : <AEMethod [/ManageIQ/Transformation/StateMachines/TransformationPlanRequestApproval/approve_request]> Ending
>[----] I, [2018-06-28T06:00:48.384157 #12245:e0513c] INFO -- : Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:00:48.387675 #12245:e0513c] INFO -- : Processed State=[ApproveRequest]
>[----] I, [2018-06-28T06:00:48.387916 #12245:e0513c] INFO -- : Next State=[]
>[----] I, [2018-06-28T06:00:48.388077 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/Transformation/StateMachines/TransformationPlanRequestApproval/Default#create]
>[----] I, [2018-06-28T06:00:48.388508 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Policy/ServiceTemplateTransformationPlanRequest_created#create]
>[----] I, [2018-06-28T06:00:48.388935 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Policy/request_created#create]
>[----] I, [2018-06-28T06:00:48.389409 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/RequestEvent/Request/request_created#create]
>[----] I, [2018-06-28T06:00:51.371605 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:00:51.373642 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7675&MiqEvent%3A%3Amiq_event=7675&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_details=Worker%20started%3A%20ID%20%5B63%5D%2C%20PID%20%5B44858%5D%2C%20GUID%20%5B399a80d8-b527-4d57-b8af-00f0ef9faea4%5D&event_stream_id=7675&event_type=evm_worker_start&miq_event_id=7675&object_name=Event&type=ManageIQ%3A%3AProviders%3A%3ARedhat%3A%3AInfraManager%3A%3ARefreshWorker&vmdb_object_type=miq_server]
>[----] I, [2018-06-28T06:00:51.389737 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7675&MiqEvent%3A%3Amiq_event=7675&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_details=Worker%20started%3A%20ID%20%5B63%5D%2C%20PID%20%5B44858%5D%2C%20GUID%20%5B399a80d8-b527-4d57-b8af-00f0ef9faea4%5D&event_stream_id=7675&event_type=evm_worker_start&miq_event_id=7675&object_name=Event&type=ManageIQ%3A%3AProviders%3A%3ARedhat%3A%3AInfraManager%3A%3ARefreshWorker&vmdb_object_type=miq_server ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.417603 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:00:51.420171 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7676&MiqEvent%3A%3Amiq_event=7676&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_details=Worker%20started%3A%20ID%20%5B64%5D%2C%20PID%20%5B44867%5D%2C%20GUID%20%5Bdbbca9dc-2d9b-4752-8d66-09fc4b0f85c3%5D&event_stream_id=7676&event_type=evm_worker_start&miq_event_id=7676&object_name=Event&type=ManageIQ%3A%3AProviders%3A%3ARedhat%3A%3ANetworkManager%3A%3ARefreshWorker&vmdb_object_type=miq_server]
>[----] I, [2018-06-28T06:00:51.438780 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7676&MiqEvent%3A%3Amiq_event=7676&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_details=Worker%20started%3A%20ID%20%5B64%5D%2C%20PID%20%5B44867%5D%2C%20GUID%20%5Bdbbca9dc-2d9b-4752-8d66-09fc4b0f85c3%5D&event_stream_id=7676&event_type=evm_worker_start&miq_event_id=7676&object_name=Event&type=ManageIQ%3A%3AProviders%3A%3ARedhat%3A%3ANetworkManager%3A%3ARefreshWorker&vmdb_object_type=miq_server ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.449353 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/evm_worker_start#create]
>[----] I, [2018-06-28T06:00:51.461751 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/evm_worker_start#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:00:51.477159 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/evm_worker_start] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:00:51.484088 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:00:51.486940 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.491749 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.496453 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:00:51.508330 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:00:51.508762 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/evm_worker_start#create]
>[----] I, [2018-06-28T06:00:51.514671 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/evm_worker_start#create]
>[----] I, [2018-06-28T06:00:51.522315 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:00:51.524256 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7677&ExtManagementSystem%3A%3Aext_management_system=1&MiqEvent%3A%3Amiq_event=7677&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_stream_id=7677&event_type=ems_auth_valid&miq_event_id=7677&object_name=Event&vmdb_object_type=ext_management_system]
>[----] I, [2018-06-28T06:00:51.527784 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/evm_worker_start#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:00:51.539770 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7677&ExtManagementSystem%3A%3Aext_management_system=1&MiqEvent%3A%3Amiq_event=7677&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_stream_id=7677&event_type=ems_auth_valid&miq_event_id=7677&object_name=Event&vmdb_object_type=ext_management_system ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.545090 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/evm_worker_start] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:00:51.553566 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:00:51.556661 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.562357 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.567676 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:00:51.582873 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:00:51.583348 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/evm_worker_start#create]
>[----] I, [2018-06-28T06:00:51.597296 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:00:51.599465 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7678&ExtManagementSystem%3A%3Aext_management_system=1&MiqEvent%3A%3Amiq_event=7678&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_stream_id=7678&event_type=ems_auth_valid&miq_event_id=7678&object_name=Event&vmdb_object_type=ext_management_system]
>[----] I, [2018-06-28T06:00:51.601857 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/ems_auth_valid#create]
>[----] I, [2018-06-28T06:00:51.614016 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/ems_auth_valid#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:00:51.615696 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7678&ExtManagementSystem%3A%3Aext_management_system=1&MiqEvent%3A%3Amiq_event=7678&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&event_stream_id=7678&event_type=ems_auth_valid&miq_event_id=7678&object_name=Event&vmdb_object_type=ext_management_system ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.629311 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/ems_auth_valid] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:00:51.636049 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:00:51.639109 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.644920 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.651106 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:00:51.660380 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:00:51.660870 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/ems_auth_valid#create]
>[----] I, [2018-06-28T06:00:51.679480 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/ems_auth_valid#create]
>[----] I, [2018-06-28T06:00:51.691616 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/ems_auth_valid#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:00:51.706021 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/ems_auth_valid] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:00:51.712376 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:00:51.714982 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.719632 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:00:51.724179 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:00:51.733174 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:00:51.733542 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/ems_auth_valid#create]
>[----] I, [2018-06-28T06:00:53.415942 #41070:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<"request_approved"> inputs=<{"EventStream::event_stream"=>7679, :event_stream_id=>7679}>
>[----] I, [2018-06-28T06:00:53.523775 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) MiqAeEvent.build_evm_event >> event=<"request_starting"> inputs=<{"EventStream::event_stream"=>7680, :event_stream_id=>7680}>
>[----] I, [2018-06-28T06:00:53.538761 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:00:53.553487 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7680&MiqRequest%3A%3Amiq_request=18&MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanRequest%3A%3Aservice_template_transformation_plan_request=18&User%3A%3Auser=1&event_stream_id=7680&event_type=request_starting&object_name=Event&vmdb_object_type=service_template_transformation_plan_request]
>[----] I, [2018-06-28T06:00:53.567186 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7680&MiqRequest%3A%3Amiq_request=18&MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanRequest%3A%3Aservice_template_transformation_plan_request=18&User%3A%3Auser=1&event_stream_id=7680&event_type=request_starting&object_name=Event&vmdb_object_type=service_template_transformation_plan_request ManageIQ/System]
>[----] I, [2018-06-28T06:00:53.650620 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Following Relationship [miqaedb:/System/Event/RequestEvent/Request/request_starting#create]
>[----] I, [2018-06-28T06:00:53.661476 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Updated namespace [miqaedb:/System/Event/RequestEvent/Request/request_starting#create ManageIQ/System/Event/RequestEvent]
>[----] I, [2018-06-28T06:00:53.680487 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Following Relationship [miqaedb:/System/Policy/request_starting#create]
>[----] I, [2018-06-28T06:00:53.683241 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Updated namespace [miqaedb:/System/Policy/request_starting#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:53.691876 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Updated namespace [System/Policy/get_request_type ManageIQ/System]
>[----] I, [2018-06-28T06:00:53.696469 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Invoking [inline] method [/ManageIQ/System/Policy/get_request_type] with inputs [{}]
>[----] I, [2018-06-28T06:00:53.700108 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) <AEMethod [/ManageIQ/System/Policy/get_request_type]> Starting 
>[----] I, [2018-06-28T06:00:54.161252 #28780:e14614] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) <AEMethod get_request_type> Request Type:<ServiceTemplateTransformationPlanRequest>
>[----] I, [2018-06-28T06:00:54.179181 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) <AEMethod [/ManageIQ/System/Policy/get_request_type]> Ending
>[----] I, [2018-06-28T06:00:54.179313 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:00:54.180265 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Following Relationship [miqaedb:/System/Process/parse_provider_category#create]
>[----] I, [2018-06-28T06:00:54.184622 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Updated namespace [miqaedb:/System/Process/parse_provider_category#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:54.192349 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Updated namespace [System/Process/parse_provider_category ManageIQ/System]
>[----] I, [2018-06-28T06:00:54.198096 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Invoking [builtin] method [/ManageIQ/System/Process/parse_provider_category] with inputs [{}]
>[----] I, [2018-06-28T06:00:54.268906 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Setting provider_category to: 
>[----] I, [2018-06-28T06:00:54.269828 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Followed Relationship [miqaedb:/System/Process/parse_provider_category#create]
>[----] I, [2018-06-28T06:00:54.270060 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Following Relationship [miqaedb:/System/Policy/ServiceTemplateTransformationPlanRequest_starting#create]
>[----] I, [2018-06-28T06:00:54.272071 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Updated namespace [miqaedb:/System/Policy/ServiceTemplateTransformationPlanRequest_starting#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:54.275590 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Followed Relationship [miqaedb:/System/Policy/ServiceTemplateTransformationPlanRequest_starting#create]
>[----] I, [2018-06-28T06:00:54.275879 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Followed Relationship [miqaedb:/System/Policy/request_starting#create]
>[----] I, [2018-06-28T06:00:54.276181 #28780:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_request_18]) Followed Relationship [miqaedb:/System/Event/RequestEvent/Request/request_starting#create]
>[----] I, [2018-06-28T06:00:56.441939 #41070:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:00:56.455321 #41070:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7679&MiqRequest%3A%3Amiq_request=18&MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanRequest%3A%3Aservice_template_transformation_plan_request=18&User%3A%3Auser=1&event_stream_id=7679&event_type=request_approved&object_name=Event&vmdb_object_type=service_template_transformation_plan_request]
>[----] I, [2018-06-28T06:00:56.472101 #41070:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7679&MiqRequest%3A%3Amiq_request=18&MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanRequest%3A%3Aservice_template_transformation_plan_request=18&User%3A%3Auser=1&event_stream_id=7679&event_type=request_approved&object_name=Event&vmdb_object_type=service_template_transformation_plan_request ManageIQ/System]
>[----] I, [2018-06-28T06:00:56.547987 #41070:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/RequestEvent/Request/request_approved#create]
>[----] I, [2018-06-28T06:00:56.561829 #41070:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/RequestEvent/Request/request_approved#create ManageIQ/System/Event/RequestEvent]
>[----] I, [2018-06-28T06:00:56.586419 #41070:e0513c] INFO -- : Following Relationship [miqaedb:/System/Policy/request_approved#create]
>[----] I, [2018-06-28T06:00:56.590489 #41070:e0513c] INFO -- : Updated namespace [miqaedb:/System/Policy/request_approved#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:56.601081 #41070:e0513c] INFO -- : Following Relationship [miqaedb:/System/Process/parse_provider_category#create]
>[----] I, [2018-06-28T06:00:56.603187 #41070:e0513c] INFO -- : Updated namespace [miqaedb:/System/Process/parse_provider_category#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:56.609873 #41070:e0513c] INFO -- : Updated namespace [System/Process/parse_provider_category ManageIQ/System]
>[----] I, [2018-06-28T06:00:56.615960 #41070:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/Process/parse_provider_category] with inputs [{}]
>[----] I, [2018-06-28T06:00:56.618856 #41070:e0513c] INFO -- : Setting provider_category to: 
>[----] I, [2018-06-28T06:00:56.619814 #41070:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Process/parse_provider_category#create]
>[----] I, [2018-06-28T06:00:56.620904 #41070:e0513c] INFO -- : Following Relationship [miqaedb:/System/Policy/ServiceTemplateTransformationPlanRequest_Approved#create]
>[----] I, [2018-06-28T06:00:56.622834 #41070:e0513c] INFO -- : Updated namespace [miqaedb:/System/Policy/ServiceTemplateTransformationPlanRequest_Approved#create ManageIQ/System]
>[----] I, [2018-06-28T06:00:56.628310 #41070:e0513c] INFO -- : Following Relationship [miqaedb:/System/Notification/Email/TransformationPlanRequestRequesterApproved#create]
>[----] I, [2018-06-28T06:00:56.639533 #41070:e0513c] INFO -- : Updated namespace [miqaedb:/System/Notification/Email/TransformationPlanRequestRequesterApproved#create ManageIQ/System/Notification]
>[----] I, [2018-06-28T06:00:56.677789 #41070:e0513c] INFO -- : Evaluating substituted assertion [true]
>[----] I, [2018-06-28T06:00:56.678188 #41070:e0513c] INFO -- : Following Relationship [miqaedb:/Configuration/Email/Default#create]
>[----] I, [2018-06-28T06:00:56.685534 #41070:e0513c] INFO -- : Updated namespace [miqaedb:/Configuration/Email/Default#create ManageIQ/Configuration]
>[----] I, [2018-06-28T06:00:56.709453 #41070:e0513c] INFO -- : Followed Relationship [miqaedb:/Configuration/Email/Default#create]
>[----] I, [2018-06-28T06:00:56.963800 #41070:e0513c] INFO -- : Invoking [builtin] method [/$/Object/send_email] with inputs [{"to"=>"evmadmin@example.com", "from"=>"evmadmin@example.com", "subject"=>"Request ID 18 - Your Transformation Plan Request was Approved, pending Quota Validation.", "body"=>"Hello, Your Transformation Plan Request was Approved. If Service provisioning is successful you will be notified via email when the Service is available. Approvers notes : Auto-Approved To view this Request go to : <a href=https://dhcp-8-197-178.cfme2.lab.eng.rdu2.redhat.com/miq_request/show/18>https://dhcp-8-197-178.cfme2.lab.eng.rdu2.redhat.com/miq_request/show/18</a> Thank you, Virtualization Infrastructure Team"}]
>[----] I, [2018-06-28T06:00:57.134516 #41070:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Notification/Email/TransformationPlanRequestRequesterApproved#create]
>[----] I, [2018-06-28T06:00:57.134799 #41070:e0513c] INFO -- : Following Relationship [miqaedb:/System/Notification/Email/TransformationPlanRequestApproverApproved#create]
>[----] I, [2018-06-28T06:00:57.136482 #41070:e0513c] INFO -- : Updated namespace [miqaedb:/System/Notification/Email/TransformationPlanRequestApproverApproved#create ManageIQ/System/Notification]
>[----] I, [2018-06-28T06:00:57.139134 #41070:e0513c] INFO -- : Evaluating substituted assertion [true]
>[----] I, [2018-06-28T06:00:57.139325 #41070:e0513c] INFO -- : Following Relationship [miqaedb:/Configuration/Email/Default#create]
>[----] I, [2018-06-28T06:00:57.140235 #41070:e0513c] INFO -- : Updated namespace [miqaedb:/Configuration/Email/Default#create ManageIQ/Configuration]
>[----] I, [2018-06-28T06:00:57.148161 #41070:e0513c] INFO -- : Followed Relationship [miqaedb:/Configuration/Email/Default#create]
>[----] I, [2018-06-28T06:00:57.311582 #41070:e0513c] INFO -- : Invoking [builtin] method [/$/Object/send_email] with inputs [{"to"=>"evmadmin@example.com", "from"=>"evmadmin@example.com", "subject"=>"Request ID 18 - Transformation Plan Request received from was Approved.", "body"=>"Approver, Transformation Plan Request received from was Approved. Approvers reason : Auto-Approved To view this Request go to : <a href=https://dhcp-8-197-178.cfme2.lab.eng.rdu2.redhat.com/miq_request/show/18>https://dhcp-8-197-178.cfme2.lab.eng.rdu2.redhat.com/miq_request/show/18</a> Thank you, Virtualization Infrastructure Team"}]
>[----] I, [2018-06-28T06:00:57.314968 #41070:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Notification/Email/TransformationPlanRequestApproverApproved#create]
>[----] I, [2018-06-28T06:00:57.315267 #41070:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Policy/ServiceTemplateTransformationPlanRequest_Approved#create]
>[----] I, [2018-06-28T06:00:57.315541 #41070:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Policy/request_approved#create]
>[----] I, [2018-06-28T06:00:57.315832 #41070:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/RequestEvent/Request/request_approved#create]
>[----] I, [2018-06-28T06:00:57.329588 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:00:57.364353 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Instantiating [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=18&User%3A%3Auser=1&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task]
>[----] I, [2018-06-28T06:00:57.496286 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=18&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-28T06:00:57.564395 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7681, :miq_event_id=>7681, "EventStream::event_stream"=>7681, :event_stream_id=>7681}>
>[----] I, [2018-06-28T06:00:57.601915 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7682, :miq_event_id=>7682, "EventStream::event_stream"=>7682, :event_stream_id=>7682}>
>[----] I, [2018-06-28T06:00:57.641207 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7683, :miq_event_id=>7683, "EventStream::event_stream"=>7683, :event_stream_id=>7683}>
>[----] I, [2018-06-28T06:00:57.676395 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7684, :miq_event_id=>7684, "EventStream::event_stream"=>7684, :event_stream_id=>7684}>
>[----] I, [2018-06-28T06:00:57.682820 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State1]
>[----] I, [2018-06-28T06:00:57.683059 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State2]
>[----] I, [2018-06-28T06:00:57.683244 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State2], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-28T06:00:57.692800 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:00:57.718843 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7685, :miq_event_id=>7685, "EventStream::event_stream"=>7685, :event_stream_id=>7685}>
>[----] I, [2018-06-28T06:00:57.723443 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-28T06:00:57.724558 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:00:57.759443 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7686, :miq_event_id=>7686, "EventStream::event_stream"=>7686, :event_stream_id=>7686}>
>[----] I, [2018-06-28T06:00:57.812131 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7687, :miq_event_id=>7687, "EventStream::event_stream"=>7687, :event_stream_id=>7687}>
>[----] I, [2018-06-28T06:00:57.858623 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7688, :miq_event_id=>7688, "EventStream::event_stream"=>7688, :event_stream_id=>7688}>
>[----] I, [2018-06-28T06:00:57.901864 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7689, :miq_event_id=>7689, "EventStream::event_stream"=>7689, :event_stream_id=>7689}>
>[----] I, [2018-06-28T06:00:57.941270 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7690, :miq_event_id=>7690, "EventStream::event_stream"=>7690, :event_stream_id=>7690}>
>[----] I, [2018-06-28T06:00:57.981875 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7691, :miq_event_id=>7691, "EventStream::event_stream"=>7691, :event_stream_id=>7691}>
>[----] I, [2018-06-28T06:00:58.016323 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7692, :miq_event_id=>7692, "EventStream::event_stream"=>7692, :event_stream_id=>7692}>
>[----] I, [2018-06-28T06:00:58.053051 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7693, :miq_event_id=>7693, "EventStream::event_stream"=>7693, :event_stream_id=>7693}>
>[----] I, [2018-06-28T06:00:58.086972 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7694, :miq_event_id=>7694, "EventStream::event_stream"=>7694, :event_stream_id=>7694}>
>[----] I, [2018-06-28T06:00:58.123343 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7695, :miq_event_id=>7695, "EventStream::event_stream"=>7695, :event_stream_id=>7695}>
>[----] I, [2018-06-28T06:00:58.157103 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7696, :miq_event_id=>7696, "EventStream::event_stream"=>7696, :event_stream_id=>7696}>
>[----] I, [2018-06-28T06:00:58.194115 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7697, :miq_event_id=>7697, "EventStream::event_stream"=>7697, :event_stream_id=>7697}>
>[----] I, [2018-06-28T06:00:58.230447 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7698, :miq_event_id=>7698, "EventStream::event_stream"=>7698, :event_stream_id=>7698}>
>[----] I, [2018-06-28T06:00:58.256392 #41070:df497c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Assess Migration", "message"=>"Assess Migration", "started_on"=>2018-06-28 10:00:58 UTC, "percent"=>0.0}
>[----] I, [2018-06-28T06:00:58.271764 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7699, :miq_event_id=>7699, "EventStream::event_stream"=>7699, :event_stream_id=>7699}>
>[----] I, [2018-06-28T06:00:58.310455 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:00:58.310556 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:00:58.310867 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processing State=[State2]
>[----] I, [2018-06-28T06:00:58.311057 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Following Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-28T06:00:58.312999 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7700, :miq_event_id=>7700, "EventStream::event_stream"=>7700, :event_stream_id=>7700}>
>[----] I, [2018-06-28T06:00:58.320262 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [miqaedb:/Transformation/Common/AssessTransformation#create ManageIQ/Transformation]
>[----] I, [2018-06-28T06:00:58.331707 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Instance [/ManageIQ/Transformation/Common/AssessTransformation] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:00:58.334266 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/Common/assesstransformation ManageIQ/Transformation]
>[----] I, [2018-06-28T06:00:58.339199 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/Transformation/Common/AssessTransformation] with inputs [{}]
>[----] I, [2018-06-28T06:00:58.340243 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Starting 
>[----] I, [2018-06-28T06:00:58.355519 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7701, :miq_event_id=>7701, "EventStream::event_stream"=>7701, :event_stream_id=>7701}>
>[----] I, [2018-06-28T06:00:58.393195 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7702, :miq_event_id=>7702, "EventStream::event_stream"=>7702, :event_stream_id=>7702}>
>[----] I, [2018-06-28T06:00:58.433134 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7703, :miq_event_id=>7703, "EventStream::event_stream"=>7703, :event_stream_id=>7703}>
>[----] I, [2018-06-28T06:00:58.472094 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7704, :miq_event_id=>7704, "EventStream::event_stream"=>7704, :event_stream_id=>7704}>
>[----] I, [2018-06-28T06:00:58.512918 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7705, :miq_event_id=>7705, "EventStream::event_stream"=>7705, :event_stream_id=>7705}>
>[----] I, [2018-06-28T06:00:58.552106 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7706, :miq_event_id=>7706, "EventStream::event_stream"=>7706, :event_stream_id=>7706}>
>[----] I, [2018-06-28T06:00:58.641844 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7707, :miq_event_id=>7707, "EventStream::event_stream"=>7707, :event_stream_id=>7707}>
>[----] I, [2018-06-28T06:00:58.677158 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7708, :miq_event_id=>7708, "EventStream::event_stream"=>7708, :event_stream_id=>7708}>
>[----] I, [2018-06-28T06:00:58.716874 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7709, :miq_event_id=>7709, "EventStream::event_stream"=>7709, :event_stream_id=>7709}>
>[----] I, [2018-06-28T06:00:58.747377 #41070:44dd074] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod assesstransformation> Network mappings: [{:source=>"VM Network", :destination=>"ovirtmgmt"}]
>[----] I, [2018-06-28T06:00:58.752378 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7710, :miq_event_id=>7710, "EventStream::event_stream"=>7710, :event_stream_id=>7710}>
>[----] I, [2018-06-28T06:00:58.787608 #41070:44dd074] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod assesstransformation> Source VM Disks [{:path=>"[NFS_Datastore_1] ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±/ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±.vmdk", :size=>21474836480, :percent=>0, :weight=>100.0}]
>[----] I, [2018-06-28T06:00:58.794855 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7711, :miq_event_id=>7711, "EventStream::event_stream"=>7711, :event_stream_id=>7711}>
>[----] I, [2018-06-28T06:00:58.833204 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7712, :miq_event_id=>7712, "EventStream::event_stream"=>7712, :event_stream_id=>7712}>
>[----] I, [2018-06-28T06:00:58.877264 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7713, :miq_event_id=>7713, "EventStream::event_stream"=>7713, :event_stream_id=>7713}>
>[----] I, [2018-06-28T06:00:58.877491 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/Common/AssessTransformation]> Ending
>[----] I, [2018-06-28T06:00:58.877600 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:00:58.877939 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Followed Relationship [miqaedb:/Transformation/Common/AssessTransformation#create]
>[----] I, [2018-06-28T06:00:58.878084 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State2] with Result=[ok]
>[----] I, [2018-06-28T06:00:58.878155 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State2]
>[----] I, [2018-06-28T06:00:58.878250 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State2], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Assess Migration", task_message => "Validating")]
>[----] I, [2018-06-28T06:00:58.880293 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:00:58.911306 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Assess Migration", "task_message"=>"Validating"}]
>[----] I, [2018-06-28T06:00:58.912215 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:00:58.916902 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7714, :miq_event_id=>7714, "EventStream::event_stream"=>7714, :event_stream_id=>7714}>
>[----] I, [2018-06-28T06:00:58.964234 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7715, :miq_event_id=>7715, "EventStream::event_stream"=>7715, :event_stream_id=>7715}>
>[----] I, [2018-06-28T06:00:59.006080 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7716, :miq_event_id=>7716, "EventStream::event_stream"=>7716, :event_stream_id=>7716}>
>[----] I, [2018-06-28T06:00:59.049194 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7717, :miq_event_id=>7717, "EventStream::event_stream"=>7717, :event_stream_id=>7717}>
>[----] I, [2018-06-28T06:00:59.089085 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7718, :miq_event_id=>7718, "EventStream::event_stream"=>7718, :event_stream_id=>7718}>
>[----] I, [2018-06-28T06:00:59.131204 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7719, :miq_event_id=>7719, "EventStream::event_stream"=>7719, :event_stream_id=>7719}>
>[----] I, [2018-06-28T06:00:59.171517 #28780:e0513c] INFO -- : MiqAeEvent.build_evm_event >> event=<:assigned_company_tag> inputs=<{"MiqEvent::miq_event"=>7720, :miq_event_id=>7720, "EventStream::event_stream"=>7720, :event_stream_id=>7720}>
>[----] I, [2018-06-28T06:00:59.251652 #41070:a2d60cc] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State2 is finished.", "started_on"=>2018-06-28 10:00:58 UTC, "percent"=>100.0, "updated_on"=>2018-06-28 10:00:59 UTC}
>[----] I, [2018-06-28T06:00:59.297985 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:00:59.298093 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:00:59.298660 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State3]
>[----] I, [2018-06-28T06:00:59.298963 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State3]
>[----] I, [2018-06-28T06:00:59.299154 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State4]
>[----] I, [2018-06-28T06:00:59.299333 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State4]
>[----] I, [2018-06-28T06:00:59.299540 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State5]
>[----] I, [2018-06-28T06:00:59.299739 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State5], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-28T06:00:59.302392 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:00:59.331492 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-28T06:00:59.332401 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:00:59.784097 #41070:df497c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-28 10:00:59 UTC, "percent"=>0.0}
>[----] I, [2018-06-28T06:00:59.836833 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:00:59.836994 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:00:59.837517 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processing State=[State5]
>[----] I, [2018-06-28T06:00:59.837718 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Following Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-28T06:00:59.840856 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [miqaedb:/Transformation/Common/AcquireTransformationHost#create ManageIQ/Transformation]
>[----] I, [2018-06-28T06:00:59.841137 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Instance [/ManageIQ/Transformation/Common/AcquireTransformationHost] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:00:59.843615 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/Common/acquiretransformationhost ManageIQ/Transformation]
>[----] I, [2018-06-28T06:00:59.849691 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/Transformation/Common/AcquireTransformationHost] with inputs [{}]
>[----] I, [2018-06-28T06:00:59.859864 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/TransformationHosts/Common/Utils ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:00:59.876108 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Loading embedded method ManageIQ/Transformation/TransformationHosts/Common/Utils
>[----] I, [2018-06-28T06:00:59.877163 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Starting 
>[----] I, [2018-06-28T06:01:00.229753 #41070:4704988] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod acquiretransformationhost> Task: #<MiqAeServiceServiceTemplateTransformationPlanTask:0xa49f688 @object=#<ServiceTemplateTransformationPlanTask id: 18, description: "Transforming VM [ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶Ñ...", state: "active", request_type: "transformation_plan", userid: "admin", options: {:dialog=>nil, :workflow_settings=>{:resource_action_id=>27}, :initiator=>nil, :src_id=>17, :cart_state=>"ordered", :requester_group=>"EvmGroup-super_administrator", :executed_on_servers=>[1, 1], :delivered_on=>2018-06-28 10:00:54 UTC, :progress=>{"current_state"=>"/State5", "current_description"=>"Acquire Transformation Host", "percent"=>1.0, "states"=>{"/State2"=>{"status"=>"finished", "weight"=>1, "description"=>"Assess Migration", "message"=>"State2 is finished.", "started_on"=>2018-06-28 10:00:58 UTC, "percent"=>100.0, "updated_on"=>2018-06-28 10:00:59 UTC}, "/State5"=>{"status"=>"active", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"Acquire Transformation Host", "started_on"=>2018-06-28 10:00:59 UTC, "percent"=>0.0}}}, :virtv2v_networks=>[{:source=>"VM Network", :destination=>"ovirtmgmt"}], :virtv2v_disks=>[{:path=>"[NFS_Datastore_1] ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±/ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±.vmdk", :size=>21474836480, :percent=>0, :weight=>100.0}], :source_vm_power_state=>"off", :collapse_snapshots=>true, :power_off=>true}, created_on: "2018-06-28 10:00:54", updated_on: "2018-06-28 10:00:59", message: "Pre-migration", status: "Ok", type: "ServiceTemplateTransformationPlanTask", miq_request_id: 18, source_id: 258, 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-28T06:01:00.234173 #41070:4704988] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod acquiretransformationhost> Source VM: ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±
>[----] I, [2018-06-28T06:01:00.238238 #41070:4704988] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod acquiretransformationhost> Source Cluster: Cluster
>[----] I, [2018-06-28T06:01:00.253218 #41070:4704988] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod acquiretransformationhost> Destination Cluster: V2V
>[----] I, [2018-06-28T06:01:00.341046 #41070:4704988] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod acquiretransformationhost> Transformation Host: rhelh09.v2v.bos.redhat.com.
>[----] I, [2018-06-28T06:01:00.398320 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/Common/AcquireTransformationHost]> Ending
>[----] I, [2018-06-28T06:01:00.398492 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:00.398788 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Followed Relationship [miqaedb:/Transformation/Common/AcquireTransformationHost#create]
>[----] I, [2018-06-28T06:01:00.398881 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State5] with Result=[ok]
>[----] I, [2018-06-28T06:01:00.398970 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State5]
>[----] I, [2018-06-28T06:01:00.399099 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State5], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Acquire Transformation Host", task_message => "Pre-migration")]
>[----] I, [2018-06-28T06:01:00.401864 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:00.437313 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Acquire Transformation Host", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-28T06:01:00.438394 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:00.787103 #41070:8ed9088] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Acquire Transformation Host", "message"=>"State5 is finished.", "started_on"=>2018-06-28 10:00:59 UTC, "percent"=>100.0, "updated_on"=>2018-06-28 10:01:00 UTC}
>[----] I, [2018-06-28T06:01:00.841726 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:00.841861 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:00.842475 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State6]
>[----] I, [2018-06-28T06:01:00.842893 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State6]
>[----] I, [2018-06-28T06:01:00.843127 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State7]
>[----] I, [2018-06-28T06:01:00.843330 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State7]
>[----] I, [2018-06-28T06:01:00.843550 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State8]
>[----] I, [2018-06-28T06:01:00.843768 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State8]
>[----] I, [2018-06-28T06:01:00.843989 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State9]
>[----] I, [2018-06-28T06:01:00.844190 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State9]
>[----] I, [2018-06-28T06:01:00.844401 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State10]
>[----] I, [2018-06-28T06:01:00.844593 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State10]
>[----] I, [2018-06-28T06:01:00.844802 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State11]
>[----] I, [2018-06-28T06:01:00.845012 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State11], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-28T06:01:00.848327 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:00.882764 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-28T06:01:00.883856 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:01.360735 #41070:df497c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Power off", "message"=>"Power off", "started_on"=>2018-06-28 10:01:01 UTC, "percent"=>0.0}
>[----] I, [2018-06-28T06:01:01.428738 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:01.428855 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:01.429209 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processing State=[State11]
>[----] I, [2018-06-28T06:01:01.429421 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Following Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-28T06:01:01.457926 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-28T06:01:01.488452 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Instance [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:01.491464 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/Infrastructure/VM/Common/poweroff ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-28T06:01:01.499324 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff] with inputs [{}]
>[----] I, [2018-06-28T06:01:01.500318 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Starting 
>[----] I, [2018-06-28T06:01:01.874644 #41070:4b9e1d8] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod poweroff> VM 'ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±' is already off. Nothing to do.
>[----] I, [2018-06-28T06:01:01.893735 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/Common/PowerOff]> Ending
>[----] I, [2018-06-28T06:01:01.893868 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:01.894253 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Followed Relationship [miqaedb:/Transformation/Infrastructure/VM/Common/PowerOff#create]
>[----] I, [2018-06-28T06:01:01.894392 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State11] with Result=[ok]
>[----] I, [2018-06-28T06:01:01.894479 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State11]
>[----] I, [2018-06-28T06:01:01.894621 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State11], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Power off", task_message => "Pre-migration")]
>[----] I, [2018-06-28T06:01:01.898034 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:01.939165 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Power off", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-28T06:01:01.940697 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:02.350128 #41070:543092c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Power off", "message"=>"State11 is finished.", "started_on"=>2018-06-28 10:01:01 UTC, "percent"=>100.0, "updated_on"=>2018-06-28 10:01:02 UTC}
>[----] I, [2018-06-28T06:01:02.415329 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:02.415498 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:02.416174 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State12]
>[----] I, [2018-06-28T06:01:02.416515 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State12]
>[----] I, [2018-06-28T06:01:02.416732 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State13]
>[----] I, [2018-06-28T06:01:02.416931 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State13]
>[----] I, [2018-06-28T06:01:02.417148 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State14]
>[----] I, [2018-06-28T06:01:02.417331 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State14], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-28T06:01:02.420316 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:02.463239 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-28T06:01:02.464633 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:02.730378 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:02.734387 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7681&MiqEvent%3A%3Amiq_event=7681&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=1&event_stream_id=7681&event_type=assigned_company_tag&miq_event_id=7681&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:02.756156 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7681&MiqEvent%3A%3Amiq_event=7681&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=1&event_stream_id=7681&event_type=assigned_company_tag&miq_event_id=7681&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:02.815507 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:02.827183 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7682&MiqEvent%3A%3Amiq_event=7682&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=1&event_stream_id=7682&event_type=assigned_company_tag&miq_event_id=7682&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:02.841478 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:02.846254 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7682&MiqEvent%3A%3Amiq_event=7682&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=1&event_stream_id=7682&event_type=assigned_company_tag&miq_event_id=7682&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:02.847198 #41070:902e320] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"Collapse Snapshots", "started_on"=>2018-06-28 10:01:02 UTC, "percent"=>0.0}
>[----] I, [2018-06-28T06:01:02.855477 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:02.872983 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:02.881190 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:02.886130 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:02.894623 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:02.900470 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:02.910473 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:02.910592 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:02.911090 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processing State=[State14]
>[----] I, [2018-06-28T06:01:02.911274 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Following Relationship [miqaedb:/Transformation/Infrastructure/VM/vmwarews/CollapseSnapshots#create]
>[----] I, [2018-06-28T06:01:02.916498 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [miqaedb:/Transformation/Infrastructure/VM/vmwarews/CollapseSnapshots#create ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-28T06:01:02.917124 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Instance [/ManageIQ/Transformation/Infrastructure/VM/vmwarews/CollapseSnapshots] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:02.920611 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/Infrastructure/VM/vmwarews/collapsesnapshots ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-28T06:01:02.929434 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/Transformation/Infrastructure/VM/vmwarews/CollapseSnapshots] with inputs [{}]
>[----] I, [2018-06-28T06:01:02.930674 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:02.931465 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/Infrastructure/VM/vmwarews/Utils ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-28T06:01:02.945567 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:02.969098 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Loading embedded method ManageIQ/Transformation/Infrastructure/VM/vmwarews/Utils
>[----] I, [2018-06-28T06:01:02.970763 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/vmwarews/CollapseSnapshots]> Starting 
>[----] I, [2018-06-28T06:01:02.972161 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:02.985039 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:02.988884 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:02.989526 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:02.990380 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.001549 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.007424 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:03.011054 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:03.015182 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7683&MiqEvent%3A%3Amiq_event=7683&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=2&event_stream_id=7683&event_type=assigned_company_tag&miq_event_id=7683&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:03.036752 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7683&MiqEvent%3A%3Amiq_event=7683&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=2&event_stream_id=7683&event_type=assigned_company_tag&miq_event_id=7683&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.080058 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.080572 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.096048 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:03.099490 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7684&MiqEvent%3A%3Amiq_event=7684&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=2&event_stream_id=7684&event_type=assigned_company_tag&miq_event_id=7684&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:03.113864 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.115941 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7684&MiqEvent%3A%3Amiq_event=7684&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=2&event_stream_id=7684&event_type=assigned_company_tag&miq_event_id=7684&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.128044 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:03.145600 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:03.154260 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.158136 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.164221 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.170531 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:03.187656 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.200595 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:03.217274 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:03.224809 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.228222 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.233625 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.238998 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:03.254925 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.255680 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.276894 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:03.285739 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7685&MiqEvent%3A%3Amiq_event=7685&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=3&event_stream_id=7685&event_type=assigned_company_tag&miq_event_id=7685&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:03.306703 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7685&MiqEvent%3A%3Amiq_event=7685&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=3&event_stream_id=7685&event_type=assigned_company_tag&miq_event_id=7685&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.314336 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.314861 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.329880 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:03.333343 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7686&MiqEvent%3A%3Amiq_event=7686&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=3&event_stream_id=7686&event_type=assigned_company_tag&miq_event_id=7686&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:03.349630 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7686&MiqEvent%3A%3Amiq_event=7686&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=3&event_stream_id=7686&event_type=assigned_company_tag&miq_event_id=7686&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.386023 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.401663 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:03.417448 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.420829 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:03.429713 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.430091 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:03.433606 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.440095 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.445422 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:03.446348 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:03.451879 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.454584 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.459306 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.465999 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:03.531849 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.532634 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.550346 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:03.550639 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.551120 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.554983 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7687&MiqEvent%3A%3Amiq_event=7687&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=4&event_stream_id=7687&event_type=assigned_company_tag&miq_event_id=7687&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:03.566487 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:03.569659 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7688&MiqEvent%3A%3Amiq_event=7688&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=4&event_stream_id=7688&event_type=assigned_company_tag&miq_event_id=7688&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:03.574814 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7687&MiqEvent%3A%3Amiq_event=7687&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=4&event_stream_id=7687&event_type=assigned_company_tag&miq_event_id=7687&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.580119 #41070:62e907c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod collapsesnapshots> VM 'ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±' has no snapshot. Nothing to do.
>[----] I, [2018-06-28T06:01:03.584697 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7688&MiqEvent%3A%3Amiq_event=7688&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=4&event_stream_id=7688&event_type=assigned_company_tag&miq_event_id=7688&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.622401 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/Infrastructure/VM/vmwarews/CollapseSnapshots]> Ending
>[----] I, [2018-06-28T06:01:03.622579 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:03.622865 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Followed Relationship [miqaedb:/Transformation/Infrastructure/VM/vmwarews/CollapseSnapshots#create]
>[----] I, [2018-06-28T06:01:03.622965 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State14] with Result=[ok]
>[----] I, [2018-06-28T06:01:03.623054 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State14]
>[----] I, [2018-06-28T06:01:03.623157 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State14], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Collapse Snapshots", task_message => "Pre-migration")]
>[----] I, [2018-06-28T06:01:03.626095 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:03.642417 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.653726 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:03.657732 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Collapse Snapshots", "task_message"=>"Pre-migration"}]
>[----] I, [2018-06-28T06:01:03.658651 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:03.670783 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:03.676834 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.677790 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.679385 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.684009 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.688624 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:03.690741 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:03.706187 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:03.714089 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.717631 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.723631 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.729480 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:03.751227 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.751645 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.765314 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:03.768223 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7689&MiqEvent%3A%3Amiq_event=7689&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=6&event_stream_id=7689&event_type=assigned_company_tag&miq_event_id=7689&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:03.782393 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7689&MiqEvent%3A%3Amiq_event=7689&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=6&event_stream_id=7689&event_type=assigned_company_tag&miq_event_id=7689&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.804671 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.805223 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.819965 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:03.823191 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7690&MiqEvent%3A%3Amiq_event=7690&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=6&event_stream_id=7690&event_type=assigned_company_tag&miq_event_id=7690&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:03.838500 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7690&MiqEvent%3A%3Amiq_event=7690&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=6&event_stream_id=7690&event_type=assigned_company_tag&miq_event_id=7690&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.841985 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.857517 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:03.871864 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:03.878318 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.881029 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.885707 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.890104 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:03.895694 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.907001 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:03.921434 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:03.927955 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.930731 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.935520 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:03.940265 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:03.952214 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:03.952582 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:03.966277 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:03.969066 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7691&MiqEvent%3A%3Amiq_event=7691&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=8&event_stream_id=7691&event_type=assigned_company_tag&miq_event_id=7691&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:03.983518 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7691&MiqEvent%3A%3Amiq_event=7691&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=8&event_stream_id=7691&event_type=assigned_company_tag&miq_event_id=7691&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.002546 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.003078 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.016891 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.019867 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7692&MiqEvent%3A%3Amiq_event=7692&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=8&event_stream_id=7692&event_type=assigned_company_tag&miq_event_id=7692&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:04.033765 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7692&MiqEvent%3A%3Amiq_event=7692&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=8&event_stream_id=7692&event_type=assigned_company_tag&miq_event_id=7692&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.040274 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.051400 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.064663 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.070350 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.073070 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.077331 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.081587 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.088353 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.099137 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.111886 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.117923 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.120860 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.125066 #41070:df7d0c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Collapse Snapshots", "message"=>"State14 is finished.", "started_on"=>2018-06-28 10:01:02 UTC, "percent"=>100.0, "updated_on"=>2018-06-28 10:01:04 UTC}
>[----] I, [2018-06-28T06:01:04.125383 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.129604 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.140663 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.141045 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.155650 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.158630 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7693&MiqEvent%3A%3Amiq_event=7693&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=9&event_stream_id=7693&event_type=assigned_company_tag&miq_event_id=7693&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:04.173320 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7693&MiqEvent%3A%3Amiq_event=7693&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=9&event_stream_id=7693&event_type=assigned_company_tag&miq_event_id=7693&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.182133 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:04.182212 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:04.182817 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State15]
>[----] I, [2018-06-28T06:01:04.183131 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State15]
>[----] I, [2018-06-28T06:01:04.183336 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State16]
>[----] I, [2018-06-28T06:01:04.183556 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State16]
>[----] I, [2018-06-28T06:01:04.183833 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State17]
>[----] I, [2018-06-28T06:01:04.184045 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State17], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 94, description => "Transform VM")]
>[----] I, [2018-06-28T06:01:04.186841 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:04.196657 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.197153 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.211830 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.215193 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7694&MiqEvent%3A%3Amiq_event=7694&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=9&event_stream_id=7694&event_type=assigned_company_tag&miq_event_id=7694&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:04.218555 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>94, "description"=>"Transform VM", "task_message"=>"_"}]
>[----] I, [2018-06-28T06:01:04.219502 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:04.230874 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7694&MiqEvent%3A%3Amiq_event=7694&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=9&event_stream_id=7694&event_type=assigned_company_tag&miq_event_id=7694&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.277752 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.289570 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.290497 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.302048 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.303578 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.309595 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.312617 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.316299 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.317265 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.321906 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.322735 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.325640 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.330156 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.334769 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.383549 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.384043 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.398227 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.399588 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.400106 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.401277 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7695&MiqEvent%3A%3Amiq_event=7695&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=10&event_stream_id=7695&event_type=assigned_company_tag&miq_event_id=7695&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:04.414712 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.416390 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7695&MiqEvent%3A%3Amiq_event=7695&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=10&event_stream_id=7695&event_type=assigned_company_tag&miq_event_id=7695&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.417835 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7696&MiqEvent%3A%3Amiq_event=7696&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=10&event_stream_id=7696&event_type=assigned_company_tag&miq_event_id=7696&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:04.433205 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7696&MiqEvent%3A%3Amiq_event=7696&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=10&event_stream_id=7696&event_type=assigned_company_tag&miq_event_id=7696&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.474317 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.485907 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.493594 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.499848 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.505389 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.506163 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.508998 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.513420 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.517845 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.519929 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.526843 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.529719 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.535183 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.540161 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.569933 #41070:498d40c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>94, "description"=>"Transform VM", "message"=>"Transform VM", "started_on"=>2018-06-28 10:01:04 UTC, "percent"=>0.0}
>[----] I, [2018-06-28T06:01:04.579138 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.579532 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.593519 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.596480 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7697&MiqEvent%3A%3Amiq_event=7697&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=11&event_stream_id=7697&event_type=assigned_company_tag&miq_event_id=7697&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:04.604510 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.604966 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.614830 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7697&MiqEvent%3A%3Amiq_event=7697&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=11&event_stream_id=7697&event_type=assigned_company_tag&miq_event_id=7697&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.616592 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:04.616752 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:04.617300 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processing State=[State17]
>[----] I, [2018-06-28T06:01:04.617543 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Following Relationship [miqaedb:/Transformation/StateMachines/VMTransformation/vmwarews2rhevm_vddk?state_ancestry=/State17#create]
>[----] I, [2018-06-28T06:01:04.620069 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [miqaedb:/Transformation/StateMachines/VMTransformation/vmwarews2rhevm_vddk?state_ancestry=/State17#create ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-28T06:01:04.620189 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.623418 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7698&MiqEvent%3A%3Amiq_event=7698&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=11&event_stream_id=7698&event_type=assigned_company_tag&miq_event_id=7698&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:04.629690 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State1]
>[----] I, [2018-06-28T06:01:04.629978 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State2]
>[----] I, [2018-06-28T06:01:04.630181 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State2], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Convert disks", task_message => "Migrating")]
>[----] I, [2018-06-28T06:01:04.632204 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:04.638233 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7698&MiqEvent%3A%3Amiq_event=7698&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=11&event_stream_id=7698&event_type=assigned_company_tag&miq_event_id=7698&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.666462 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Convert disks", "task_message"=>"Migrating"}]
>[----] I, [2018-06-28T06:01:04.667537 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:04.670278 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.681488 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.695587 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.695904 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.702037 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.705112 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.707652 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.709924 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.714962 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.722339 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.729290 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.732352 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.737431 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.742809 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.777798 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.778201 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.792733 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.795600 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7699&MiqEvent%3A%3Amiq_event=7699&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=12&event_stream_id=7699&event_type=assigned_company_tag&miq_event_id=7699&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:04.810334 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7699&MiqEvent%3A%3Amiq_event=7699&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=12&event_stream_id=7699&event_type=assigned_company_tag&miq_event_id=7699&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.815220 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.815722 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.830747 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.834098 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7700&MiqEvent%3A%3Amiq_event=7700&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=12&event_stream_id=7700&event_type=assigned_company_tag&miq_event_id=7700&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:04.849260 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7700&MiqEvent%3A%3Amiq_event=7700&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=12&event_stream_id=7700&event_type=assigned_company_tag&miq_event_id=7700&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.866552 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.877337 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.891079 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.897767 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.900866 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.905879 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.910460 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.910782 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.922662 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:04.937400 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:04.944531 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.947553 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.953013 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:04.958923 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:04.971174 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:04.971603 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:04.985672 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:04.988768 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7701&MiqEvent%3A%3Amiq_event=7701&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=13&event_stream_id=7701&event_type=assigned_company_tag&miq_event_id=7701&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.005123 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7701&MiqEvent%3A%3Amiq_event=7701&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=13&event_stream_id=7701&event_type=assigned_company_tag&miq_event_id=7701&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.020128 #41070:560e190] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>1, "description"=>"Convert disks", "message"=>"Convert disks", "started_on"=>2018-06-28 10:01:05 UTC, "percent"=>0.0}
>[----] I, [2018-06-28T06:01:05.025300 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.025763 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.040142 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.043534 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7702&MiqEvent%3A%3Amiq_event=7702&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=13&event_stream_id=7702&event_type=assigned_company_tag&miq_event_id=7702&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.060333 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7702&MiqEvent%3A%3Amiq_event=7702&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=13&event_stream_id=7702&event_type=assigned_company_tag&miq_event_id=7702&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.064541 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.076550 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.091176 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.095257 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:05.095343 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:05.095816 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processing State=[State2]
>[----] I, [2018-06-28T06:01:05.096001 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Following Relationship [miqaedb:/Transformation/TransformationHosts/ovirt_host/VMTransform_vmwarews2rhevm_vddk#create]
>[----] I, [2018-06-28T06:01:05.097610 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.100550 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.100730 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [miqaedb:/Transformation/TransformationHosts/ovirt_host/VMTransform_vmwarews2rhevm_vddk#create ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:05.105173 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.109994 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.118202 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Instance [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMTransform_vmwarews2rhevm_vddk] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.121148 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/TransformationHosts/ovirt_host/vmtransform_vmwarews2rhevm_vddk ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:05.123654 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.128376 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMTransform_vmwarews2rhevm_vddk] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.130063 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/TransformationHosts/Common/Utils ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:05.135934 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.147678 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Loading embedded method ManageIQ/Transformation/TransformationHosts/Common/Utils
>[----] I, [2018-06-28T06:01:05.149384 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/TransformationHosts/ovirt_host/Utils ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:05.151561 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.158220 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.160850 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.165237 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.166579 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Loading embedded method ManageIQ/Transformation/TransformationHosts/ovirt_host/Utils
>[----] I, [2018-06-28T06:01:05.168029 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/Infrastructure/VM/vmwarews/Utils ManageIQ/Transformation/Infrastructure/VM]
>[----] I, [2018-06-28T06:01:05.169647 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.169719 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.170118 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.185262 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.190262 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7703&MiqEvent%3A%3Amiq_event=7703&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=15&event_stream_id=7703&event_type=assigned_company_tag&miq_event_id=7703&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.196170 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Loading embedded method ManageIQ/Transformation/Infrastructure/VM/vmwarews/Utils
>[----] I, [2018-06-28T06:01:05.197795 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMTransform_vmwarews2rhevm_vddk]> Starting 
>[----] I, [2018-06-28T06:01:05.206105 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7703&MiqEvent%3A%3Amiq_event=7703&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=15&event_stream_id=7703&event_type=assigned_company_tag&miq_event_id=7703&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.219600 #28780:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.223604 #28780:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7704&MiqEvent%3A%3Amiq_event=7704&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=15&event_stream_id=7704&event_type=assigned_company_tag&miq_event_id=7704&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.231905 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.232325 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.241145 #28780:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7704&MiqEvent%3A%3Amiq_event=7704&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=15&event_stream_id=7704&event_type=assigned_company_tag&miq_event_id=7704&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.249672 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.257956 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7705&MiqEvent%3A%3Amiq_event=7705&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=16&event_stream_id=7705&event_type=assigned_company_tag&miq_event_id=7705&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.275367 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7705&MiqEvent%3A%3Amiq_event=7705&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=16&event_stream_id=7705&event_type=assigned_company_tag&miq_event_id=7705&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.275475 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.287410 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.302669 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.309874 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.313928 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.314752 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.318887 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.324116 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.329643 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.345154 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.347004 #28780:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.356121 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.357083 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.360017 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.367141 #28780:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.372229 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.375049 #28780:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.379700 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.382384 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.387464 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.392247 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.394407 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.394879 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.412866 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.415809 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7706&MiqEvent%3A%3Amiq_event=7706&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=16&event_stream_id=7706&event_type=assigned_company_tag&miq_event_id=7706&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.431382 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7706&MiqEvent%3A%3Amiq_event=7706&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=16&event_stream_id=7706&event_type=assigned_company_tag&miq_event_id=7706&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.456287 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.456657 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.469773 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.472546 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7707&MiqEvent%3A%3Amiq_event=7707&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=17&event_stream_id=7707&event_type=assigned_company_tag&miq_event_id=7707&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.477531 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.478285 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.487284 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7707&MiqEvent%3A%3Amiq_event=7707&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=17&event_stream_id=7707&event_type=assigned_company_tag&miq_event_id=7707&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.493787 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.496311 #28780:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.500987 #28780:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7708&MiqEvent%3A%3Amiq_event=7708&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=17&event_stream_id=7708&event_type=assigned_company_tag&miq_event_id=7708&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.506159 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.520619 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.521647 #28780:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7708&MiqEvent%3A%3Amiq_event=7708&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=17&event_stream_id=7708&event_type=assigned_company_tag&miq_event_id=7708&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.527731 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.530536 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.535883 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.540899 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.597173 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.608311 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.608598 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.609050 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.621988 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.624442 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.628006 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.628585 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7709&MiqEvent%3A%3Amiq_event=7709&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=18&event_stream_id=7709&event_type=assigned_company_tag&miq_event_id=7709&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.630360 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.632590 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.634757 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.639746 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.642670 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7709&MiqEvent%3A%3Amiq_event=7709&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=18&event_stream_id=7709&event_type=assigned_company_tag&miq_event_id=7709&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.648646 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.670541 #28780:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.681126 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.684593 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.692406 #28780:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.698996 #28780:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.702836 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.709541 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.710194 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.720875 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.730165 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.735306 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7710&MiqEvent%3A%3Amiq_event=7710&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=18&event_stream_id=7710&event_type=assigned_company_tag&miq_event_id=7710&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.742324 #41070:632dd44] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmtransform_vmwarews2rhevm_vddk> Transformation - Started On: 2018-06-28 10:01:05
>[----] I, [2018-06-28T06:01:05.745441 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.754857 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7710&MiqEvent%3A%3Amiq_event=7710&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=18&event_stream_id=7710&event_type=assigned_company_tag&miq_event_id=7710&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.757828 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.761320 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.766789 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.772002 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.783216 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.784136 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.804309 #41070:632dd44] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmtransform_vmwarews2rhevm_vddk> Source VM Disks: ["[NFS_Datastore_1] ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±/ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±.vmdk"]
>[----] I, [2018-06-28T06:01:05.804812 #28780:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.810372 #28780:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7711&MiqEvent%3A%3Amiq_event=7711&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=19&event_stream_id=7711&event_type=assigned_company_tag&miq_event_id=7711&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.820516 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.832970 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.836861 #28780:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7711&MiqEvent%3A%3Amiq_event=7711&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=19&event_stream_id=7711&event_type=assigned_company_tag&miq_event_id=7711&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.845177 #41070:632dd44] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmtransform_vmwarews2rhevm_vddk> vCenter fingerprint: E6:67:CC:A7:5D:16:75:80:DF:41:CA:76:98:E6:2C:F2:62:6B:1F:81
>[----] I, [2018-06-28T06:01:05.845315 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.845827 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.851655 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.856096 #41070:632dd44] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmtransform_vmwarews2rhevm_vddk> Executing '/usr/bin/virt-v2v-wrapper.py'
>[----] I, [2018-06-28T06:01:05.857819 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.860641 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.862302 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.865174 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.865777 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7712&MiqEvent%3A%3Amiq_event=7712&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=19&event_stream_id=7712&event_type=assigned_company_tag&miq_event_id=7712&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.869607 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:05.882528 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7712&MiqEvent%3A%3Amiq_event=7712&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=19&event_stream_id=7712&event_type=assigned_company_tag&miq_event_id=7712&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.934796 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.935222 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.949941 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:05.951887 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.956800 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:05.957795 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7713&MiqEvent%3A%3Amiq_event=7713&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=20&event_stream_id=7713&event_type=assigned_company_tag&miq_event_id=7713&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:05.966659 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.972898 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7713&MiqEvent%3A%3Amiq_event=7713&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=20&event_stream_id=7713&event_type=assigned_company_tag&miq_event_id=7713&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.979657 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:05.982825 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:05.990405 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:05.994050 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:05.999358 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.000140 #28780:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:06.004368 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.011000 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.015208 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.022877 #28780:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.029908 #28780:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.033462 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.046097 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:06.060409 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:06.067188 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.069984 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.075002 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.078981 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.079477 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.079500 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.094968 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:06.098620 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7714&MiqEvent%3A%3Amiq_event=7714&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=20&event_stream_id=7714&event_type=assigned_company_tag&miq_event_id=7714&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:06.116675 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7714&MiqEvent%3A%3Amiq_event=7714&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=20&event_stream_id=7714&event_type=assigned_company_tag&miq_event_id=7714&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.120192 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.120667 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.138964 #28780:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:06.144675 #28780:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7715&MiqEvent%3A%3Amiq_event=7715&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=22&event_stream_id=7715&event_type=assigned_company_tag&miq_event_id=7715&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:06.146068 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.146554 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.160283 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:06.163352 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7716&MiqEvent%3A%3Amiq_event=7716&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=22&event_stream_id=7716&event_type=assigned_company_tag&miq_event_id=7716&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:06.167897 #28780:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7715&MiqEvent%3A%3Amiq_event=7715&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=22&event_stream_id=7715&event_type=assigned_company_tag&miq_event_id=7715&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.177813 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7716&MiqEvent%3A%3Amiq_event=7716&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=22&event_stream_id=7716&event_type=assigned_company_tag&miq_event_id=7716&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.191178 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.205358 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:06.224499 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:06.233893 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.237344 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.242171 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.256604 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:06.271962 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:06.274099 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.278824 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.281740 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.283769 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.286549 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.289303 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.291309 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.293018 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:06.315502 #28780:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:06.326440 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.331443 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.340498 #28780:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.348204 #28780:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.358122 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.358571 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.362783 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.363342 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.374024 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:06.377462 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7717&MiqEvent%3A%3Amiq_event=7717&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=197&event_stream_id=7717&event_type=assigned_company_tag&miq_event_id=7717&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:06.378310 #12245:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:06.381794 #12245:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7718&MiqEvent%3A%3Amiq_event=7718&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=197&event_stream_id=7718&event_type=assigned_company_tag&miq_event_id=7718&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:06.393647 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7717&MiqEvent%3A%3Amiq_event=7717&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=197&event_stream_id=7717&event_type=assigned_company_tag&miq_event_id=7717&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.397596 #12245:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7718&MiqEvent%3A%3Amiq_event=7718&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=197&event_stream_id=7718&event_type=assigned_company_tag&miq_event_id=7718&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.440263 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.440702 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.455846 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.457594 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.459480 #28780:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:06.463652 #28780:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7719&MiqEvent%3A%3Amiq_event=7719&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=273&event_stream_id=7719&event_type=assigned_company_tag&miq_event_id=7719&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:06.467928 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:06.469827 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:06.482206 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:06.484278 #28780:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7719&MiqEvent%3A%3Amiq_event=7719&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=273&event_stream_id=7719&event_type=assigned_company_tag&miq_event_id=7719&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.486400 #12245:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:06.489136 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.491942 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.493886 #12245:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.496743 #12245:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.496888 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.501492 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.501601 #12245:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.506597 #12245:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.566506 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.567044 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.571372 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.571926 #12245:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.577486 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.582184 #12253:e0513c] INFO -- : User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:06.585235 #12253:e0513c] INFO -- : Instantiating [/System/Process/Event?EventStream%3A%3Aevent_stream=7720&MiqEvent%3A%3Amiq_event=7720&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=273&event_stream_id=7720&event_type=assigned_company_tag&miq_event_id=7720&object_name=Event&vmdb_object_type=vm]
>[----] I, [2018-06-28T06:01:06.597563 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:06.600606 #12253:e0513c] INFO -- : Updated namespace [/System/Process/Event?EventStream%3A%3Aevent_stream=7720&MiqEvent%3A%3Amiq_event=7720&MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&VmOrTemplate%3A%3Avm=273&event_stream_id=7720&event_type=assigned_company_tag&miq_event_id=7720&object_name=Event&vmdb_object_type=vm ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.619286 #28780:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:06.628802 #28780:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.632588 #28780:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.642123 #28780:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.648968 #28780:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.663505 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.675088 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create ManageIQ/System/Event/MiqEvent]
>[----] I, [2018-06-28T06:01:06.687930 #12253:e0513c] INFO -- : Instance [/ManageIQ/System/Event/MiqEvent/POLICY/assigned_company_tag] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:06.693855 #12253:e0513c] INFO -- : Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.696317 #12253:e0513c] INFO -- : Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.700669 #12253:e0513c] INFO -- : Updated namespace [System/event_handlers/event_enforce_policy ManageIQ/System]
>[----] I, [2018-06-28T06:01:06.704875 #12253:e0513c] INFO -- : Invoking [builtin] method [/ManageIQ/System/event_handlers/event_enforce_policy] with inputs [{}]
>[----] I, [2018-06-28T06:01:06.739876 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.740751 #28780:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:06.766211 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
>[----] I, [2018-06-28T06:01:06.766578 #12253:e0513c] INFO -- : Followed Relationship [miqaedb:/System/Event/MiqEvent/POLICY/assigned_company_tag#create]
>[----] I, [2018-06-28T06:01:14.981796 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMTransform_vmwarews2rhevm_vddk]> Ending
>[----] I, [2018-06-28T06:01:14.981926 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:14.982237 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Followed Relationship [miqaedb:/Transformation/TransformationHosts/ovirt_host/VMTransform_vmwarews2rhevm_vddk#create]
>[----] I, [2018-06-28T06:01:14.982302 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State2] with Result=[ok]
>[----] I, [2018-06-28T06:01:14.982373 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State2]
>[----] I, [2018-06-28T06:01:14.982549 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State2], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 1, description => "Convert disks", task_message => "Migrating")]
>[----] I, [2018-06-28T06:01:14.985250 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:15.017074 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>1, "description"=>"Convert disks", "task_message"=>"Migrating"}]
>[----] I, [2018-06-28T06:01:15.018287 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:15.517525 #41070:df528c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"finished", "weight"=>1, "description"=>"Convert disks", "message"=>"State2 is finished.", "started_on"=>2018-06-28 10:01:05 UTC, "percent"=>100.0, "updated_on"=>2018-06-28 10:01:15 UTC}
>[----] I, [2018-06-28T06:01:15.598816 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:15.599033 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:15.599913 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State3]
>[----] I, [2018-06-28T06:01:15.600442 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State3]
>[----] I, [2018-06-28T06:01:15.600842 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State4]
>[----] I, [2018-06-28T06:01:15.601152 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State4]
>[----] I, [2018-06-28T06:01:15.601531 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Next State=[State5]
>[----] I, [2018-06-28T06:01:15.601872 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State5], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 85, description => "Convert disks", task_message => "Migrating")]
>[----] I, [2018-06-28T06:01:15.605895 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:15.640373 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>85, "description"=>"Convert disks", "task_message"=>"Migrating"}]
>[----] I, [2018-06-28T06:01:15.641634 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:16.028270 #41070:4af0d08] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>85, "description"=>"Convert disks", "message"=>"Convert disks", "started_on"=>2018-06-28 10:01:16 UTC, "percent"=>0.0}
>[----] I, [2018-06-28T06:01:16.101572 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:16.101790 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:16.102399 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processing State=[State5]
>[----] I, [2018-06-28T06:01:16.102620 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Following Relationship [miqaedb:/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk#create]
>[----] I, [2018-06-28T06:01:16.106343 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [miqaedb:/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk#create ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:16.106734 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Instance [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:16.109568 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/TransformationHosts/ovirt_host/vmchecktransformed_vmwarews2rhevm_vddk ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:16.117509 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk] with inputs [{}]
>[----] I, [2018-06-28T06:01:16.119375 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/TransformationHosts/ovirt_host/Utils ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:16.140495 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Loading embedded method ManageIQ/Transformation/TransformationHosts/ovirt_host/Utils
>[----] I, [2018-06-28T06:01:16.141821 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk]> Starting 
>[----] I, [2018-06-28T06:01:17.371370 #41070:5758474] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmchecktransformed_vmwarews2rhevm_vddk> VirtV2V State: {"started"=>true, "disks"=>[{"path"=>"[NFS_Datastore_1] ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±/ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±.vmdk", "progress"=>0}], "pid"=>14918, "disk_count"=>1}
>[----] I, [2018-06-28T06:01:17.374808 #41070:5758474] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmchecktransformed_vmwarews2rhevm_vddk> Disks: [{:path=>"[NFS_Datastore_1] ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±/ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±.vmdk", :size=>21474836480, :percent=>0, :weight=>100.0}]
>[----] I, [2018-06-28T06:01:17.375443 #41070:5758474] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmchecktransformed_vmwarews2rhevm_vddk> Converted disks: []
>[----] I, [2018-06-28T06:01:17.404876 #41070:5758474] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmchecktransformed_vmwarews2rhevm_vddk> Disk transformation is not finished. Checking in 15.seconds
>[----] I, [2018-06-28T06:01:17.450874 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk]> Ending
>[----] I, [2018-06-28T06:01:17.451033 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:17.451548 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Followed Relationship [miqaedb:/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk#create]
>[----] I, [2018-06-28T06:01:17.451651 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State5] with Result=[retry]
>[----] I, [2018-06-28T06:01:17.451845 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State5], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 85, description => "Convert disks", task_message => "Migrating")]
>[----] I, [2018-06-28T06:01:17.455344 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:17.494855 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>85, "description"=>"Convert disks", "task_message"=>"Migrating"}]
>[----] I, [2018-06-28T06:01:17.496544 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:17.910836 #41070:85e06e8] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>85, "description"=>"Convert disks", "message"=>"Disks transformation is initializing.", "started_on"=>2018-06-28 10:01:16 UTC, "percent"=>1, "updated_on"=>2018-06-28 10:01:17 UTC}
>[----] I, [2018-06-28T06:01:17.991072 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:17.991256 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:17.992603 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Followed Relationship [miqaedb:/Transformation/StateMachines/VMTransformation/vmwarews2rhevm_vddk?state_ancestry=/State17#create]
>[----] I, [2018-06-28T06:01:17.992692 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State17] with Result=[retry]
>[----] I, [2018-06-28T06:01:17.992827 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State17], invoking [on_exit] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 94, description => "Transform VM")]
>[----] I, [2018-06-28T06:01:17.996298 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:18.035237 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>94, "description"=>"Transform VM", "task_message"=>"_"}]
>[----] I, [2018-06-28T06:01:18.036610 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:18.576213 #41070:df4990] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>94, "description"=>"Transform VM", "message"=>"State17 is not finished yet [1/0 retries].", "started_on"=>2018-06-28 10:01:04 UTC, "percent"=>Infinity, "updated_on"=>2018-06-28 10:01:18 UTC}
>[----] I, [2018-06-28T06:01:18.634848 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:18.635021 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:36.815230 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) User [admin] with current group ID [2] name [EvmGroup-super_administrator]
>[----] I, [2018-06-28T06:01:36.855102 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Instantiating [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=18&User%3A%3Auser=1&ae_state=State17&ae_state_data=---%20%21ruby%2Fhash%3AActiveSupport%3A%3AHashWithIndifferentAccess%0Aae_state_progress%3A%20%0Asource_ems_type%3A%20vmwarews%0Adestination_ems_type%3A%20rhevm%0Atransformation_type%3A%20vmwarews2rhevm%0Atransformation_method%3A%20vddk%0Atransformation_host_type%3A%20ovirt_host%0Afactory_config%3A%20%21ruby%2Fhash%3AActiveSupport%3A%3AHashWithIndifferentAccess%0A%20%20vmtransformation_check_interval%3A%2015.seconds%0A%20%20vmpoweroff_check_interval%3A%2030.seconds%0A&ae_state_previous=---%0A%22%2FManageIQ%2FTransformation%2FStateMachines%2FVMTransformation%2FTransformation%22%3A%0A%20%20ae_state%3A%20State17%0A%20%20ae_state_retries%3A%201%0A%20%20ae_state_started%3A%202018-06-28%2010%3A01%3A04%20UTC%0A%20%20ae_state_max_retries%3A%200%0A%22%2FManageIQ%2FTransformation%2FStateMachines%2FVMTransformation%2Fvmwarews2rhevm_vddk%22%3A%0A%20%20ae_state%3A%20State5%0A%20%20ae_state_retries%3A%201%0A%20%20ae_state_started%3A%202018-06-28%2010%3A01%3A15%20UTC%0A%20%20ae_state_max_retries%3A%201500%0A&ae_state_retries=1&ae_state_started=2018-06-28%2010%3A01%3A04%20UTC&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task]
>[----] I, [2018-06-28T06:01:37.053837 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [/Transformation/StateMachines/VMTransformation/Transformation?MiqServer%3A%3Amiq_server=1&ServiceTemplateTransformationPlanTask%3A%3Aservice_template_transformation_plan_task=18&User%3A%3Auser=1&ae_state=State17&ae_state_data=---%20%21ruby%2Fhash%3AActiveSupport%3A%3AHashWithIndifferentAccess%0Aae_state_progress%3A%20%0Asource_ems_type%3A%20vmwarews%0Adestination_ems_type%3A%20rhevm%0Atransformation_type%3A%20vmwarews2rhevm%0Atransformation_method%3A%20vddk%0Atransformation_host_type%3A%20ovirt_host%0Afactory_config%3A%20%21ruby%2Fhash%3AActiveSupport%3A%3AHashWithIndifferentAccess%0A%20%20vmtransformation_check_interval%3A%2015.seconds%0A%20%20vmpoweroff_check_interval%3A%2030.seconds%0A&ae_state_previous=---%0A%22%2FManageIQ%2FTransformation%2FStateMachines%2FVMTransformation%2FTransformation%22%3A%0A%20%20ae_state%3A%20State17%0A%20%20ae_state_retries%3A%201%0A%20%20ae_state_started%3A%202018-06-28%2010%3A01%3A04%20UTC%0A%20%20ae_state_max_retries%3A%200%0A%22%2FManageIQ%2FTransformation%2FStateMachines%2FVMTransformation%2Fvmwarews2rhevm_vddk%22%3A%0A%20%20ae_state%3A%20State5%0A%20%20ae_state_retries%3A%201%0A%20%20ae_state_started%3A%202018-06-28%2010%3A01%3A15%20UTC%0A%20%20ae_state_max_retries%3A%201500%0A&ae_state_retries=1&ae_state_started=2018-06-28%2010%3A01%3A04%20UTC&object_name=Transformation&request=transformation_plan&service_action=Provision&vmdb_object_type=service_template_transformation_plan_task ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-28T06:01:37.303342 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State17], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 94, description => "Transform VM")]
>[----] I, [2018-06-28T06:01:37.315057 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:37.350733 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>94, "description"=>"Transform VM", "task_message"=>"_"}]
>[----] I, [2018-06-28T06:01:37.352790 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:37.927151 #41070:df44a4] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>94, "description"=>"Transform VM", "message"=>"State17 is not finished yet [1/0 retries].", "started_on"=>2018-06-28 10:01:04 UTC, "percent"=>Infinity, "updated_on"=>2018-06-28 10:01:18 UTC}
>[----] I, [2018-06-28T06:01:37.971199 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:37.971336 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:37.971950 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processing State=[State17]
>[----] I, [2018-06-28T06:01:37.972178 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Following Relationship [miqaedb:/Transformation/StateMachines/VMTransformation/vmwarews2rhevm_vddk?state_ancestry=/State17#create]
>[----] I, [2018-06-28T06:01:37.974962 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [miqaedb:/Transformation/StateMachines/VMTransformation/vmwarews2rhevm_vddk?state_ancestry=/State17#create ManageIQ/Transformation/StateMachines]
>[----] I, [2018-06-28T06:01:37.983453 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State5], invoking [on_entry] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 85, description => "Convert disks", task_message => "Migrating")]
>[----] I, [2018-06-28T06:01:37.986441 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:38.024299 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>85, "description"=>"Convert disks", "task_message"=>"Migrating"}]
>[----] I, [2018-06-28T06:01:38.025731 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:38.446707 #41070:472f444] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"active", "weight"=>85, "description"=>"Convert disks", "message"=>"Disks transformation is initializing.", "started_on"=>2018-06-28 10:01:16 UTC, "percent"=>1, "updated_on"=>2018-06-28 10:01:17 UTC}
>[----] I, [2018-06-28T06:01:38.521804 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:38.521952 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:38.522656 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processing State=[State5]
>[----] I, [2018-06-28T06:01:38.522917 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Following Relationship [miqaedb:/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk#create]
>[----] I, [2018-06-28T06:01:38.538371 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [miqaedb:/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk#create ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:38.564879 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Instance [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk] not found in MiqAeDatastore - trying [.missing]
>[----] I, [2018-06-28T06:01:38.568864 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/TransformationHosts/ovirt_host/vmchecktransformed_vmwarews2rhevm_vddk ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:38.577405 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk] with inputs [{}]
>[----] I, [2018-06-28T06:01:38.579854 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [Transformation/TransformationHosts/ovirt_host/Utils ManageIQ/Transformation/TransformationHosts]
>[----] I, [2018-06-28T06:01:38.603183 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Loading embedded method ManageIQ/Transformation/TransformationHosts/ovirt_host/Utils
>[----] I, [2018-06-28T06:01:38.604832 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk]> Starting 
>[----] I, [2018-06-28T06:01:39.823281 #41070:575cfb0] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmchecktransformed_vmwarews2rhevm_vddk> VirtV2V State: {"started"=>true, "disks"=>[{"path"=>"[NFS_Datastore_1] ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±/ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±.vmdk", "progress"=>0}], "pid"=>14918, "disk_count"=>1, "return_code"=>nil, "failed"=>true, "finished"=>true}
>[----] I, [2018-06-28T06:01:39.825803 #41070:575cfb0] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmchecktransformed_vmwarews2rhevm_vddk> Disks: [{:path=>"[NFS_Datastore_1] ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±/ytale-v2v-ubuntu-nfs-punycode-Ð´Ð¶ÑÐ¼-Ã±Ã±.vmdk", :size=>21474836480, :percent=>0, :weight=>100.0}]
>[----] E, [2018-06-28T06:01:39.873598 #41070:575cfb0] ERROR -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmchecktransformed_vmwarews2rhevm_vddk> The following error occurred during method evaluation:
>[----] E, [2018-06-28T06:01:39.874356 #41070:575cfb0] ERROR -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmchecktransformed_vmwarews2rhevm_vddk> NoMethodError: undefined method `zero?' for nil:NilClass
>[----] E, [2018-06-28T06:01:39.875413 #41070:575cfb0] ERROR -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod vmchecktransformed_vmwarews2rhevm_vddk> /ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk:53:in `main'
>[----] E, [2018-06-28T06:01:39.879533 #41070:575cfb0] ERROR -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method STDERR: /ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk:53:in `main': undefined method `zero?' for nil:NilClass (NoMethodError)
>[----] E, [2018-06-28T06:01:39.880182 #41070:575cfb0] ERROR -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method STDERR: 	from /ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk:82:in `<main>'
>[----] I, [2018-06-28T06:01:39.912670 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/Transformation/TransformationHosts/ovirt_host/VMCheckTransformed_vmwarews2rhevm_vddk]> Ending
>[----] E, [2018-06-28T06:01:39.913238 #41070:e0513c] ERROR -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Aborting instantiation (unknown method return code) because [Method exited with rc=Unknown RC: [1]]
>[----] E, [2018-06-28T06:01:39.913810 #41070:e0513c] ERROR -- : Q-task_id([r18_service_template_transformation_plan_task_18]) State=<State5> running raised exception: <Method exited with rc=Unknown RC: [1]>
>[----] W, [2018-06-28T06:01:39.913929 #41070:e0513c] WARN -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Error in State=[State5]
>[----] I, [2018-06-28T06:01:39.914061 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State5], invoking [on_error] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 85, description => "Convert disks", task_message => "Migrating")]
>[----] I, [2018-06-28T06:01:39.917538 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:39.963122 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>85, "description"=>"Convert disks", "task_message"=>"Migrating"}]
>[----] I, [2018-06-28T06:01:39.964754 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:40.387986 #41070:6718210] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"failed", "weight"=>85, "description"=>"Convert disks", "message"=>"undefined method `zero?' for nil:NilClass", "started_on"=>2018-06-28 10:01:16 UTC, "percent"=>100.0, "updated_on"=>2018-06-28 10:01:40 UTC}
>[----] I, [2018-06-28T06:01:40.474192 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:40.474357 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK
>[----] I, [2018-06-28T06:01:40.475598 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Followed Relationship [miqaedb:/Transformation/StateMachines/VMTransformation/vmwarews2rhevm_vddk?state_ancestry=/State17#create]
>[----] I, [2018-06-28T06:01:40.475699 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Processed State=[State17] with Result=[error]
>[----] W, [2018-06-28T06:01:40.475770 #41070:e0513c] WARN -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Error in State=[State17]
>[----] I, [2018-06-28T06:01:40.475876 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) In State=[State17], invoking [on_error] method=[/System/CommonMethods/MiqAe.WeightedUpdateStatus(weight => 94, description => "Transform VM")]
>[----] I, [2018-06-28T06:01:40.479215 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Updated namespace [system/commonmethods/miqae/weightedupdatestatus ManageIQ/system/commonmethods]
>[----] I, [2018-06-28T06:01:40.520420 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Invoking [inline] method [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus] with inputs [{"weight"=>94, "description"=>"Transform VM", "task_message"=>"_"}]
>[----] I, [2018-06-28T06:01:40.521758 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Starting 
>[----] I, [2018-06-28T06:01:41.126163 #41070:df7d0c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod weightedupdatestatus> State Hash: {"status"=>"failed", "weight"=>94, "description"=>"Transform VM", "message"=>"Failed to Transform VM.", "started_on"=>2018-06-28 10:01:04 UTC, "percent"=>100.0, "updated_on"=>2018-06-28 10:01:41 UTC}
>[----] I, [2018-06-28T06:01:41.203491 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) <AEMethod [/ManageIQ/System/CommonMethods/MiqAe/WeightedUpdateStatus]> Ending
>[----] I, [2018-06-28T06:01:41.203630 #41070:e0513c] INFO -- : Q-task_id([r18_service_template_transformation_plan_task_18]) Method exited with rc=MIQ_OK

Actions: View

Attachments on bug 1596143: 1455230 | 1474668