Log Analysis During Service Provisioning

If we grep for the "Following.. Followed" message pairs in automation.log during a service provisioning request from a non-admin user, we see some interestings things (here service_template_provision is abbreviated to stp for brevity).

We see the initial automation request being created though the /System/Request/UI_PROVISION_INFO entry point:

Following Rel'ship [miqaedb:/System/Request/UI_PROVISION_INFO#create]
Following Rel'ship [miqaedb:/unknown/VM/Provisioning/Profile/Bit63Group_vm_user#get_domains]
Followed  Rel'ship [miqaedb:/unknown/VM/Provisioning/Profile/Bit63Group_vm_user#get_domains]
Followed  Rel'ship [miqaedb:/System/Request/UI_PROVISION_INFO#create]
Following Rel'ship [miqaedb:/System/Event/request_created#create]
Following Rel'ship [miqaedb:/System/Policy/request_created#create]
Following Rel'ship [miqaedb:/System/Process/parse_provider_category#create]
Followed  Rel'ship [miqaedb:/System/Process/parse_provider_category#create]
Following Rel'ship [miqaedb:/System/Policy/ServiceTemplateProvisionRequest_created#create]

We see a service provisioning profile lookup to get the auto-approval State Machine, and some events raised and processed:

Following Rel'ship [miqaedb:/service/Provisioning/Profile/Bit63Group_vm_user#get_auto_approval_state_machine]
Followed  Rel'ship [miqaedb:/service/Provisioning/Profile/Bit63Group_vm_user#get_auto_approval_state_machine]
Following Rel'ship [miqaedb:/service/Provisioning/StateMachines/ServiceProvisionRequestApproval/Default#create]
Followed  Rel'ship [miqaedb:/service/Provisioning/StateMachines/ServiceProvisionRequestApproval/Default#create]
Followed  Rel'ship [miqaedb:/System/Policy/ServiceTemplateProvisionRequest_created#create]
Followed  Rel'ship [miqaedb:/System/Policy/request_created#create]
Followed  Rel'ship [miqaedb:/System/Event/request_created#create]
Following Rel'ship [miqaedb:/System/Event/request_approved#create]
Following Rel'ship [miqaedb:/System/Policy/request_approved#create]
Following Rel'ship [miqaedb:/System/Process/parse_provider_category#create]
Followed  Rel'ship [miqaedb:/System/Process/parse_provider_category#create]

We see the request approval, and the creation of the service template provisioning request (service_template_provision_request_1000000000011). We see some processing in request context:

