Created attachment 1162690 [details] error Description of problem: Version-Release number of selected component (if applicable): 5.6.0.8-rc1 How reproducible: 100% Steps to Reproduce: 1. Navigate to compute->clouds->instances 2. Provision and openstack instance by selecting cirros template having disk size 12.6 MB and flavor m1.xlarge . Actual results: Last message displays "[Excon::Errors::Forbidden]: Expected([200, 202]) <=> Actual(403 Forbidden) excon.error.response :body => "{\"forbidden\": {\"message\": \"Quota exceeded for cores: Requested 8, but already used 15 of 20 cores\", \"code\": 403}}" :cookies" Expected result: Proper text message should be displayed. Additional info: automation.log [----] I, [2016-05-30T00:40:44.849902 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) Updated namespace [Cloud/VM/Provisioning/StateMachines/Methods/check_provisioned ManageIQ/Cloud/VM/Provisioning/StateMachines] [----] I, [2016-05-30T00:40:44.866532 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) Invoking [inline] method [/ManageIQ/Cloud/VM/Provisioning/StateMachines/Methods/check_provisioned] with inputs [{}] [----] I, [2016-05-30T00:40:44.867810 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) <AEMethod [/ManageIQ/Cloud/VM/Provisioning/StateMachines/Methods/check_provisioned]> Starting [----] I, [2016-05-30T00:40:45.571375 #32478:6c087c] INFO -- : Q-task_id([miq_provision_8]) <AEMethod check_provisioned> ProvisionCheck returned <error> for state <finished> and status <Error> [----] I, [2016-05-30T00:40:45.619240 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) <AEMethod [/ManageIQ/Cloud/VM/Provisioning/StateMachines/Methods/check_provisioned]> Ending [----] I, [2016-05-30T00:40:45.619366 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) Method exited with rc=MIQ_OK [----] I, [2016-05-30T00:40:45.619965 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) Followed Relationship [miqaedb:/Cloud/VM/Provisioning/StateMachines/Methods/CheckProvisioned#create] [----] I, [2016-05-30T00:40:45.620122 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) Processed State=[CheckProvisioned] with Result=[error] [----] W, [2016-05-30T00:40:45.620379 #32478:6d5998] WARN -- : Q-task_id([miq_provision_8]) Error in State=[CheckProvisioned] [----] I, [2016-05-30T00:40:45.620718 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) In State=[CheckProvisioned], invoking [on_error] method=[update_provision_status(status => '[Excon::Errors::Forbidden]: Expected([200, 202]) <=> Actual(403 Forbidden) excon.error.response :body => "{\"forbidden\": {\"message\": \"Quota exceeded for cores: Requested 8, but already used 15 of 20 cores\", \"code\": 403}}" :cookies ')] [----] I, [2016-05-30T00:40:45.625076 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) Updated namespace [Cloud/VM/Provisioning/StateMachines/VMProvision_vm/update_provision_status ManageIQ/Cloud/VM/Provisioning/StateMachines] [----] E, [2016-05-30T00:40:45.635824 #32478:6d5998] ERROR -- : Q-task_id([miq_provision_8]) State=<CheckProvisioned> running on_error raised exception: <Method [/ManageIQ/Cloud/VM/Provisioning/StateMachines/VMProvision_VM/update_provision_status] requires parameter [status]> [----] I, [2016-05-30T00:40:45.636364 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) Followed Relationship [miqaedb:/Cloud/VM/Provisioning/StateMachines/VMProvision_vm/template#create] [----] I, [2016-05-30T00:40:45.636926 #32478:6d5998] INFO -- : Q-task_id([miq_provision_8]) Followed Relationship [miqaedb:/cloud/VM/Lifecycle/Provisioning#create]
evm.log error: [----] E, [2016-05-31T03:26:02.072559 #9702:6d5998] ERROR -- : Q-task_id([miq_provision_31]) MIQ(ManageIQ::Providers::Openstack::CloudManager::Provision#provision_error) [[Excon::Errors::Forbidden]: Expected([200, 202]) <=> Actual(403 Forbidden) excon.error.response :body => "{\"forbidden\": {\"message\": \"Quota exceeded for instances: Requested 1, but already used 10 of 10 instances\", \"code\": 403}}" :cookies => [ ] :headers => { "Content-Length" => "121" "Content-Type" => "application/json; charset=UTF-8" "Date" => "Tue, 31 May 2016 07:25:51 GMT" "Retry-After" => "0" "X-Compute-Request-Id" => "req-96ada69f-ce73-4714-9589-4bd5fd5852df" } :host => "10.8.58.70" :local_address => "192.168.55.84" :local_port => 52235 :path => "/v2/ff5ee96505a9421fa973482c3938e22e/servers.json" :port => 8774 :reason_phrase => "Forbidden" :remote_ip => "10.8.58.70" :status => 403 :status_line => "HTTP/1.1 403 Forbidden\r\n" ] encountered during phase [start_clone_task] [----] E, [2016-05-31T03:26:02.072705 #9702:6d5998] ERROR -- : Q-task_id([miq_provision_31]) /opt/rh/cfme-gemset/gems/excon-0.49.0/lib/excon/middlewares/expects.rb:6:in `response_call' /opt/rh/cfme-gemset/gems/excon-0.49.0/lib/excon/middlewares/response_parser.rb:8:in `response_call' /opt/rh/cfme-gemset/gems/excon-0.49.0/lib/excon/connection.rb:389:in `response' /opt/rh/cfme-gemset/gems/excon-0.49.0/lib/excon/connection.rb:253:in `request' /opt/rh/cfme-gemset/gems/fog-core-1.40.0/lib/fog/core/connection.rb:81:in `request' /opt/rh/cfme-gemset/gems/fog-openstack-0.1.6/lib/fog/openstack/compute.rb:367:in `request' /opt/rh/cfme-gemset/gems/fog-openstack-0.1.6/lib/fog/openstack/requests/compute/create_server.rb:82:in `create_server' /opt/rh/cfme-gemset/gems/fog-openstack-0.1.6/lib/fog/openstack/models/compute/server.rb:375:in `save' /opt/rh/cfme-gemset/gems/fog-core-1.40.0/lib/fog/core/collection.rb:51:in `create' /var/www/miq/vmdb/app/models/manageiq/providers/openstack/cloud_manager/provision/cloning.rb:43:in `block in start_clone' /var/www/miq/vmdb/app/models/mixins/provider_object_mixin.rb:6:in `block in with_provider_connection' /var/www/miq/vmdb/app/models/ext_management_system.rb:360:in `with_provider_connection' /var/www/miq/vmdb/app/models/mixins/provider_object_mixin.rb:5:in `with_provider_connection' /var/www/miq/vmdb/app/models/manageiq/providers/openstack/cloud_manager/provision/cloning.rb:42:in `start_clone' /var/www/miq/vmdb/app/models/manageiq/providers/cloud_manager/provision/state_machine.rb:37:in `start_clone_task' /var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:17:in `signal' /var/www/miq/vmdb/app/models/miq_provision/state_machine.rb:18:in `prepare_provision' /var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:17:in `signal' /var/www/miq/vmdb/app/models/manageiq/providers/cloud_manager/provision/state_machine.rb:28:in `poll_volumes_complete' /var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:17:in `signal' /var/www/miq/vmdb/app/models/manageiq/providers/cloud_manager/provision/state_machine.rb:15:in `prepare_volumes' /var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:17:in `signal' /var/www/miq/vmdb/app/models/manageiq/providers/cloud_manager/provision/state_machine.rb:9:in `determine_placement' /var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:17:in `signal' /var/www/miq/vmdb/app/models/manageiq/providers/cloud_manager/provision/state_machine.rb:3:in `create_destination' /var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:17:in `signal' /var/www/miq/vmdb/app/models/miq_provision/state_machine.rb:4:in `run_provision' /var/www/miq/vmdb/app/models/miq_request_task/state_machine.rb:17:in `signal' /var/www/miq/vmdb/app/models/miq_provision_task.rb:15:in `do_request' /var/www/miq/vmdb/app/models/miq_request_task.rb:192:in `execute' /var/www/miq/vmdb/app/models/miq_queue.rb:345:in `block in deliver' /opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:89:in `block in timeout' /opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:34:in `block in catch' /opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:34:in `catch' /opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:34:in `catch' /opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:104:in `timeout' /var/www/miq/vmdb/app/models/miq_queue.rb:341:in `deliver' /var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:106:in `deliver_queue_message' /var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:134:in `deliver_message' /var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:152:in `block in do_work' /var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:146:in `loop' /var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:146:in `do_work' /var/www/miq/vmdb/app/models/miq_worker/runner.rb:334:in `block in do_work_loop' /var/www/miq/vmdb/app/models/miq_worker/runner.rb:331:in `loop' /var/www/miq/vmdb/app/models/miq_worker/runner.rb:331:in `do_work_loop' /var/www/miq/vmdb/app/models/miq_worker/runner.rb:153:in `run' /var/www/miq/vmdb/app/models/miq_worker/runner.rb:128:in `start' /var/www/miq/vmdb/app/models/miq_worker/runner.rb:21:in `start_worker' /var/www/miq/vmdb/app/models/miq_worker.rb:346:in `block in start' /opt/rh/cfme-gemset/gems/nakayoshi_fork-0.0.3/lib/nakayoshi_fork.rb:24:in `fork' /opt/rh/cfme-gemset/gems/nakayoshi_fork-0.0.3/lib/nakayoshi_fork.rb:24:in `fork' /var/www/miq/vmdb/app/models/miq_worker.rb:344:in `start' /var/www/miq/vmdb/app/models/miq_worker.rb:274:in `start_worker' /var/www/miq/vmdb/app/models/miq_worker.rb:154:in `block in sync_workers' /var/www/miq/vmdb/app/models/miq_worker.rb:154:in `times' /var/www/miq/vmdb/app/models/miq_worker.rb:154:in `sync_workers' /var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:52:in `block in sync_workers' /var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:50:in `each' /var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:50:in `sync_workers' /var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:22:in `monitor_workers' /var/www/miq/vmdb/app/models/miq_server.rb:361:in `block in monitor' /var/www/miq/vmdb/gems/pending/util/extensions/miq-benchmark.rb:11:in `realtime_store' /var/www/miq/vmdb/gems/pending/util/extensions/miq-benchmark.rb:30:in `realtime_block' /var/www/miq/vmdb/app/models/miq_server.rb:361:in `monitor' /var/www/miq/vmdb/app/models/miq_server.rb:381:in `block (2 levels) in monitor_loop' /var/www/miq/vmdb/gems/pending/util/extensions/miq-benchmark.rb:11:in `realtime_store' /var/www/miq/vmdb/gems/pending/util/extensions/miq-benchmark.rb:30:in `realtime_block' /var/www/miq/vmdb/app/models/miq_server.rb:381:in `block in monitor_loop' /var/www/miq/vmdb/app/models/miq_server.rb:380:in `loop' /var/www/miq/vmdb/app/models/miq_server.rb:380:in `monitor_loop' /var/www/miq/vmdb/app/models/miq_server.rb:265:in `start' /var/www/miq/vmdb/lib/workers/evm_server.rb:65:in `start' /var/www/miq/vmdb/lib/workers/evm_server.rb:92:in `start' /var/www/miq/vmdb/lib/workers/bin/evm_server.rb:4:in `<main>'