Gaprindashvili-3 service deployment issues

Support
#1

Hi All,

I have recently deployed gaprindashvili-3 as it fixed a problem with service dialog’s drop down fields but (which are now working ok) but unfortunately I am now unable to deploy VMs (ovirt template via RHEV provider) via from service catalogs (which apart from drop-down issue works fine in gaprindashvili-2). I wanted to use the drop-downs for vCPUs/RAM allocation to VMs…

Request fails with generic error:

Service Provision Error: Server [EVM] Service XXX Step [checkprovisioned] Status [Error Creating Service] Message [Creating VM]

I can actually see the VM created in ovirt but it’s not powered on and not customized…

I do not see anything useful in production.log:

INFO – : NotificationChannel is transmitting the subscription confirmation
INFO – : NotificationChannel is streaming from notifications_1000000000001
INFO – : [ActionCable] Broadcasting to notifications_1000000000001: {:level=>“success”, :created_at=>Fri, 18 May 2018 07:21:09 UTC +00:00, :text=>“Request %{subject} has been approved.”, :bindings=>{:subject=>{:link=>{:id=>1000000000006, :model=>“ServiceTemplateProvisionRequest”}, :text=>“Provisioning Service [XXX] from [XXX]”}}}
INFO – : NotificationChannel transmitting {“level”=>“success”, “created_at”=>“2018-05-18T07:21:09.288Z”, “text”=>“Request %{subject} has been approved.”, “bindings”=>{“subject”=>{“link”=>{“id”=>1000000000006, “model”=>“ServiceTemplateProvisionRequest”}, “text”=>“Provisioning Service [XXX] from [XXX]”}}} (via streamed from notifications_1000000000001)
…
INFO – : Processing by Api::ApiController#index as JSON
INFO – : Completed 200 OK in 25ms (Views: 0.1ms | ActiveRecord: 2.2ms)
INFO – : Started GET “/api” for 127.0.0.1 at 2018-05-18 08:22:56 +0100
INFO – : Processing by Api::ApiController#index as JSON
INFO – : Completed 200 OK in 40ms (Views: 0.1ms | ActiveRecord: 9.4ms)
INFO – : [ActionCable] Broadcasting to notifications_1000000000001: {:level=>“error”, :created_at=>Fri, 18 May 2018 07:23:38 UTC +00:00, :text=>"%{message}", :bindings=>{:message=>{:text=>“Service Provision Error: Server [EVM] Service [ZZZ] Step [checkprovisioned] Status [Error Creating Service] Message [Creating VM] “}, :initiator=>{:link=>{:id=>1000000000001, :model=>“User”}, :text=>“Administrator”}, :subject=>{:link=>{:id=>1000000000006, :model=>“ServiceTemplateProvisionRequest”}, :text=>“Provisioning Service [XXX] from [XXX]”}}}
INFO – : NotificationChannel transmitting {“level”=>“error”, “created_at”=>“2018-05-18T07:23:38.180Z”, “text”=>”%{message}”, “bindings”=>{“message”=>{“text”=>"Service Provision Error: Server [EVM] Service [ZZZ] Step [checkprovisioned] Status [Error Creating Service] Message [Creating VM] “}, “initiator”=>{“link”=>{“i… (via streamed from notifications_1000000000001)
…
INFO – : Processing by Api::ApiController#index as JSON
INFO – : Completed 200 OK in 26ms (Views: 0.1ms | ActiveRecord: 2.3ms)
INFO – : [ActionCable] Broadcasting to notifications_1000000000001: {:level=>“error”, :created_at=>Fri, 18 May 2018 07:24:04 UTC +00:00, :text=>”%{message}”, :bindings=>{:message=>{:text=>“Service Provision Error: Server [EVM] Service [ZZZ] Step [checkprovisioned] Status [Error Creating Service] Message [Creating VM] “}, :initiator=>{:link=>{:id=>1000000000001, :model=>“User”}, :text=>“Administrator”}, :subject=>{:link=>{:id=>1000000000006, :model=>“ServiceTemplateProvisionRequest”}, :text=>“Provisioning Service [XXX] from [XXX]”}}}
INFO – : NotificationChannel transmitting {“level”=>“error”, “created_at”=>“2018-05-18T07:24:04.382Z”, “text”=>”%{message}”, “bindings”=>{“message”=>{“text”=>"Service Provision Error: Server [EVM] Service [ZZZ] Step [checkprovisioned] Status [Error Creating Service] Message [Creating VM] “}, “initiator”=>{“link”=>{“i… (via streamed from notifications_1000000000001)
INFO – : [ActionCable] Broadcasting to notifications_1000000000001: {:level=>“error”, :created_at=>Fri, 18 May 2018 07:24:32 UTC +00:00, :text=>”%{message}”, :bindings=>{:message=>{:text=>“VM Provision Error: [EVM] VM [rhel-miq2-catalog4] Step [CheckProvisioned] Status [Error Creating VM] Message [Creating VM] “}, :initiator=>{:link=>{:id=>1000000000001, :model=>“User”}, :text=>“Administrator”}, :subject=>{:link=>{:id=>1000000000006, :model=>“ServiceTemplateProvisionRequest”}, :text=>“Provisioning Service [XXX] from [XXX]”}}}
INFO – : NotificationChannel transmitting {“level”=>“error”, “created_at”=>“2018-05-18T07:24:32.378Z”, “text”=>”%{message}”, “bindings”=>{“message”=>{“text”=>"VM Provision Error: [EVM] VM [rhel-miq2-catalog4] Step [CheckProvisioned] Status [Error Creating VM] Message [Creating VM] "}, “initiator”=>{“link”=>{“id”=>1000000000001, “model”=>… (via streamed from notifications_1000000000001)
INFO – : Finished “/ws/notifications” [WebSocket] for 127.0.0.1 at 2018-05-18 0

Is anyone able to advice?

Thanks in advance,
Tomasz

#2

Were there any additional errors in the automation.log file? I don’t use RHEV or oVirt so can’t aid much in that regard.

#3

all seems ok up until this:

INFO – : Service ProvisionCheck returned for state and status
INFO – : Q-task_id([service_template_provision_task_1000000000014]) <AEMethod [/ManageIQ/Service/Provisioning/StateMachines/Methods/check_provisioned]> Ending
INFO – : Q-task_id([service_template_provision_task_1000000000014]) Method exited with rc=MIQ_OK
INFO – : Q-task_id([service_template_provision_task_1000000000014]) Followed Relationship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
INFO – : Q-task_id([service_template_provision_task_1000000000014]) Processed State=[checkprovisioned] with Result=[error]
WARN – : Q-task_id([service_template_provision_task_1000000000014]) Error in State=[checkprovisioned]
INFO – : Q-task_id([service_template_provision_task_1000000000014]) In State=[checkprovisioned], invoking [on_error] method=[update_serviceprovision_status(status => ‘Error Creating Service’)]
INFO – : Q-task_id([service_template_provision_task_1000000000014]) Updated namespace [Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status ManageIQ/Service/Provisioning/StateMachines]
INFO – : Q-task_id([service_template_provision_task_1000000000014]) Invoking [inline] method [/ManageIQ/Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status] with inputs [{“status”=>“Error Creating Service”}]
INFO – : Q-task_id([service_template_provision_task_1000000000014]) <AEMethod [/ManageIQ/Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status]> Starting
INFO – : Validating Notification type: automate_user_error
INFO – : Calling Create Notification type: automate_user_error subject type: MiqRequest id: 1000000000007 options: {:message=>"Service Provision Error: Server [EVM] Service [VMXXX]] Step [checkprovisioned] Status [Error Creating Service] Message [Creating VM] "}
RROR – : Service Provision Error: Server [EVM] Service [VMXXX]] Step [checkprovisioned] Status [Error Creating Service] Message [Creating VM]
INFO – : Q-task_id([service_template_provision_task_1000000000014]) <AEMethod [/ManageIQ/Service/Provisioning/StateMachines/ServiceProvision_Template/update_serviceprovision_status]> Ending
INFO – : Q-task_id([service_template_provision_task_1000000000014]) Method exited with rc=MIQ_OK

#4

Is anyone able to assist here?

#5

Actually this is down on me - I have incorrectly set the values for option_0_vm_memory dropdown in Service Dialog. Once corrected (4 instead of 4096 for example) all seems to be working ok now.

1 Like