Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 1455230 Details for
Bug 1596143
[v2v] vm name with punycode international characters fails while migration
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
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:
punycode_automation.log
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,<br/><br/>Your Transformation Plan Request was Approved. If Service provisioning is successful you will be notified via email when the Service is available.<br/><br/>Approvers notes : Auto-Approved<br/><br/>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><br/><br/> Thank you,<br/> 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,<br/><br/>Transformation Plan Request received from was Approved.<br/><br/>Approvers reason : Auto-Approved<br/><br/>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><br/><br/> Thank you,<br/> 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
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1596143
: 1455230 |
1474668