Created attachment 1021985 [details] automation log Description of problem: Version-Release number of selected component (if applicable): How reproducible: Steps to Reproduce: 1. create a service dialog from cloud formation template 2. Create an orchestration catalog item 3. Order that item . 4. Provisioning fails with error " ERROR -- : Q-task_id([service_template_provision_task_11]) State=<checkprovisioned> running on_error raised exception: <Method [/ManageIQ/Cloud/Orchestration/Provisioning/StateMachines/Provision/update_serviceprovision_status] requires parameter [status]> " Logs attached. Actual results: Expected results: Additional info:
Build: 5.4.0.0.24.20150427192818_1fd9e49
Can I have evm.log please? All give me access to your appliance.
Logs are at https://10.8.59.84
New commit detected on manageiq/master: https://github.com/ManageIQ/manageiq/commit/9f885b09362f7a70292bf56b3748f18ab6ed47bc commit 9f885b09362f7a70292bf56b3748f18ab6ed47bc Author: Bill Wei <bilwei> AuthorDate: Wed May 6 09:50:18 2015 -0400 Commit: Bill Wei <bilwei> CommitDate: Wed May 6 09:57:16 2015 -0400 Enhance error handling for rollback_complete status Also make the error visible on UI by setting the user message https://bugzilla.redhat.com/show_bug.cgi?id=1218436 .../StateMachines/Methods.class/__methods__/check_provisioned.rb | 9 +++++++-- .../StateMachines/Methods.class/__methods__/provision.rb | 4 +++- 2 files changed, 10 insertions(+), 3 deletions(-)
Not fixed in 5.4.0.0.26.20150511144816_f924bd4 oned#create] [----] I, [2015-05-12T16:57:12.415838 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Updated namespace [miqaedb:/Cloud/Orchestration/Provisioning/StateMachines/Methods/CheckProvisioned#create Default/Cloud/Orchestration/Provisioning/StateMachines] [----] I, [2015-05-12T16:57:12.735337 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Updated namespace [Cloud/Orchestration/Provisioning/StateMachines/Methods/check_provisioned Default/Cloud/Orchestration/Provisioning/StateMachines] [----] I, [2015-05-12T16:57:12.742026 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Invoking [inline] method [/Default/Cloud/Orchestration/Provisioning/StateMachines/Methods/check_provisioned] with inputs [{}] [----] I, [2015-05-12T16:57:12.742917 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod [/Default/Cloud/Orchestration/Provisioning/StateMachines/Methods/check_provisioned]> Starting [----] I, [2015-05-12T16:57:13.098256 #2354:5e9ccbc] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod check_provisioned> Check orchestration deployed [----] I, [2015-05-12T16:57:14.193108 #2354:5e9ccbc] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod check_provisioned> Stack deployment finished. Status: error, reason: Stack was rolled back [----] I, [2015-05-12T16:57:14.199511 #2354:5e9ccbc] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod check_provisioned> Refreshing provider ec2-east [----] I, [2015-05-12T16:57:14.270847 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod [/Default/Cloud/Orchestration/Provisioning/StateMachines/Methods/check_provisioned]> Ending [----] I, [2015-05-12T16:57:14.270957 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Method exited with rc=MIQ_OK [----] I, [2015-05-12T16:57:14.271313 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Followed Relationship [miqaedb:/Cloud/Orchestration/Provisioning/StateMachines/Methods/CheckProvisioned#create] [----] I, [2015-05-12T16:57:14.271457 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Processed State=[checkprovisioned] with Result=[retry] [----] I, [2015-05-12T16:57:14.271723 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) In State=[checkprovisioned], invoking [on_exit] method=[update_serviceprovision_status(status => 'Creating Stack')] [----] I, [2015-05-12T16:57:14.275284 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Updated namespace [Cloud/Orchestration/Provisioning/StateMachines/Provision/update_serviceprovision_status Default/Cloud/Orchestration/Provisioning/StateMachines] [----] I, [2015-05-12T16:57:14.612612 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Invoking [inline] method [/Default/Cloud/Orchestration/Provisioning/StateMachines/Provision/update_serviceprovision_status] with inputs [{"status"=>"Creating Stack"}] [----] I, [2015-05-12T16:57:14.613513 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod [/Default/Cloud/Orchestration/Provisioning/StateMachines/Provision/update_serviceprovision_status]> Starting [----] I, [2015-05-12T16:57:14.970253 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod [/Default/Cloud/Orchestration/Provisioning/StateMachines/Provision/update_serviceprovision_status]> Ending [----] I, [2015-05-12T16:57:14.970371 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Method exited with rc=MIQ_OK [----] I, [2015-05-12T16:57:48.044893 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Instantiating [/ManageIQ/Cloud/Orchestration/Provisioning/StateMachines/Provision/default?MiqServer%3A%3Amiq_server=1&ServiceTemplateProvisionTask%3A%3Aservice_template_provision_task=12&User%3A%3Auser=1&ae_state=checkprovisioned&ae_state_data=---%20%21ruby%2Fhash%3AActiveSupport%3A%3AHashWithIndifferentAccess%0Adeploy_result%3A%20error%0Adeploy_reason%3A%20Stack%20was%20rolled%20back%0Aprovider_last_refresh%3A%202015-05-12%2020%3A52%3A16.168145000%20Z%0A&ae_state_retries=2&ae_state_started=2015-05-12%2020%3A56%3A07%20UTC&dialog_param_DBName=MyDatabase&dialog_param_InstanceType=m1.small&dialog_param_KeyName=userkey&dialog_param_SSHLocation=0.0.0.0%2F0&dialog_stack_name=abcd&dialog_stack_onfailure=ROLLBACK&object_name=default&password%3A%3Adialog_param_DBPassword=v2%3A%7BRNZC9%2F49XqS1gicyW9Jdxw%3D%3D%7D&password%3A%3Adialog_param_DBRootPassword=v2%3A%7BRNZC9%2F49XqS1gicyW9Jdxw%3D%3D%7D&password%3A%3Adialog_param_DBUser=v2%3A%7BHl0VySdrAoGk7oD9LlPjHw%3D%3D%7D&request=clone_to_service&vmdb_object_type=service_template_provision_task#create] [----] I, [2015-05-12T16:57:48.177090 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Processing State=[checkprovisioned] [----] I, [2015-05-12T16:57:48.177347 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Following Relationship [miqaedb:/Cloud/Orchestration/Provisioning/StateMachines/Methods/CheckProvisioned#create] [----] I, [2015-05-12T16:57:48.496898 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Updated namespace [miqaedb:/Cloud/Orchestration/Provisioning/StateMachines/Methods/CheckProvisioned#create Default/Cloud/Orchestration/Provisioning/StateMachines] [----] I, [2015-05-12T16:57:48.627095 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Updated namespace [Cloud/Orchestration/Provisioning/StateMachines/Methods/check_provisioned Default/Cloud/Orchestration/Provisioning/StateMachines] [----] I, [2015-05-12T16:57:48.633035 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Invoking [inline] method [/Default/Cloud/Orchestration/Provisioning/StateMachines/Methods/check_provisioned] with inputs [{}] [----] I, [2015-05-12T16:57:48.633973 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod [/Default/Cloud/Orchestration/Provisioning/StateMachines/Methods/check_provisioned]> Starting [----] I, [2015-05-12T16:57:48.962173 #2354:6a130c8] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod check_provisioned> Check refresh status of stack (abcd) [----] I, [2015-05-12T16:57:49.245107 #2354:6a130c8] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod check_provisioned> Stack (abcd, id = 5) has been added to VMDB [----] I, [2015-05-12T16:57:49.269626 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod [/Default/Cloud/Orchestration/Provisioning/StateMachines/Methods/check_provisioned]> Ending [----] I, [2015-05-12T16:57:49.269751 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Method exited with rc=MIQ_OK [----] I, [2015-05-12T16:57:49.270089 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Followed Relationship [miqaedb:/Cloud/Orchestration/Provisioning/StateMachines/Methods/CheckProvisioned#create] [----] I, [2015-05-12T16:57:49.270241 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Processed State=[checkprovisioned] with Result=[error] [----] W, [2015-05-12T16:57:49.270377 #2354:711eac] WARN -- : Q-task_id([service_template_provision_task_12]) Error in State=[checkprovisioned] [----] I, [2015-05-12T16:57:49.270677 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) In State=[checkprovisioned], invoking [on_error] method=[update_serviceprovision_status(status => 'Stack was rolled back')] [----] I, [2015-05-12T16:57:49.274374 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Updated namespace [Cloud/Orchestration/Provisioning/StateMachines/Provision/update_serviceprovision_status Default/Cloud/Orchestration/Provisioning/StateMachines] [----] I, [2015-05-12T16:57:49.408033 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Invoking [inline] method [/Default/Cloud/Orchestration/Provisioning/StateMachines/Provision/update_serviceprovision_status] with inputs [{"status"=>"Stack was rolled back"}] [----] I, [2015-05-12T16:57:49.408780 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod [/Default/Cloud/Orchestration/Provisioning/StateMachines/Provision/update_serviceprovision_status]> Starting [----] I, [2015-05-12T16:57:49.756195 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) <AEMethod [/Default/Cloud/Orchestration/Provisioning/StateMachines/Provision/update_serviceprovision_status]> Ending [----] I, [2015-05-12T16:57:49.756347 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Method exited with rc=MIQ_OK [----] I, [2015-05-12T16:57:49.994165 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Instantiating [/System/Process/Event?EmsEvent%3A%3Aems_event=454&MiqServer%3A%3Amiq_server=1&event_id=454&event_type=service_provisioned&object_name=Event&vmdb_object_type=ems_event] [----] I, [2015-05-12T16:57:50.003775 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Updated namespace [/System/Process/Event?EmsEvent%3A%3Aems_event=454&MiqServer%3A%3Amiq_server=1&event_id=454&event_type=service_provisioned&object_name=Event&vmdb_object_type=ems_event Default/System] [----] I, [2015-05-12T16:57:50.066461 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Following Relationship [miqaedb:/System/Event/service_provisioned#create] [----] I, [2015-05-12T16:57:50.076126 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Updated namespace [miqaedb:/System/Event/service_provisioned#create Default/System] [----] I, [2015-05-12T16:57:50.076493 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Instance [/Default/System/Event/service_provisioned] not found in MiqAeDatastore - trying [.missing] [----] I, [2015-05-12T16:57:50.085721 #2354:711eac] INFO -- : Q-task_id([service_template_provision_task_12]) Followed Relationship [miqaedb:/System/Event/service_provisioned#create]
Fixed in 5.4.0.0.26.20150511144816_f924bd4
Since the problem described in this bug report should be resolved in a recent advisory, it has been closed with a resolution of ERRATA. For information on the advisory, and where to find the updated files, follow the link below. If the solution does not work for you, open a new bug report. https://rhn.redhat.com/errata/RHBA-2015-1100.html