Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Error in Service catalog when passing field values from dialog #43

Closed
vatsalparekh opened this issue Mar 7, 2018 · 4 comments
Closed

Error in Service catalog when passing field values from dialog #43

vatsalparekh opened this issue Mar 7, 2018 · 4 comments
Labels
enhancement stale

Comments

@vatsalparekh
Copy link
Member

when passing a value to the dialog which was configured to take value option_0_number_of_vms, and ordering it with a value to get multiple VMs gets stuck with error

Here's the evm.log for that request

[----] I, [2018-03-06T00:00:19.676219 #9142:2aeba5d00f50]  INFO -- : MIQ(MiqScheduleWorker::Runner#rufus_remove_schedules_by_tag) Unscheduling 1 jobs with tag: miq_schedules_3
[----] I, [2018-03-06T02:01:15.272344 #9096:2aeba5d00f50]  INFO -- : MIQ(Metric.reindex) Reindexing table metrics_3
[----] E, [2018-03-06T02:01:15.272893 #9096:2aeba5d00f50] ERROR -- : MIQ(MiqQueue#deliver) Message id: [29293], Error: [PG::UndefinedTable: ERROR:  relation "metrics_3" does not exist
: REINDEX TABLE "metrics_3"]
[----] E, [2018-03-06T02:01:15.273041 #9096:2aeba5d00f50] ERROR -- : [ActiveRecord::StatementInvalid]: PG::UndefinedTable: ERROR:  relation "metrics_3" does not exist
: REINDEX TABLE "metrics_3"  Method:[block in method_missing]
[----] I, [2018-03-07T00:00:22.230629 #947:2aabaed24f54]  INFO -- : MIQ(MiqScheduleWorker::Runner#rufus_remove_schedules_by_tag) Unscheduling 1 jobs with tag: miq_schedules_3
[----] I, [2018-03-07T02:01:10.092173 #902:2aabaed24f54]  INFO -- : MIQ(Metric.reindex) Reindexing table metrics_3
[----] E, [2018-03-07T02:01:10.092812 #902:2aabaed24f54] ERROR -- : MIQ(MiqQueue#deliver) Message id: [36179], Error: [PG::UndefinedTable: ERROR:  relation "metrics_3" does not exist
: REINDEX TABLE "metrics_3"]
[----] E, [2018-03-07T02:01:10.092962 #902:2aabaed24f54] ERROR -- : [ActiveRecord::StatementInvalid]: PG::UndefinedTable: ERROR:  relation "metrics_3" does not exist
: REINDEX TABLE "metrics_3"  Method:[block in method_missing]
[----] I, [2018-03-07T08:56:11.307062 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#deliver) Message id: [38490], Delivering...
[----] I, [2018-03-07T08:56:11.309697 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqAeEngine.deliver) Delivering {"request"=>"vm_provision"} for object [ManageIQ::Providers::Kubevirt::InfraManager::Provision.3] with state [] to Automate
[----] I, [2018-03-07T08:56:25.059324 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqAeEngine.deliver) Requeuing {:object_type=>"ManageIQ::Providers::Kubevirt::InfraManager::Provision", :object_id=>3, :attrs=>{"request"=>"vm_provision"}, :instance_name=>"AUTOMATION", :user_id=>1, :miq_group_id=>2, :tenant_id=>1, :state=>"CheckProvisioned", :ae_fsm_started=>nil, :ae_state_started=>"2018-03-07 08:56:23 UTC", :ae_state_retries=>1, :ae_state_previous=>"---\n\"/ManageIQ/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template\":\n  ae_state: CheckProvisioned\n  ae_state_retries: 1\n  ae_state_started: 2018-03-07 08:56:23 UTC\n  ae_state_max_retries: 100\n"} for object [ManageIQ::Providers::Kubevirt::InfraManager::Provision.3] with state [CheckProvisioned] to Automate for delivery in [60] seconds
[----] I, [2018-03-07T08:56:25.069100 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue.put) Message id: [38501],  id: [], Zone: [default], Role: [automate], Server: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqAeEngine.deliver], Timeout: [3600], Priority: [100], State: [ready], Deliver On: [2018-03-07 08:57:25 UTC], Data: [], Args: [{:object_type=>"ManageIQ::Providers::Kubevirt::InfraManager::Provision", :object_id=>3, :attrs=>{"request"=>"vm_provision"}, :instance_name=>"AUTOMATION", :user_id=>1, :miq_group_id=>2, :tenant_id=>1, :state=>"CheckProvisioned", :ae_fsm_started=>nil, :ae_state_started=>"2018-03-07 08:56:23 UTC", :ae_state_retries=>1, :ae_state_previous=>"---\n\"/ManageIQ/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template\":\n  ae_state: CheckProvisioned\n  ae_state_retries: 1\n  ae_state_started: 2018-03-07 08:56:23 UTC\n  ae_state_max_retries: 100\n"}]
[----] I, [2018-03-07T08:56:25.069194 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#after_ae_delivery) ae_result="retry"
[----] I, [2018-03-07T08:56:25.069386 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#delivered) Message id: [38490], State: [ok], Delivered in [13.762294227] seconds
[----] I, [2018-03-07T08:57:21.101165 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#deliver) Message id: [38500], Delivering...
[----] I, [2018-03-07T08:57:21.102879 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#execute) Executing VM Provisioning request: [Provision from [linux-vm-template] to [vparekh-test001]]
[----] I, [2018-03-07T08:57:21.132789 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#execute) VM Provisioning initiated
[----] I, [2018-03-07T08:57:21.163489 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <run_provision>
[----] I, [2018-03-07T08:57:21.175042 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <create_destination>
[----] I, [2018-03-07T08:57:21.185585 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <determine_placement>
[----] I, [2018-03-07T08:57:21.193569 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <prepare_provision>
[----] I, [2018-03-07T08:57:21.230768 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#prepare_provision) Default Clone Options: [:name](String) = "vparekh-test001"
[----] I, [2018-03-07T08:57:21.230866 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <start_clone_task>
[----] I, [2018-03-07T08:57:21.266415 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provisioning 'linux-vm-template' to 'vparekh-test001'
[----] I, [2018-03-07T08:57:21.266488 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Source template name is 'linux-vm-template'
[----] I, [2018-03-07T08:57:21.266544 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Destination virtual machine name is 'vparekh-test001'
[----] I, [2018-03-07T08:57:21.266707 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Clone options: [:name](String) = "vparekh-test001"
[----] I, [2018-03-07T08:57:21.266941 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:initial_pass](TrueClass) = true
[----] I, [2018-03-07T08:57:21.267062 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:src_vm_id][0](Integer) = 3
[----] I, [2018-03-07T08:57:21.267144 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:src_vm_id][1](String) = "linux-vm-template"
[----] I, [2018-03-07T08:57:21.267240 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:miq_request_dialog_name](String) = "miq_provision_kubevirt_dialogs_template"
[----] I, [2018-03-07T08:57:21.267326 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:current_tab_key](Symbol) = :service
[----] I, [2018-03-07T08:57:21.267411 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_phone](NilClass) = nil
[----] I, [2018-03-07T08:57:21.267493 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_country](NilClass) = nil
[----] I, [2018-03-07T08:57:21.267587 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_phone_mobile](NilClass) = nil
[----] I, [2018-03-07T08:57:21.267669 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_title](NilClass) = nil
[----] I, [2018-03-07T08:57:21.267747 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_first_name](NilClass) = nil
[----] I, [2018-03-07T08:57:21.267830 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_manager](NilClass) = nil
[----] I, [2018-03-07T08:57:21.267919 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_address](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268001 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_company](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268079 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_last_name](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268164 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_manager_mail](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268249 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_city](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268329 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_department](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268407 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_load_ldap](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268490 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_manager_phone](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268584 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_state](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268666 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_office](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268744 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_zip](NilClass) = nil
[----] I, [2018-03-07T08:57:21.268822 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_email](String) = "vparekh@redhat.com"
[----] I, [2018-03-07T08:57:21.268902 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:request_notes](NilClass) = nil
[----] I, [2018-03-07T08:57:21.269035 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:provision_type][0](String) = "native_clone"
[----] I, [2018-03-07T08:57:21.269111 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:provision_type][1](String) = "Native Clone"
[----] I, [2018-03-07T08:57:21.269224 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:number_of_vms][0](Integer) = 10
[----] I, [2018-03-07T08:57:21.269300 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:number_of_vms][1](String) = "10"
[----] I, [2018-03-07T08:57:21.269383 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:vm_description](NilClass) = nil
[----] I, [2018-03-07T08:57:21.269469 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:vm_prefix](NilClass) = nil
[----] I, [2018-03-07T08:57:21.269558 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:vm_name](String) = "vparekh-test"
[----] I, [2018-03-07T08:57:21.269665 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:schedule_type][0](String) = "schedule"
[----] I, [2018-03-07T08:57:21.269740 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:schedule_type][1](String) = "Schedule"
[----] I, [2018-03-07T08:57:21.269847 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:vm_auto_start][0](TrueClass) = true
[----] I, [2018-03-07T08:57:21.269922 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:vm_auto_start][1](Integer) = 1
[----] I, [2018-03-07T08:57:21.270029 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:schedule_time](ActiveSupport::TimeWithZone) = Wed, 07 Mar 2018 01:00:00 UTC +00:00
[----] I, [2018-03-07T08:57:21.270137 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:retirement][0](Integer) = 0
[----] I, [2018-03-07T08:57:21.270227 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:retirement][1](String) = "Indefinite"
[----] I, [2018-03-07T08:57:21.270331 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:retirement_warn][0](Integer) = 604800
[----] I, [2018-03-07T08:57:21.270408 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:retirement_warn][1](String) = "1 Week"
[----] I, [2018-03-07T08:57:21.270519 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:stateless][0](FalseClass) = false
[----] I, [2018-03-07T08:57:21.270611 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:stateless][1](Integer) = 0
[----] I, [2018-03-07T08:57:21.270720 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:vm_memory][0](String) = "4096"
[----] I, [2018-03-07T08:57:21.270795 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:vm_memory][1](String) = "4096"
[----] I, [2018-03-07T08:57:21.270870 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:start_date](String) = "03/07/2018"
[----] I, [2018-03-07T08:57:21.270943 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:start_hour](String) = "1"
[----] I, [2018-03-07T08:57:21.271022 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:start_min](String) = "00"
[----] I, [2018-03-07T08:57:21.271190 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:src_ems_id][0](Integer) = 20
[----] I, [2018-03-07T08:57:21.271267 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:src_ems_id][1](String) = "k6t-ocp Virtualization Manager"
[----] I, [2018-03-07T08:57:21.271345 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:requester_group](String) = "EvmGroup-super_administrator"
[----] I, [2018-03-07T08:57:21.271426 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:owner_group](NilClass) = nil
[----] I, [2018-03-07T08:57:21.271503 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:delivered_on](Time) = 2018-03-07 08:56:05 UTC
[----] I, [2018-03-07T08:57:21.271594 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:pass](Integer) = 1
[----] I, [2018-03-07T08:57:21.271674 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:dns_domain](NilClass) = nil
[----] I, [2018-03-07T08:57:21.271749 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:vm_target_name](String) = "vparekh-test001"
[----] I, [2018-03-07T08:57:21.271820 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#log_clone_options) Provision options: [:vm_target_hostname](String) = "vparekh-test001"
[----] I, [2018-03-07T08:57:21.271986 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Kubevirt::InfraManager: [k6t-ocp Virtualization Manager]
[----] I, [2018-03-07T08:57:22.638211 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Kubevirt::InfraManager: [k6t-ocp Virtualization Manager]
[----] I, [2018-03-07T08:57:22.661837 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Kubevirt::InfraManager: [k6t-ocp Virtualization Manager]
[----] I, [2018-03-07T08:57:24.384493 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <poll_destination_in_vmdb>
[----] I, [2018-03-07T08:57:24.453839 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#poll_destination_in_vmdb) Unable to find VM [vparekh-test001] with ems_ref [8ee20e50-21e5-11e8-b116-fa163ecafec3], will retry
[----] I, [2018-03-07T08:57:24.467846 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue.put) Message id: [38522],  id: [], Zone: [default], Role: [ems_operations], Server: [], Ident: [generic], Target id: [], Instance id: [3], Task id: [], Command: [ManageIQ::Providers::Kubevirt::InfraManager::Provision.poll_destination_in_vmdb], Timeout: [600], Priority: [100], State: [ready], Deliver On: [2018-03-07 08:57:34 UTC], Data: [], Args: []
[----] I, [2018-03-07T08:57:24.469471 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#delivered) Message id: [38500], State: [ok], Delivered in [3.368327403] seconds
[----] I, [2018-03-07T08:57:24.474705 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#m_callback) Message id: [38500], Invoking Callback with args: ["ok", "Message delivered successfully", "#<MiqQueue id: 38522, target_id: nil, priority: 100, method_name: \"poll_destination_in_vmdb\", state: \"ready\", created_on: \"2018-03-07 08:57:24\", updated_on: \"2018-03-07 08:57:24\", lock_version: 0, task_id: nil, deliver_on: \"2018-03-07 08:57:34\", queue_name: \"generic\", class_name: \"ManageIQ::Providers::Kubevirt::InfraManager::Provi...\", instance_id: 3, args: [], miq_callback: {:class_name=>\"ManageIQ::Providers::Kubevirt::InfraManager::Provision\", :instance_id=>3, :method_name=>:execute_callback},..."]
[----] I, [2018-03-07T08:57:33.034368 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#deliver) Message id: [38501], Delivering...
[----] I, [2018-03-07T08:57:33.039913 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqAeEngine.deliver) Delivering {"request"=>"vm_provision"} for object [ManageIQ::Providers::Kubevirt::InfraManager::Provision.3] with state [CheckProvisioned] to Automate
[----] I, [2018-03-07T08:57:35.320258 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqAeEngine.deliver) Requeuing {:object_type=>"ManageIQ::Providers::Kubevirt::InfraManager::Provision", :object_id=>3, :attrs=>{"request"=>"vm_provision"}, :instance_name=>"AUTOMATION", :user_id=>1, :miq_group_id=>2, :tenant_id=>1, :state=>"CheckProvisioned", :ae_fsm_started=>nil, :ae_state_started=>"2018-03-07 08:56:23 UTC", :ae_state_retries=>2, :ae_state_previous=>"---\n\"/ManageIQ/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template\":\n  ae_state: CheckProvisioned\n  ae_state_retries: 2\n  ae_state_started: 2018-03-07 08:56:23 UTC\n  ae_state_max_retries: 100\n"} for object [ManageIQ::Providers::Kubevirt::InfraManager::Provision.3] with state [CheckProvisioned] to Automate for delivery in [60] seconds
[----] I, [2018-03-07T08:57:35.329869 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue.put) Message id: [38532],  id: [], Zone: [default], Role: [automate], Server: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqAeEngine.deliver], Timeout: [3600], Priority: [100], State: [ready], Deliver On: [2018-03-07 08:58:35 UTC], Data: [], Args: [{:object_type=>"ManageIQ::Providers::Kubevirt::InfraManager::Provision", :object_id=>3, :attrs=>{"request"=>"vm_provision"}, :instance_name=>"AUTOMATION", :user_id=>1, :miq_group_id=>2, :tenant_id=>1, :state=>"CheckProvisioned", :ae_fsm_started=>nil, :ae_state_started=>"2018-03-07 08:56:23 UTC", :ae_state_retries=>2, :ae_state_previous=>"---\n\"/ManageIQ/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template\":\n  ae_state: CheckProvisioned\n  ae_state_retries: 2\n  ae_state_started: 2018-03-07 08:56:23 UTC\n  ae_state_max_retries: 100\n"}]
[----] I, [2018-03-07T08:57:35.329982 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#after_ae_delivery) ae_result="retry"
[----] I, [2018-03-07T08:57:35.330196 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#delivered) Message id: [38501], State: [ok], Delivered in [2.295803903] seconds
[----] I, [2018-03-07T08:57:35.803618 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#deliver) Message id: [38522], Delivering...
[----] I, [2018-03-07T08:57:35.840087 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <customize_destination>
[----] I, [2018-03-07T08:57:35.847628 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <post_provision>
[----] I, [2018-03-07T08:57:35.854094 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <autostart_destination>
[----] I, [2018-03-07T08:57:35.881236 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#autostart_destination) Starting VM id: [8], name: [vparekh-test001]
[----] I, [2018-03-07T08:57:35.937407 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue.put) Message id: [38534],  id: [], Zone: [default], Role: [automate], Server: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqAeEngine.deliver], Timeout: [3600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: [{:object_type=>"ManageIQ::Providers::Kubevirt::InfraManager::Vm", :object_id=>8, :attrs=>{:event_type=>:request_vm_start, "VmOrTemplate::vm"=>8, :vm_id=>8, "ExtManagementSystem::ext_management_system"=>20, :ext_management_system_id=>20, "MiqEvent::miq_event"=>7066, :miq_event_id=>7066, "EventStream::event_stream"=>7066, :event_stream_id=>7066}, :instance_name=>"Event", :user_id=>1, :miq_group_id=>2, :tenant_id=>1, :automate_message=>nil}]
[----] I, [2018-03-07T08:57:35.937493 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <post_create_destination>
[----] I, [2018-03-07T08:57:35.947910 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#post_create_destination) Destination Vm ID=8, Name=vparekh-test001
[----] I, [2018-03-07T08:57:35.949112 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#set_description) Setting Vm description to nil
[----] I, [2018-03-07T08:57:35.968858 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#set_ownership) Setting Owning User to Name=Administrator, ID=1
[----] I, [2018-03-07T08:57:35.969014 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#set_ownership) Setting Owning Group to Name=EvmGroup-super_administrator, ID=2
[----] I, [2018-03-07T08:57:35.969230 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#set_genealogy) Setting Genealogy Parent to MiqTemplate Name=linux-vm-template, ID=3
[----] I, [2018-03-07T08:57:35.992912 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <mark_as_completed>
[----] I, [2018-03-07T08:57:36.013848 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue.put) Message id: [38536],  id: [], Zone: [default], Role: [automate], Server: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqAeEngine.deliver], Timeout: [3600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: [{:object_type=>"ManageIQ::Providers::Kubevirt::InfraManager::Vm", :object_id=>8, :attrs=>{:event_type=>"vm_provisioned", "VmOrTemplate::vm"=>8, :vm_id=>8, :host=>nil, "MiqEvent::miq_event"=>7067, :miq_event_id=>7067, "EventStream::event_stream"=>7067, :event_stream_id=>7067}, :instance_name=>"Event", :user_id=>1, :miq_group_id=>2, :tenant_id=>1, :automate_message=>nil}]
[----] I, [2018-03-07T08:57:36.036117 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) Starting Phase <finish>
[----] I, [2018-03-07T08:57:36.043732 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#finish) Executing provision request: [Provision from [linux-vm-template] to [vparekh-test001]], Pass: 1 of 10... Complete
[----] I, [2018-03-07T08:57:36.043888 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#delivered) Message id: [38522], State: [ok], Delivered in [0.240268521] seconds
[----] I, [2018-03-07T08:57:36.045590 #902:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#m_callback) Message id: [38522], Invoking Callback with args: ["ok", "Message delivered successfully", "true"]
[----] I, [2018-03-07T08:57:40.148709 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#deliver) Message id: [38534], Delivering...
[----] I, [2018-03-07T08:57:40.152766 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqAeEngine.deliver) Delivering {:event_type=>:request_vm_start, "VmOrTemplate::vm"=>8, :vm_id=>8, "ExtManagementSystem::ext_management_system"=>20, :ext_management_system_id=>20, "MiqEvent::miq_event"=>7066, :miq_event_id=>7066, "EventStream::event_stream"=>7066, :event_stream_id=>7066} for object [ManageIQ::Providers::Kubevirt::InfraManager::Vm.8] with state [] to Automate
[----] I, [2018-03-07T08:57:40.285125 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqEvent#process_evm_event) target = [#<ManageIQ::Providers::Kubevirt::InfraManager::Vm id: 8, vendor: "kubevirt", format: nil, version: nil, name: "vparekh-test001", description: nil, location: "unknown", config_xml: nil, autostart: nil, host_id: nil, last_sync_on: nil, created_on: "2018-03-07 08:57:24", updated_on: "2018-03-07 08:57:35", storage_id: 1, guid: "d7ecbabc-8265-4688-8ab9-9860a82bb529", ems_id: 20, last_scan_on: nil, last_scan_attempt_on: nil, uid_ems: "8ee20e50-21e5-11e8-b116-fa163ecafec3", retires_on: nil, retired: nil, boot_time: nil, tools_status: nil, standby_action: nil, power_state: "off", state_changed_on: "2018-03-07 08:57:24", previous_state: nil, connection_state: "connected", last_perf_capture_on: nil, registered: nil, busy: nil, smart: nil, memory_reserve: nil, memory_reserve_expand: nil, memory_limit: nil, memory_shares: nil, memory_shares_level: nil, cpu_reserve: nil, cpu_reserve_expand: nil, cpu_limit: nil, cpu_shares: nil, cpu_shares_level: nil, cpu_affinity: nil, ems_created_on: nil, template: false, evm_owner_id: 1, ems_ref_obj: "--- 8ee20e50-21e5-11e8-b116-fa163ecafec3\n...\n", miq_group_id: 2, linked_clone: nil, fault_tolerance: nil, type: "ManageIQ::Providers::Kubevirt::InfraManager::Vm", ems_ref: "8ee20e50-21e5-11e8-b116-fa163ecafec3", ems_cluster_id: nil, retirement_warn: nil, retirement_last_warn: nil, vnc_port: nil, flavor_id: nil, availability_zone_id: nil, cloud: false, retirement_state: nil, cloud_network_id: nil, cloud_subnet_id: nil, cloud_tenant_id: nil, raw_power_state: "off", publicly_available: nil, orchestration_stack_id: nil, retirement_requester: nil, tenant_id: 1, resource_group_id: nil, deprecated: nil, storage_profile_id: nil, cpu_hot_add_enabled: nil, cpu_hot_remove_enabled: nil, memory_hot_add_enabled: nil, memory_hot_add_limit: nil, memory_hot_add_increment: nil, hostname: nil>]
[----] I, [2018-03-07T08:57:40.285341 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqEvent#process_evm_event) Event Raised [request_vm_start]
[----] I, [2018-03-07T08:57:40.302376 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqEvent#process_evm_event) Alert for Event [request_vm_start]
[----] I, [2018-03-07T08:57:40.302489 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqAlert.evaluate_alerts) [request_vm_start] Target: ManageIQ::Providers::Kubevirt::InfraManager::Vm Name: [vparekh-test001], Id: [8]
[----] I, [2018-03-07T08:57:40.313371 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#delivered) Message id: [38534], State: [ok], Delivered in [0.164645623] seconds
[----] I, [2018-03-07T08:57:40.359642 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#m_callback) Message id: [38534], Invoking Callback with args: [:raw_start, "ok", "Message delivered successfully", "#<MiqAeEngine::MiqAeWorkspaceRuntime:0x0000555773890ad8 @readonly=false, @nodes=[#<MiqAeEngine::MiqAeObject:0x0000555773a1db30 @workspace=#<MiqAeEngine::MiqAeWorkspaceRuntime:0x0000555773890ad8 ...>, @namespace=\"ManageIQ/System\", @klass=\"Process\", @instance=\"Event\", @attributes={\"event_stream_id\"=>\"7066\", \"event_type\"=>\"request_vm_start\", \"ext_management_system_id\"=>\"20\", \"miq_event_id\"=>\"7066\", \"object_name\"=>\"Event\", \"vm_id\"=>\"8\", \"vmdb_object_type\"=>\"vm\", \"event_stream\"=>#<MiqAeServiceMiqEven..."]
[----] I, [2018-03-07T08:57:40.362039 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Kubevirt::InfraManager: [k6t-ocp Virtualization Manager]
[----] I, [2018-03-07T08:57:40.417453 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#deliver) Message id: [38536], Delivering...
[----] I, [2018-03-07T08:57:40.420376 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqAeEngine.deliver) Delivering {:event_type=>"vm_provisioned", "VmOrTemplate::vm"=>8, :vm_id=>8, :host=>nil, "MiqEvent::miq_event"=>7067, :miq_event_id=>7067, "EventStream::event_stream"=>7067, :event_stream_id=>7067} for object [ManageIQ::Providers::Kubevirt::InfraManager::Vm.8] with state [] to Automate
[----] I, [2018-03-07T08:57:40.538339 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqEvent#process_evm_event) target = [#<ManageIQ::Providers::Kubevirt::InfraManager::Vm id: 8, vendor: "kubevirt", format: nil, version: nil, name: "vparekh-test001", description: nil, location: "unknown", config_xml: nil, autostart: nil, host_id: nil, last_sync_on: nil, created_on: "2018-03-07 08:57:24", updated_on: "2018-03-07 08:57:40", storage_id: 1, guid: "d7ecbabc-8265-4688-8ab9-9860a82bb529", ems_id: 20, last_scan_on: nil, last_scan_attempt_on: nil, uid_ems: "8ee20e50-21e5-11e8-b116-fa163ecafec3", retires_on: nil, retired: nil, boot_time: nil, tools_status: nil, standby_action: nil, power_state: "on", state_changed_on: "2018-03-07 08:57:40", previous_state: "off", connection_state: "connected", last_perf_capture_on: nil, registered: nil, busy: nil, smart: nil, memory_reserve: nil, memory_reserve_expand: nil, memory_limit: nil, memory_shares: nil, memory_shares_level: nil, cpu_reserve: nil, cpu_reserve_expand: nil, cpu_limit: nil, cpu_shares: nil, cpu_shares_level: nil, cpu_affinity: nil, ems_created_on: nil, template: false, evm_owner_id: 1, ems_ref_obj: "--- 8ee20e50-21e5-11e8-b116-fa163ecafec3\n...\n", miq_group_id: 2, linked_clone: nil, fault_tolerance: nil, type: "ManageIQ::Providers::Kubevirt::InfraManager::Vm", ems_ref: "8ee20e50-21e5-11e8-b116-fa163ecafec3", ems_cluster_id: nil, retirement_warn: nil, retirement_last_warn: nil, vnc_port: nil, flavor_id: nil, availability_zone_id: nil, cloud: false, retirement_state: nil, cloud_network_id: nil, cloud_subnet_id: nil, cloud_tenant_id: nil, raw_power_state: "on", publicly_available: nil, orchestration_stack_id: nil, retirement_requester: nil, tenant_id: 1, resource_group_id: nil, deprecated: nil, storage_profile_id: nil, cpu_hot_add_enabled: nil, cpu_hot_remove_enabled: nil, memory_hot_add_enabled: nil, memory_hot_add_limit: nil, memory_hot_add_increment: nil, hostname: nil>]
[----] I, [2018-03-07T08:57:40.538470 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqEvent#process_evm_event) Event Raised [vm_provisioned]
[----] I, [2018-03-07T08:57:40.549882 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqEvent#process_evm_event) Alert for Event [vm_provisioned]
[----] I, [2018-03-07T08:57:40.549991 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqAlert.evaluate_alerts) [vm_provisioned] Target: ManageIQ::Providers::Kubevirt::InfraManager::Vm Name: [vparekh-test001], Id: [8]
[----] I, [2018-03-07T08:57:40.556690 #911:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#delivered) Message id: [38536], State: [ok], Delivered in [0.139245632] seconds
[----] I, [2018-03-07T08:58:42.182203 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#deliver) Message id: [38532], Delivering...
[----] I, [2018-03-07T08:58:42.184648 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqAeEngine.deliver) Delivering {"request"=>"vm_provision"} for object [ManageIQ::Providers::Kubevirt::InfraManager::Provision.3] with state [CheckProvisioned] to Automate
[----] I, [2018-03-07T08:58:50.432292 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(ManageIQ::Providers::Kubevirt::InfraManager::Provision#after_ae_delivery) ae_result="ok"
[----] I, [2018-03-07T08:58:50.461754 #893:2aabaed24f54]  INFO -- : Q-task_id([miq_provision_3]) MIQ(MiqQueue#delivered) Message id: [38532], State: [ok], Delivered in [8.27954346] seconds
 pg_toast_3592                                      |                  0 |           0 |                   |                         |                         |                  |         0 |            0 |                0 |             0 |         0 |            0 |                    0 |           0
 pg_toast_3596                                      |                  0 |           0 |                   |                         |                         |                  |         0 |            0 |                0 |             0 |         0 |            0 |                    0 |           0
[----] I, [2018-03-07T10:44:01.432043 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(MiqQueue#deliver) Message id: [39123], Delivering...
[----] I, [2018-03-07T10:44:01.433656 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(ServiceTemplateProvisionRequest#create_request_tasks) Creating request task instances for: <Provisioning Service [test] from [test]>...
[----] I, [2018-03-07T10:44:01.433717 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(ServiceTemplateProvisionRequest#call_automate_event) Raising event [request_starting] to Automate synchronously
[----] I, [2018-03-07T10:44:01.440313 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(MiqAeEngine.deliver) Delivering {:event_type=>"request_starting", "EventStream::event_stream"=>7187, :event_stream_id=>7187} for object [ServiceTemplateProvisionRequest.3] with state [] to Automate
[----] I, [2018-03-07T10:44:02.752773 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(ServiceTemplateProvisionRequest#call_automate_event) Raised event [request_starting] to Automate
[----] I, [2018-03-07T10:44:02.821843 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(ServiceTemplateProvisionTask#create_child_tasks) - creating service tasks for service <ServiceTemplateProvisionTask:13> with parent service <none>
[----] I, [2018-03-07T10:44:03.039271 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(ServiceTemplateContainerTemplate.automate_result_include_service_template?) Include Service Template <test> : <true>
[----] I, [2018-03-07T10:44:03.190936 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) Setting Service Owning User to Name=Administrator, ID=1, Group to Name=EvmGroup-super_administrator, ID=2
[----] I, [2018-03-07T10:44:03.195764 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(ServiceTemplateProvisionTask#create_child_tasks) - created <0> service tasks for service <ServiceTemplateProvisionTask:13> with parent service <none>
[----] I, [2018-03-07T10:44:03.202692 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(ServiceTemplateProvisionTask#deliver_to_automate) Queuing Service_Template_Provisioning: [Provisioning [test] for Service [test]]...
[----] I, [2018-03-07T10:44:03.212669 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(MiqQueue.put) Message id: [39125],  id: [], Zone: [default], Role: [automate], Server: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqAeEngine.deliver], Timeout: [600], Priority: [100], State: [ready], Deliver On: [], Data: [], Args: [{:object_type=>"ServiceTemplateProvisionTask", :object_id=>13, :namespace=>"Service/Generic/StateMachines", :class_name=>"GenericLifecycle", :instance_name=>"provision", :automate_message=>"create", :attrs=>{"dialog_option_0_number_of_vms"=>"5", "request"=>"clone_to_service", :service_action=>"Provision", "Service::Service"=>1}, :user_id=>1, :miq_group_id=>2, :tenant_id=>1}]
[----] I, [2018-03-07T10:44:03.234010 #893:2aabaed24f54]  INFO -- : Q-task_id([service_template_provision_request_3]) MIQ(MiqQueue#delivered) Message id: [39123], State: [ok], Delivered in [1.80196688] seconds
@vatsalparekh vatsalparekh mentioned this issue May 22, 2018
Closed
@pkliczewski
Copy link
Contributor

@vatsalparekh Please provide steps to reproduce

@pkliczewski
Copy link
Contributor

ATM we haven't decided to support service catalog

@miq-bot miq-bot added the stale label Dec 3, 2018
@miq-bot
Copy link
Member

miq-bot commented Dec 3, 2018

This issue has been automatically marked as stale because it has not been updated for at least 6 months.

If you can still reproduce this issue on the current release or on master, please reply with all of the information you have about it in order to keep the issue open.

Thank you for all your contributions!

@JPrause
Copy link
Member

JPrause commented Jan 30, 2019

@miq-bot close_issue

@miq-bot miq-bot closed this as completed Jan 30, 2019
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
enhancement stale
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@pkliczewski @vatsalparekh @miq-bot @JPrause