Unable to complete vm build in aws

Hi,

I’ve installed a new version of foreman 2.4.1 and seeing the following message when attempting to build a VM in AWS (The VM is created and provisioning is completed until right at the end):

foreman-rake errors:fetch_log request_id=c40fa035
2021-07-09T13:58:20 [I|app|c40fa035] Started GET "/hosts/<my_vm_name>/vm" for <IPADDR> at 2021-07-09 13:58:20 +0100
2021-07-09T13:58:20 [I|app|c40fa035] Processing by HostsController#vm as HTML
2021-07-09T13:58:20 [I|app|c40fa035]   Parameters: {"id"=>"<my_vm_name>"}
2021-07-09T13:58:20 [D|tax|c40fa035] Current location set to none
2021-07-09T13:58:20 [D|tax|c40fa035] Current organization set to none
2021-07-09T13:58:20 [W|app|c40fa035] Failed to fetch vm information
2021-07-09T13:58:20 [I|app|c40fa035] Backtrace for 'Failed to fetch vm information' error (ActiveRecord::RecordNotFound): ActiveRecord::RecordNotFound
 c40fa035 | /usr/share/foreman/app/models/compute_resources/foreman/model/ec2.rb:50:in `rescue in find_vm_by_uuid'
 c40fa035 | /usr/share/foreman/app/models/compute_resources/foreman/model/ec2.rb:47:in `find_vm_by_uuid'
 c40fa035 | /usr/share/foreman/app/controllers/hosts_controller.rb:280:in `vm'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/abstract_controller/base.rb:195:in `process_action'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_controller/metal/rendering.rb:30:in `process_action'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/abstract_controller/callbacks.rb:42:in `block in process_action'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/callbacks.rb:112:in `block in run_callbacks'
 c40fa035 | /usr/share/foreman/app/controllers/concerns/foreman/controller/timezone.rb:10:in `set_timezone'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/callbacks.rb:121:in `block in run_callbacks'
 c40fa035 | /usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/callbacks.rb:121:in `block in run_callbacks'
 c40fa035 | /usr/share/foreman/app/controllers/concerns/foreman/controller/topbar_sweeper.rb:12:in `set_topbar_sweeper_controller'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/callbacks.rb:121:in `block in run_callbacks'
 c40fa035 | /usr/share/gems/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/callbacks.rb:121:in `block in run_callbacks'
 c40fa035 | /usr/share/gems/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/callbacks.rb:121:in `block in run_callbacks'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/callbacks.rb:139:in `run_callbacks'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/abstract_controller/callbacks.rb:41:in `process_action'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_controller/metal/rescue.rb:22:in `process_action'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_controller/metal/instrumentation.rb:33:in `block in process_action'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/notifications.rb:180:in `block in instrument'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/notifications/instrumenter.rb:24:in `instrument'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/notifications.rb:180:in `instrument'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_controller/metal/instrumentation.rb:32:in `process_action'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_controller/metal/params_wrapper.rb:245:in `process_action'
 c40fa035 | /usr/share/gems/gems/activerecord-6.0.3.4/lib/active_record/railties/controller_runtime.rb:27:in `process_action'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/abstract_controller/base.rb:136:in `process'
 c40fa035 | /usr/share/gems/gems/actionview-6.0.3.4/lib/action_view/rendering.rb:39:in `process'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_controller/metal.rb:190:in `dispatch'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_controller/metal.rb:254:in `dispatch'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/routing/route_set.rb:50:in `dispatch'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/routing/route_set.rb:33:in `serve'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/journey/router.rb:49:in `block in serve'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/journey/router.rb:32:in `each'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/journey/router.rb:32:in `serve'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/routing/route_set.rb:834:in `call'
 c40fa035 | /usr/share/gems/gems/apipie-dsl-2.3.0/lib/apipie_dsl/static_dispatcher.rb:67:in `call'
 c40fa035 | /usr/share/gems/gems/apipie-rails-0.5.17/lib/apipie/static_dispatcher.rb:66:in `call'
 c40fa035 | /usr/share/gems/gems/apipie-rails-0.5.17/lib/apipie/extractor/recorder.rb:137:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/static.rb:126:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/static.rb:126:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/static.rb:126:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/static.rb:126:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/static.rb:126:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/static.rb:126:in `call'
 c40fa035 | /usr/share/foreman/lib/foreman/middleware/telemetry.rb:10:in `call'
 c40fa035 | /usr/share/gems/gems/apipie-rails-0.5.17/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
 c40fa035 | /usr/share/foreman/lib/foreman/middleware/catch_json_parse_errors.rb:9:in `call'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/tempfile_reaper.rb:15:in `call'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/etag.rb:27:in `call'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/conditional_get.rb:27:in `call'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/head.rb:12:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/http/content_security_policy.rb:18:in `call'
 c40fa035 | /usr/share/foreman/lib/foreman/middleware/logging_context_session.rb:22:in `call'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:266:in `context'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:260:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/cookies.rb:648:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/callbacks.rb:27:in `block in call'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/callbacks.rb:101:in `run_callbacks'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/callbacks.rb:26:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/actionable_exceptions.rb:18:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/debug_exceptions.rb:32:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
 c40fa035 | /usr/share/gems/gems/railties-6.0.3.4/lib/rails/rack/logger.rb:37:in `call_app'
 c40fa035 | /usr/share/gems/gems/railties-6.0.3.4/lib/rails/rack/logger.rb:28:in `call'
 c40fa035 | /usr/share/gems/gems/sprockets-rails-3.2.1/lib/sprockets/rails/quiet_assets.rb:13:in `call'
 c40fa035 | /usr/share/foreman/lib/foreman/middleware/logging_context_request.rb:11:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/request_id.rb:27:in `call'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/method_override.rb:24:in `call'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/runtime.rb:22:in `call'
 c40fa035 | /usr/share/gems/gems/activesupport-6.0.3.4/lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/executor.rb:14:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/static.rb:126:in `call'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/sendfile.rb:110:in `call'
 c40fa035 | /usr/share/gems/gems/actionpack-6.0.3.4/lib/action_dispatch/middleware/host_authorization.rb:76:in `call'
 c40fa035 | /usr/share/gems/gems/secure_headers-6.3.0/lib/secure_headers/middleware.rb:11:in `call'
 c40fa035 | /usr/share/gems/gems/railties-6.0.3.4/lib/rails/engine.rb:527:in `call'
 c40fa035 | /usr/share/gems/gems/railties-6.0.3.4/lib/rails/railtie.rb:190:in `public_send'
 c40fa035 | /usr/share/gems/gems/railties-6.0.3.4/lib/rails/railtie.rb:190:in `method_missing'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/urlmap.rb:74:in `block in call'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/urlmap.rb:58:in `each'
 c40fa035 | /usr/share/gems/gems/rack-2.2.3/lib/rack/urlmap.rb:58:in `call'
 c40fa035 | /usr/share/gems/gems/puma-5.1.1/lib/puma/configuration.rb:246:in `call'
 c40fa035 | /usr/share/gems/gems/puma-5.1.1/lib/puma/request.rb:76:in `block in handle_request'
 c40fa035 | /usr/share/gems/gems/puma-5.1.1/lib/puma/thread_pool.rb:337:in `with_force_shutdown'
 c40fa035 | /usr/share/gems/gems/puma-5.1.1/lib/puma/request.rb:75:in `handle_request'
 c40fa035 | /usr/share/gems/gems/puma-5.1.1/lib/puma/server.rb:431:in `process_client'
 c40fa035 | /usr/share/gems/gems/puma-5.1.1/lib/puma/thread_pool.rb:145:in `block in spawn_thread'
 c40fa035 | /usr/share/gems/gems/logging-2.3.0/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
2021-07-09T13:58:20 [I|app|c40fa035]   Rendered common/_ajax_error.html.erb (Duration: 6.7ms | Allocations: 6049)
2021-07-09T13:58:20 [I|app|c40fa035] Completed 500 Internal Server Error in 432ms (Views: 7.3ms | ActiveRecord: 38.1ms | Allocations: 44905)

Foreman version: 2.4.1
Plugins:

  • foreman-tasks 4.0.1
  • foreman_ansible 6.2.0
  • foreman_column_view 0.4.0
  • foreman_discovery 17.0.1
  • foreman_expire_hosts 7.0.1
  • foreman_fog_proxmox 0.13.3
  • foreman_hooks 0.3.17
  • foreman_remote_execution 4.3.0
  • foreman_templates 9.0.2
  • puppetdb_foreman 5.0.0

I tried downgrading a few versions foreman-ec2, but made no difference. Any ideas on how to fix this?

Many Thanks,
Tania

After a bit more digging I’ve found the UUID returns 1_id instead of id which would explain why it cannot find the vm. Just not sure how to get it to return the correct uuid.

Never mind I found it was due foreman_fog_proxmox 0.13.3, I’ve removed for now and commented on the existing bug Proxmox plugin breaks UUID creation on foreman for libvirt · Issue #197 · theforeman/foreman_fog_proxmox · GitHub

1 Like