Following Rel'ship [miqaedb:/System/Policy/ServiceTemplateProvisionRequest_Approved#create]
Following Rel'ship [miqaedb:/Service/Provisioning/Email/ServiceTemplateProvisionRequest_Approved#create]
Q-task_id([stp_request_10...11]) Following Rel'ship [miqaedb:/System/Event/request_starting#create]
Q-task_id([stp_request_10...11]) Following Rel'ship [miqaedb:/System/Policy/request_starting#create]
Q-task_id([stp_request_10...11]) Following Rel'ship [miqaedb:/System/Process/parse_provider_category#create]
Followed  Rel'ship [miqaedb:/Service/Provisioning/Email/ServiceTemplateProvisionRequest_Approved#create]
Followed  Rel'ship [miqaedb:/System/Policy/ServiceTemplateProvisionRequest_Approved#create]
Followed  Rel'ship [miqaedb:/System/Policy/request_approved#create]
Followed  Rel'ship [miqaedb:/System/Event/request_approved#create]
Q-task_id([stp_request_10...11]) Followed  Rel'ship [miqaedb:/System/Process/parse_provider_category#create]
Q-task_id([stp_request_10...11]) Following Rel'ship [miqaedb:/System/Policy/ServiceTemplateProvisionRequest_starting#create]
Q-task_id([stp_request_10...11]) Following Rel'ship [miqaedb:/service/Provisioning/Profile/Bit63Group_vm_user#get_quota_state_machine]
Q-task_id([stp_request_10...11]) Followed  Rel'ship [miqaedb:/service/Provisioning/Profile/Bit63Group_vm_user#get_quota_state_machine]
Q-task_id([stp_request_10...11]) Following Rel'ship [miqaedb:/service/Provisioning/StateMachines/ServiceProvisionRequestQuotaVerification/Default#create]
Q-task_id([stp_request_10...11]) Followed  Rel'ship [miqaedb:/service/Provisioning/StateMachines/ServiceProvisionRequestQuotaVerification/Default#create]
Q-task_id([stp_request_10...11]) Followed  Rel'ship [miqaedb:/System/Policy/ServiceTemplateProvisionRequest_starting#create]
Q-task_id([stp_request_10...11]) Followed  Rel'ship [miqaedb:/System/Policy/request_starting#create]
Q-task_id([stp_request_10...11]) Followed  Rel'ship [miqaedb:/System/Event/request_starting#create]
Q-task_id([stp_request_10...11]) Following Rel'ship [miqaedb:/System/Request/UI_PROVISION_INFO#create]
Q-task_id([stp_request_10...11]) Following Rel'ship [miqaedb:/infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_vmname]
Q-task_id([stp_request_10...11]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Naming/Default#create]
Q-task_id([stp_request_10...11]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Naming/Default#create]
Q-task_id([stp_request_10...11]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_vmname]
Q-task_id([stp_request_10...11]) Followed  Rel'ship [miqaedb:/System/Request/UI_PROVISION_INFO#create]

Notice that this request processing runs the naming method, which is therefore processed before CatalogItemInitialization (which is processed in task context).

Next we see two service template provisioning tasks created, our top-level and child task objects (service_template_provision_task_1000000000031 and service_template_provision_task_1000000000032).

The two tasks are actually running through two separate State Machines. Task service_template_provision_task_1000000000031 is running through /Service/Provisioning/StateMachines/ServiceProvision_Template/CatalogItemInitialization, while task service_template_provision_task_1000000000032 is running through /Service/Provisioning/StateMachines/ServiceProvision_Template/clone_to_service.

Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/DialogParser#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/DialogParser#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CatalogItemInitialization#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CatalogItemInitialization#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/Provision#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/Provision#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/GroupSequenceCheck#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/GroupSequenceCheck#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/Provision#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/Provision#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]

We see our grandchild miq_provision task object created (miq_provision_1000000000033), and it processing the /Infrastructure/VM/Provisioning/StateMachines methods in the State Machine defined in our user profile:

Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CustomizeRequest#VMware]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CustomizeRequest#VMware]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Placement/default#VMware]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Placement/default#VMware]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/PreProvision#VMware]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/PreProvision#VMware]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/Provision#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/Provision#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/System/Request/UI_PROVISION_INFO#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_host_and_storage]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_host_and_storage]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/System/Request/UI_PROVISION_INFO#create]

We see both top-level and child service template provisioning tasks running their CheckProvisioned Methods:

Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]

We see the entire /Infrastructure/VM/Provisioning/StateMachines State Machine being re-instantiated for each call of its CheckProvisioned Method, including the Profile lookup:

/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine.

(recall that if a state/stage exits with $evm.root['ae_result'] = 'retry', the entire State Machine is re-launched after the retry interval, starting at the state/stage to be retried)

Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]

We see the Infrastructure/VM provisioning State Machine CheckProvisioned Method return success, and continue with the remainder of the State Machine:

Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Profile/Bit63Group_vm_user#get_state_machine]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/PostProvision#VMware]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/Methods/PostProvision#VMware]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Email/MiqProvision_Complete?event=vm_provisioned#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/Email/MiqProvision_Complete?event=vm_provisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/System/CommonMethods/StateMachineMethods/vm_provision_finished#create]
Q-task_id([miq_provision_10...33]) Following Rel'ship [miqaedb:/System/Event/service_provisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/System/Event/service_provisioned#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/System/CommonMethods/StateMachineMethods/vm_provision_finished#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/Infrastructure/VM/Provisioning/StateMachines/VMProvision_vm/template#create]
Q-task_id([miq_provision_10...33]) Followed  Rel'ship [miqaedb:/infrastructure/VM/Lifecycle/Provisioning#create]

Finally we see both of the Service provisioning State Machine CheckProvisioned Methods return success, and continue with the remainder of their State Machines:

Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/Service/Provisioning/Email/ServiceProvision_complete?event=service_provisioned#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/Service/Provisioning/Email/ServiceProvision_complete?event=service_provisioned#create]
Q-task_id([stp_task_10...32]) Following Rel'ship [miqaedb:/System/CommonMethods/StateMachineMethods/service_provision_finished#create]
Q-task_id([stp_task_10...32]) Followed  Rel'ship [miqaedb:/System/CommonMethods/StateMachineMethods/service_provision_finished#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/StateMachines/Methods/CheckProvisioned#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/Service/Provisioning/Email/ServiceProvision_complete?event=service_provisioned#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/Service/Provisioning/Email/ServiceProvision_complete?event=service_provisioned#create]
Q-task_id([stp_task_10...31]) Following Rel'ship [miqaedb:/System/CommonMethods/StateMachineMethods/service_provision_finished#create]
Q-task_id([stp_task_10...31]) Followed  Rel'ship [miqaedb:/System/CommonMethods/StateMachineMethods/service_provision_finished#create]