manageiq: Azure Provisioning never completes

Azure provisioning doesn’t complete even if the VM has been provisioned on azure portal. Inventory also shows that the VM is in running state., It is always in Creating serivice state and it continues to retries.

This is happening on Lasker and Morphy version of ManageIQ.

The miq_queue never completes and keeps increasing…

select * from miq_queue where state ='ready';

The EMS credentials has been verified by adding it again.

Update: Sometimes it does get provisioned as you can see in the below screenshot for request ID 706.

I’ve checked the log for request ID 707 and 708 and it shows that the status has succeeded but ManageIQ continues to retry.

sh-4.4# tail -f /var/www/miq/vmdb/log/evm.log | grep -i "Azure status is"
{"@timestamp":"2022-07-01T07:13:07.107183 ","hostname":"532b59dee1bc","pid":539,"tid":"2aed5ca5395c","service":"evm","level":"info","message":"Q-task_id([r707_miq_provision_1838]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Creating"}
[----] I, [2022-07-01T07:13:07.107476 #539:2aed5ca5395c]  INFO -- evm: Q-task_id([r707_miq_provision_1838]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Creating
{"@timestamp":"2022-07-01T07:13:29.361505 ","hostname":"532b59dee1bc","pid":539,"tid":"2aed5ca5395c","service":"evm","level":"info","message":"Q-task_id([r707_miq_provision_1838]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Creating"}
[----] I, [2022-07-01T07:13:29.361595 #539:2aed5ca5395c]  INFO -- evm: Q-task_id([r707_miq_provision_1838]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Creating
{"@timestamp":"2022-07-01T07:13:50.366351 ","hostname":"532b59dee1bc","pid":539,"tid":"2aed5ca5395c","service":"evm","level":"info","message":"Q-task_id([r707_miq_provision_1838]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Succeeded"}
[----] I, [2022-07-01T07:13:50.366498 #539:2aed5ca5395c]  INFO -- evm: Q-task_id([r707_miq_provision_1838]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Succeeded
^C
sh-4.4# ^C
sh-4.4# ^C
sh-4.4# ^C
sh-4.4# ^C
sh-4.4# tail -f /var/www/miq/vmdb/log/evm.log | grep -i "Azure status is"
{"@timestamp":"2022-07-01T07:21:05.877021 ","hostname":"532b59dee1bc","pid":537,"tid":"2b0ac8605970","service":"evm","level":"info","message":"Q-task_id([r708_miq_provision_1841]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Creating"}
[----] I, [2022-07-01T07:21:05.877106 #537:2b0ac8605970]  INFO -- evm: Q-task_id([r708_miq_provision_1841]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Creating
{"@timestamp":"2022-07-01T07:21:23.599211 ","hostname":"532b59dee1bc","pid":537,"tid":"2b0ac8605970","service":"evm","level":"info","message":"Q-task_id([r708_miq_provision_1841]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Succeeded"}
[----] I, [2022-07-01T07:21:23.599491 #537:2b0ac8605970]  INFO -- evm: Q-task_id([r708_miq_provision_1841]) MIQ(ManageIQ::Providers::Azure::CloudManager::Provision#do_clone_task_check) Azure status is Succeeded

---

Gitter discussion: https://gitter.im/ManageIQ/manageiq?at=62bc93608fe56a38b5e71cee