gistfile1.txt

2020-06-24T16:41:57 [I|app|896c85e1] Started POST "/hosts" for 10.x.x.x at 2020-06-24 16:41:57 +0000
2020-06-24T16:41:57 [I|app|896c85e1] Processing by HostsController#create as */*
2020-06-24T16:41:57 [I|app|896c85e1]   Parameters: {"utf8"=>"✓", "authenticity_token"=>"NtDzbGZDLHLqxcsRiGKFQqrK3pE8Hl+hv84ihPnp0+9qpdGyKlAL9V69XW3MY7h/LmFuMOtbWV8zNgBPzMyvxA==", "host"=>{"name"=>"adam-ranildi", "organization_id"=>"8", "location_id"=>"2", "hostgroup_id"=>"1", "content_facet_attributes"=>{"lifecycle_environment_id"=>"4", "content_view_id"=>"4", "content_source_id"=>"1"}, "puppetclass_ids"=>[""], "managed"=>"true", "progress_report_id"=>"[FILTERED]", "type"=>"Host::Managed", "interfaces_attributes"=>{"0"=>{"_destroy"=>"0", "type"=>"Nic::Managed", "mac"=>"", "identifier"=>"", "name"=>"adam-ranildi", "domain_id"=>"2", "subnet_id"=>"1", "ip"=>"10.x.x.x", "ip6"=>"", "managed"=>"1", "primary"=>"1", "provision"=>"1", "virtual"=>"0", "tag"=>"", "attached_to"=>"", "compute_attributes"=>{"type"=>"VirtualE1000", "network"=>"network-168"}}}, "compute_attributes"=>{"cpus"=>"1", "corespersocket"=>"1", "memory_mb"=>"2048", "firmware"=>"bios", "cluster"=>"10.x.x.x", "resource_pool"=>"Resources", "path"=>"/Datacenters/Slvmcs/vm", "guest_id"=>"centos7_64Guest", "hardware_version"=>"Default", "memoryHotAddEnabled"=>"0", "cpuHotAddEnabled"=>"0", "add_cdrom"=>"0", "boot_order"=>["network", "disk"], "start"=>"1", "annotation"=>"", "scsi_controllers"=>"{\"scsiControllers\":[{\"type\":\"VirtualLsiLogicController\",\"key\":1000}],\"volumes\":[{\"thin\":true,\"name\":\"Hard disk\",\"mode\":\"persistent\",\"controllerKey\":1000,\"size\":104857600,\"sizeGb\":100,\"datastore\":\"datastore1 (6)\",\"storagePod\":null}]}"}, "architecture_id"=>"1", "operatingsystem_id"=>"1", "provision_method"=>"build", "build"=>"1", "medium_id"=>"11", "ptable_id"=>"113", "pxe_loader"=>"PXELinux BIOS", "disk"=>"", "root_pass"=>"[FILTERED]", "is_owned_by"=>"4-Users", "enabled"=>"1", "comment"=>"", "overwrite"=>"false"}, "media_selector"=>"install_media"}
2020-06-24T16:41:57 [D|tax|896c85e1] Current location set to none
2020-06-24T16:41:57 [D|tax|896c85e1] Current organization set to BOS
2020-06-24T16:41:57 [D|app|896c85e1] Unpermitted parameter: :media_selector
2020-06-24T16:41:57 [D|tax|896c85e1] Current organization set to BOS
2020-06-24T16:41:57 [D|tax|896c85e1] Current location set to Default Organization
2020-06-24T16:41:57 [D|tax|896c85e1] Current location set to none
2020-06-24T16:41:57 [D|tax|896c85e1] Current organization set to BOS
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Deploy TFTP PXELinux config for adam-ranildi.qa.examples.com' to 'Host::Managed Main' queue
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Deploy TFTP PXEGrub2 config for adam-ranildi.qa.examples.com' to 'Host::Managed Main' queue
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Deploy TFTP PXEGrub config for adam-ranildi.qa.examples.com' to 'Host::Managed Main' queue
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Deploy TFTP iPXE config for adam-ranildi.qa.examples.com' to 'Host::Managed Main' queue
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Fetch TFTP boot files for adam-ranildi.qa.examples.com' to 'Host::Managed Main' queue
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Set up compute instance adam-ranildi.qa.examples.com' to 'Host::Managed Main' queue
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Query instance details for adam-ranildi.qa.examples.com' to 'Host::Managed Main' queue
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Power up compute instance adam-ranildi.qa.examples.com' to 'Host::Managed Main' queue
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Cleanup PuppetCA certificates for adam-ranildi.qa.examples.com' to 'Host::Managed Post' queue
2020-06-24T16:41:57 [D|app|896c85e1] Enqueued task 'Enable PuppetCA autosigning for adam-ranildi.qa.examples.com' to 'Host::Managed Post' queue
2020-06-24T16:41:58 [D|app|896c85e1] Processing task 'Set up compute instance adam-ranildi.qa.examples.com' from 'Host::Managed Main'
2020-06-24T16:41:58 [I|app|896c85e1] Adding Compute instance for adam-ranildi.qa.examples.com
2020-06-24T16:41:58 [D|app|896c85e1] Reading from compute resource cache: networks
2020-06-24T16:41:59 [I|app|cf78e5b5] Started GET "/tasks/6ca77502-1093-41f5-bc9c-f2d30f23867d" for 10.x.x.x at 2020-06-24 16:41:59 +0000
2020-06-24T16:41:59 [I|app|cf78e5b5] Processing by TasksController#show as */*
2020-06-24T16:41:59 [I|app|cf78e5b5]   Parameters: {"id"=>"6ca77502-1093-41f5-bc9c-f2d30f23867d"}
2020-06-24T16:41:59 [W|app|896c85e1] Failed to create a compute vCenter - Wembley (VMware) instance adam-ranildi.qa.examples.com: PlatformConfigFault: An error occurred during host configuration.
 
2020-06-24T16:41:59 [D|app|896c85e1] Backtrace for 'Failed to create a compute vCenter - Wembley (VMware) instance adam-ranildi.qa.examples.com: PlatformConfigFault: An error occurred during host configuration.
 ' error (RbVmomi::Fault): PlatformConfigFault: An error occurred during host configuration.
/opt/theforeman/tfm/root/usr/share/gems/gems/rbvmomi-2.2.0/lib/rbvmomi/vim/Task.rb:14:in `wait_for_completion'
/opt/theforeman/tfm/root/usr/share/gems/gems/fog-vsphere-3.2.5/lib/fog/vsphere/requests/compute/create_vm.rb:48:in `create_vm_on_datastore'
/opt/theforeman/tfm/root/usr/share/gems/gems/fog-vsphere-3.2.5/lib/fog/vsphere/requests/compute/create_vm.rb:40:in `create_vm'
/opt/theforeman/tfm/root/usr/share/gems/gems/fog-vsphere-3.2.5/lib/fog/vsphere/models/compute/server.rb:302:in `save'
/usr/share/foreman/app/models/compute_resources/foreman/model/vmware.rb:490:in `create_vm'
/usr/share/foreman/app/models/concerns/orchestration/compute.rb:91:in `setCompute'
/usr/share/foreman/app/models/concerns/orchestration.rb:220:in `execute'
/usr/share/foreman/app/models/concerns/orchestration.rb:145:in `block in process'
/usr/share/foreman/app/models/concerns/orchestration.rb:137:in `each'
/usr/share/foreman/app/models/concerns/orchestration.rb:137:in `process'
/usr/share/foreman/app/models/concerns/orchestration.rb:44:in `around_save_orchestration'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:136:in `run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:816:in `_run_save_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/callbacks.rb:342:in `create_or_update'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/persistence.rb:275:in `save'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/validations.rb:46:in `save'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:310:in `block (2 levels) in save'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:387:in `block in with_transaction_returning_status'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `block in transaction'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:239:in `block in within_new_transaction'
/opt/rh/rh-ruby25/root/usr/share/ruby/monitor.rb:226:in `mon_synchronize'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:236:in `within_new_transaction'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `transaction'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:212:in `transaction'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:385:in `with_transaction_returning_status'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:310:in `block in save'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:325:in `rollback_active_record_state!'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/transactions.rb:309:in `save'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/suppressor.rb:44:in `save'
/usr/share/foreman/app/models/concerns/foreman/sti.rb:26:in `save'
/usr/share/foreman/app/controllers/hosts_controller.rb:98:in `create'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:194:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/rendering.rb:30:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:42:in `block in process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:109:in `block in run_callbacks'
/usr/share/foreman/app/controllers/concerns/foreman/controller/timezone.rb:10:in `set_timezone'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/app/controllers/concerns/foreman/controller/topbar_sweeper.rb:12:in `set_topbar_sweeper_controller'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:136:in `run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:41:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/rescue.rb:22:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:34:in `block in process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `block in instrument'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications/instrumenter.rb:23:in `instrument'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `instrument'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:32:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/params_wrapper.rb:256:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/railties/controller_runtime.rb:24:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:134:in `process'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionview-5.2.1/lib/action_view/rendering.rb:32:in `process'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal.rb:191:in `dispatch'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal.rb:252:in `dispatch'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:52:in `dispatch'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:34:in `serve'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:52:in `block in serve'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `each'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `serve'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:840:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.15.1.1/lib/katello/middleware/event_daemon.rb:10:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.17/lib/apipie/static_dispatcher.rb:66:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.17/lib/apipie/extractor/recorder.rb:137:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/usr/share/foreman/lib/foreman/middleware/telemetry.rb:10:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.17/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
/usr/share/foreman/lib/foreman/middleware/catch_json_parse_errors.rb:9:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/tempfile_reaper.rb:15:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/etag.rb:25:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/conditional_get.rb:38:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/head.rb:12:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/http/content_security_policy.rb:18:in `call'
/usr/share/foreman/lib/foreman/middleware/logging_context_session.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/session/abstract/id.rb:232:in `context'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/session/abstract/id.rb:226:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/cookies.rb:670:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:98:in `run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:26:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/debug_exceptions.rb:61:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/rack/logger.rb:38:in `call_app'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/rack/logger.rb:28:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/sprockets-rails-3.2.1/lib/sprockets/rails/quiet_assets.rb:13:in `call'
/usr/share/foreman/lib/foreman/middleware/logging_context_request.rb:11:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/request_id.rb:27:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.15.1.1/lib/katello/prevent_json_parsing.rb:12:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/method_override.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/runtime.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/executor.rb:14:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/sendfile.rb:111:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/secure_headers-6.3.0/lib/secure_headers/middleware.rb:11:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/engine.rb:524:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `public_send'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `method_missing'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/urlmap.rb:68:in `block in call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/urlmap.rb:53:in `each'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/urlmap.rb:53:in `call'
/usr/share/passenger/phusion_passenger/rack/thread_handler_extension.rb:74:in `process_request'
/usr/share/passenger/phusion_passenger/request_handler/thread_handler.rb:141:in `accept_and_process_next_request'
/usr/share/passenger/phusion_passenger/request_handler/thread_handler.rb:109:in `main_loop'
/usr/share/passenger/phusion_passenger/request_handler.rb:455:in `block (3 levels) in start_threads'
/opt/theforeman/tfm/root/usr/share/gems/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
2020-06-24T16:41:59 [W|app|896c85e1] Rolling back due to a problem: [#<Orchestration::Task:0x00007fa3f121b500 @name="Set up compute instance adam-ranildi.qa.examples.com", @id="Set up compute instance adam-ranildi.qa.examples.com", @status="failed", @priority=3, @action=[#<Host::Managed id: nil, name: "adam-ranildi.qa.examples.com", last_compile: nil, last_report: nil, updated_at: nil, created_at: nil, root_pass: "$5$jFJf4NTpKqWP8cUF$Cuo6fX/HyUkydu0bWm4dn7xigPOU/K...", architecture_id: 1, operatingsystem_id: 1, environment_id: 2, ptable_id: 113, medium_id: 11, build: true, comment: "", disk: "", installed_at: nil, model_id: nil, hostgroup_id: 1, owner_id: 4, owner_type: "User", enabled: true, puppet_ca_proxy_id: 1, managed: true, use_image: nil, image_file: nil, uuid: nil, compute_resource_id: 1, puppet_proxy_id: 1, certname: nil, image_id: nil, organization_id: 8, location_id: 2, type: "Host::Managed", otp: nil, realm_id: nil, compute_profile_id: 9, provision_method: "build", grub_pass: "$6$CqSYi85oYrPBWllp$S7zV18rEX8qpGWY4p1sraipBaioz10...", global_status: 0, lookup_value_matcher: "fqdn=adam-ranildi.qa.examples.com", pxe_loader: "PXELinux BIOS", initiated_at: nil, build_errors: nil>, :setCompute], @created=1593016917.5124097, @timestamp=2020-06-24 16:41:59 UTC>]
2020-06-24T16:41:59 [I|app|896c85e1] Processed 1 tasks from queue 'Host::Managed Main', completed 0/8
2020-06-24T16:41:59 [E|app|896c85e1] Task 'Set up compute instance adam-ranildi.qa.examples.com' *failed*
2020-06-24T16:41:59 [E|app|896c85e1] Task 'Query instance details for adam-ranildi.qa.examples.com' *canceled*
2020-06-24T16:41:59 [E|app|896c85e1] Task 'Deploy TFTP PXELinux config for adam-ranildi.qa.examples.com' *canceled*
2020-06-24T16:41:59 [E|app|896c85e1] Task 'Deploy TFTP PXEGrub2 config for adam-ranildi.qa.examples.com' *canceled*
2020-06-24T16:41:59 [E|app|896c85e1] Task 'Deploy TFTP PXEGrub config for adam-ranildi.qa.examples.com' *canceled*
2020-06-24T16:41:59 [E|app|896c85e1] Task 'Deploy TFTP iPXE config for adam-ranildi.qa.examples.com' *canceled*
2020-06-24T16:41:59 [E|app|896c85e1] Task 'Fetch TFTP boot files for adam-ranildi.qa.examples.com' *canceled*
2020-06-24T16:41:59 [E|app|896c85e1] Task 'Power up compute instance adam-ranildi.qa.examples.com' *canceled*
2020-06-24T16:41:59 [I|app|cf78e5b5]   Rendered tasks/_list.html.erb (3.1ms)
2020-06-24T16:41:59 [I|app|cf78e5b5] Completed 200 OK in 29ms (Views: 15.5ms | ActiveRecord: 2.4ms)
2020-06-24T16:41:59 [D|app|896c85e1] Unpermitted parameter: :media_selector
2020-06-24T16:41:59 [D|app|896c85e1] Unpermitted parameter: :media_selector
2020-06-24T16:41:59 [D|app|896c85e1] Unpermitted parameter: :media_selector
2020-06-24T16:41:59 [E|app|896c85e1] Failed to save: Failed to create a compute vCenter - Wembley (VMware) instance adam-ranildi.qa.examples.com: PlatformConfigFault: An error occurred during host configuration.
 
2020-06-24T16:41:59 [I|app|896c85e1]   Rendering hosts/new.html.erb within layouts/application
2020-06-24T16:41:59 [I|app|896c85e1]   Rendered hosts/_progress.html.erb (0.5ms)
2020-06-24T16:41:59 [D|tax|896c85e1] Current organization set to BOS
2020-06-24T16:41:59 [D|tax|896c85e1] Current location set to Default Organization
2020-06-24T16:41:59 [I|app|896c85e1]   Rendered /opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.15.1.1/app/views/overrides/activation_keys/_host_environment_select.html.erb (28.2ms)
2020-06-24T16:41:59 [I|app|896c85e1]   Rendered hosts/puppet/_main_tab_fields.html.erb (15.1ms)
2020-06-24T16:41:59 [I|app|896c85e1]   Rendered collection of puppetclasses/_selectedClasses.html.erb [0 times] (0.0ms)
2020-06-24T16:41:59 [I|app|896c85e1]   Rendered collection of puppetclasses/_classes_in_groups.html.erb [0 times] (0.0ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered puppetclasses/_classes.html.erb (0.3ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered puppetclasses/_class_selection.html.erb (486.7ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered hosts/puppet/_puppet_classes_tab.html.erb (487.4ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered nic/_base_form.html.erb (92.4ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered nic/_virtual_form.html.erb (2.4ms)
2020-06-24T16:42:00 [D|app|896c85e1] Reading from compute resource cache: networks
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered compute_resources_vms/form/vmware/_network.html.erb (2.4ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered nic/_provider_specific_form.html.erb (4.8ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered nic/manageds/_managed.html.erb (102.5ms)
2020-06-24T16:42:00 [I|app|824cc5cb] Started GET "/notification_recipients" for 10.x.x.x.x at 2020-06-24 16:42:00 +0000
2020-06-24T16:42:00 [I|app|824cc5cb] Processing by NotificationRecipientsController#index as JSON
2020-06-24T16:42:00 [D|tax|824cc5cb] Current location set to none
2020-06-24T16:42:00 [D|tax|824cc5cb] Current organization set to BOS
2020-06-24T16:42:00 [D|not|824cc5cb] Cache Hit: notification, reading cache for notification-4
2020-06-24T16:42:00 [D|app|824cc5cb] Body: {"notifications":[]}
2020-06-24T16:42:00 [I|app|824cc5cb] Completed 200 OK in 22ms (Views: 0.3ms | ActiveRecord: 1.9ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered nic/_base_form.html.erb (94.2ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered nic/_virtual_form.html.erb (2.4ms)
2020-06-24T16:42:00 [D|app|896c85e1] Reading from compute resource cache: networks
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered compute_resources_vms/form/vmware/_network.html.erb (2.3ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered nic/_provider_specific_form.html.erb (4.7ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered nic/manageds/_managed.html.erb (104.3ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered hosts/_interfaces.html.erb (209.3ms)
2020-06-24T16:42:00 [D|app|896c85e1] Reading from compute resource cache: resource_pools-10.x.x.x
2020-06-24T16:42:00 [D|app|896c85e1] Reading from compute resource cache: folders
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered compute_resources_vms/form/vmware/_base.html.erb (505.1ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered compute_resources_vms/form/_volumes.html.erb (0.7ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered hosts/_compute_detail.html.erb (506.8ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered hosts/_compute.html.erb (509.5ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered common/os_selection/_architecture.html.erb (8.0ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered common/os_selection/_pxe_loader.html.erb (2.3ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered common/os_selection/_operatingsystem.html.erb (157.7ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered hosts/provision_method/build/_form.html.erb (160.1ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered hosts/provision_method/image/_form.html.erb (0.4ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered hosts/_operating_system.html.erb (176.2ms)
2020-06-24T16:42:00 [I|app|896c85e1]   Rendered hosts/_unattended.html.erb (686.7ms)
2020-06-24T16:42:01 [I|app|896c85e1]   Rendered collection of puppetclasses/_class_parameters.html.erb [0 times] (0.0ms)
2020-06-24T16:42:01 [I|app|896c85e1]   Rendered puppetclasses/_classes_parameters.html.erb (16.9ms)
2020-06-24T16:42:01 [I|app|896c85e1]   Rendered common_parameters/_inherited_parameters.html.erb (0.5ms)
2020-06-24T16:42:01 [I|app|896c85e1]   Rendered common_parameters/_parameter.html.erb (3.5ms)
2020-06-24T16:42:01 [I|app|896c85e1]   Rendered common_parameters/_parameters.html.erb (7.3ms)
2020-06-24T16:42:01 [D|tax|896c85e1] Current location set to none
2020-06-24T16:42:01 [D|tax|896c85e1] Current organization set to BOS
2020-06-24T16:42:01 [I|app|896c85e1]   Rendered hosts/_form.html.erb (1629.3ms)
2020-06-24T16:42:01 [I|app|896c85e1]   Rendered hosts/new.html.erb within layouts/application (1630.2ms)
2020-06-24T16:42:01 [I|app|896c85e1]   Rendered layouts/_application_content.html.erb (2.0ms)
2020-06-24T16:42:01 [I|app|896c85e1]   Rendering layouts/base.html.erb
2020-06-24T16:42:01 [I|app|896c85e1]   Rendered layouts/base.html.erb (37.3ms)
2020-06-24T16:42:01 [I|app|896c85e1] Completed 200 OK in 3982ms (Views: 1613.4ms | ActiveRecord: 120.1ms)
2020-06-24T16:42:01 [I|app|0465b7fb] Started GET "/tasks/6ca77502-1093-41f5-bc9c-f2d30f23867d" for 10.x.x.x at 2020-06-24 16:42:01 +0000
2020-06-24T16:42:01 [I|app|0465b7fb] Processing by TasksController#show as */*
2020-06-24T16:42:01 [I|app|0465b7fb]   Parameters: {"id"=>"6ca77502-1093-41f5-bc9c-f2d30f23867d"}
2020-06-24T16:42:01 [I|app|0465b7fb]   Rendered tasks/_list.html.erb (1.0ms)
2020-06-24T16:42:01 [I|app|0465b7fb] Completed 200 OK in 13ms (Views: 1.8ms | ActiveRecord: 2.2ms)
2020-06-24T16:42:02 [I|app|9af12f44] Started GET "/api/v2/compute_resources/1-vCenter%20-%20Wembley/available_storage_domains" for 10.x.x.x at 2020-06-24 16:42:02 +0000
2020-06-24T16:42:02 [I|app|9af12f44] Processing by Api::V2::ComputeResourcesController#available_storage_domains as JSON
2020-06-24T16:42:02 [I|app|9af12f44]   Parameters: {"apiv"=>"v2", "id"=>"1-vCenter - Wembley"}
2020-06-24T16:42:02 [D|tax|9af12f44] Current location set to none
2020-06-24T16:42:02 [D|tax|9af12f44] Current organization set to BOS
2020-06-24T16:42:02 [D|app|9af12f44] Reading from compute resource cache: datastores
2020-06-24T16:42:02 [W|app|9af12f44] Action failed
2020-06-24T16:42:02 [D|app|9af12f44] Backtrace for 'Action failed' error (NameError): uninitialized constant Fog::Vsphere::Compute::Datastore
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/inflector/methods.rb:285:in `const_get'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/inflector/methods.rb:285:in `block in constantize'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/inflector/methods.rb:281:in `each'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/inflector/methods.rb:281:in `inject'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/inflector/methods.rb:281:in `constantize'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/core_ext/string/inflections.rb:68:in `constantize'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/core_ext/marshal.rb:10:in `rescue in load'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/core_ext/marshal.rb:5:in `load'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache.rb:804:in `uncompress'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache.rb:726:in `value'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache.rb:358:in `block in read'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache.rb:663:in `block in instrument'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications.rb:170:in `instrument'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache.rb:663:in `instrument'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache.rb:345:in `read'
/usr/share/foreman/app/services/compute_resource_cache.rb:34:in `read'
/usr/share/foreman/app/services/compute_resource_cache.rb:19:in `cache'
/usr/share/foreman/app/models/compute_resources/foreman/model/vmware.rb:114:in `datastores'
/usr/share/foreman/app/models/compute_resources/foreman/model/vmware.rb:183:in `available_storage_domains'
/usr/share/foreman/app/controllers/api/v2/compute_resources_controller.rb:175:in `available_storage_domains'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:194:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/rendering.rb:30:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:42:in `block in process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:109:in `block in run_callbacks'
/usr/share/foreman/app/controllers/api/v2/base_controller.rb:163:in `disable_json_root'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/app/controllers/concerns/foreman/controller/timezone.rb:10:in `set_timezone'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/app/controllers/concerns/foreman/controller/topbar_sweeper.rb:12:in `set_topbar_sweeper_controller'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:136:in `run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:41:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/rescue.rb:22:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:34:in `block in process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `block in instrument'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications/instrumenter.rb:23:in `instrument'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `instrument'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:32:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal/params_wrapper.rb:256:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/activerecord-5.2.1/lib/active_record/railties/controller_runtime.rb:24:in `process_action'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:134:in `process'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionview-5.2.1/lib/action_view/rendering.rb:32:in `process'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal.rb:191:in `dispatch'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_controller/metal.rb:252:in `dispatch'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:52:in `dispatch'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:34:in `serve'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/mapper.rb:18:in `block in <class:Constraints>'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/mapper.rb:48:in `serve'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:52:in `block in serve'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `each'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `serve'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:840:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.15.1.1/lib/katello/middleware/event_daemon.rb:10:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.17/lib/apipie/static_dispatcher.rb:66:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.17/lib/apipie/extractor/recorder.rb:137:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/usr/share/foreman/lib/foreman/middleware/telemetry.rb:10:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.5.17/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
/usr/share/foreman/lib/foreman/middleware/catch_json_parse_errors.rb:9:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/tempfile_reaper.rb:15:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/etag.rb:25:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/conditional_get.rb:25:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/head.rb:12:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/http/content_security_policy.rb:18:in `call'
/usr/share/foreman/lib/foreman/middleware/logging_context_session.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/session/abstract/id.rb:232:in `context'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/session/abstract/id.rb:226:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/cookies.rb:670:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:98:in `run_callbacks'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:26:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/debug_exceptions.rb:61:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/rack/logger.rb:38:in `call_app'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/rack/logger.rb:28:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/sprockets-rails-3.2.1/lib/sprockets/rails/quiet_assets.rb:13:in `call'
/usr/share/foreman/lib/foreman/middleware/logging_context_request.rb:11:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/request_id.rb:27:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/katello-3.15.1.1/lib/katello/prevent_json_parsing.rb:12:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/method_override.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/runtime.rb:22:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/activesupport-5.2.1/lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/executor.rb:14:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/sendfile.rb:111:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/secure_headers-6.3.0/lib/secure_headers/middleware.rb:11:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/engine.rb:524:in `call'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `public_send'
/opt/theforeman/tfm/root/usr/share/gems/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `method_missing'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/urlmap.rb:68:in `block in call'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/urlmap.rb:53:in `each'
/opt/theforeman/tfm/root/usr/share/gems/gems/rack-2.0.6/lib/rack/urlmap.rb:53:in `call'
/usr/share/passenger/phusion_passenger/rack/thread_handler_extension.rb:74:in `process_request'
/usr/share/passenger/phusion_passenger/request_handler/thread_handler.rb:141:in `accept_and_process_next_request'
/usr/share/passenger/phusion_passenger/request_handler/thread_handler.rb:109:in `main_loop'
/usr/share/passenger/phusion_passenger/request_handler.rb:455:in `block (3 levels) in start_threads'
/opt/theforeman/tfm/root/usr/share/gems/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
2020-06-24T16:42:02 [I|app|9af12f44]   Rendering api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout
2020-06-24T16:42:02 [I|app|9af12f44]   Rendered api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout (2.1ms)
2020-06-24T16:42:02 [I|app|9af12f44] Completed 500 Internal Server Error in 71ms (Views: 7.5ms | ActiveRecord: 5.3ms)
2020-06-24T16:42:02 [I|app|056a3289] Started GET "/api/v2/compute_resources/1-vCenter%20-%20Wembley/available_storage_pods" for 10.x.x.x at 2020-06-24 16:42:02 +0000
2020-06-24T16:42:02 [I|app|056a3289] Processing by Api::V2::ComputeResourcesController#available_storage_pods as JSON
2020-06-24T16:42:02 [I|app|056a3289]   Parameters: {"apiv"=>"v2", "id"=>"1-vCenter - Wembley"}
2020-06-24T16:42:02 [D|tax|056a3289] Current location set to none
2020-06-24T16:42:02 [D|tax|056a3289] Current organization set to BOS
2020-06-24T16:42:02 [D|app|056a3289] Reading from compute resource cache: storage_pods
2020-06-24T16:42:02 [I|app|056a3289]   Rendering api/v2/compute_resources/available_storage_pods.rabl within api/v2/layouts/index_layout
2020-06-24T16:42:02 [I|app|056a3289]   Rendered api/v2/compute_resources/available_storage_pods.rabl within api/v2/layouts/index_layout (0.9ms)
2020-06-24T16:42:02 [D|app|056a3289] Body: {
  "total": 0,
  "subtotal": 0,
  "page": 1,
  "per_page": 20,
  "search": null,
  "sort": {
    "by": null,
    "order": null
  },
  "results": []
}

2020-06-24T16:42:02 [I|app|056a3289] Completed 200 OK in 37ms (Views: 2.9ms | ActiveRecord: 6.2ms)
2020-06-24T16:42:03 [I|app|807c28c1] Started GET "/tasks/6ca77502-1093-41f5-bc9c-f2d30f23867d" for 10.x.x.x at 2020-06-24 16:42:03 +0000
2020-06-24T16:42:03 [I|app|807c28c1] Processing by TasksController#show as */*
2020-06-24T16:42:03 [I|app|807c28c1]   Parameters: {"id"=>"6ca77502-1093-41f5-bc9c-f2d30f23867d"}
2020-06-24T16:42:03 [I|app|807c28c1]   Rendered tasks/_list.html.erb (1.1ms)
2020-06-24T16:42:03 [I|app|807c28c1] Completed 200 OK in 15ms (Views: 2.2ms | ActiveRecord: 2.3ms)
2020-06-24T16:42:10 [I|app|c3542fb9] Started GET "/notification_recipients" for 10.x.x.x at 2020-06-24 16:42:10 +0000
2020-06-24T16:42:10 [I|app|c3542fb9] Processing by NotificationRecipientsController#index as JSON
2020-06-24T16:42:10 [D|tax|c3542fb9] Current location set to none
2020-06-24T16:42:10 [D|tax|c3542fb9] Current organization set to BOS
2020-06-24T16:42:10 [D|not|c3542fb9] Cache Hit: notification, reading cache for notification-4
2020-06-24T16:42:10 [D|app|c3542fb9] Body: {"notifications":[]}
2020-06-24T16:42:10 [I|app|c3542fb9] Completed 200 OK in 15ms (Views: 0.3ms | ActiveRecord: 1.7ms)
2020-06-24T16:42:20 [I|app|cd0983ed] Started GET "/notification_recipients" for 10.x.x.x at 2020-06-24 16:42:20 +0000
2020-06-24T16:42:20 [I|app|cd0983ed] Processing by NotificationRecipientsController#index as JSON
2020-06-24T16:42:20 [D|tax|cd0983ed] Current location set to none
2020-06-24T16:42:20 [D|tax|cd0983ed] Current organization set to BOS
2020-06-24T16:42:20 [D|not|cd0983ed] Cache Hit: notification, reading cache for notification-4
2020-06-24T16:42:20 [D|app|cd0983ed] Body: {"notifications":[]}
2020-06-24T16:42:20 [I|app|cd0983ed] Completed 200 OK in 15ms (Views: 0.3ms | ActiveRecord: 1.9ms)
2020-06-24T16:42:22 [I|app|f643a826] Started GET "/node/wevv2tinf03.prod.examples.com?format=yml" for 10.x.x.x at 2020-06-24 16:42:22 +0000
2020-06-24T16:42:22 [I|app|f643a826] Processing by HostsController#externalNodes as YML
2020-06-24T16:42:22 [I|app|f643a826]   Parameters: {"name"=>"wevv2tinf03.prod.examples.com"}
2020-06-24T16:42:22 [D|app|f643a826] Client sent certificate with subject 'wevv2tinf03.prod.examples.com' and subject alt names '["wevv2tinf03.prod.examples.com"]'
2020-06-24T16:42:22 [D|app|f643a826] CN and SANs were extracted from a client certificate.
2020-06-24T16:42:22 [D|app|f643a826] Verifying request from ["wevv2tinf03.prod.examples.com"] against ["wevv2tinf03.prod.examples.com"]
2020-06-24T16:42:22 [I|app|f643a826]   Rendering text template
2020-06-24T16:42:22 [I|app|f643a826]   Rendered text template (0.0ms)
2020-06-24T16:42:22 [I|app|f643a826] Completed 200 OK in 551ms (Views: 1.7ms | ActiveRecord: 144.3ms)
2020-06-24T16:42:23 [I|app|f449e0ea] Started POST "/api/hosts/facts" for 10.x.x.x at 2020-06-24 16:42:23 +0000
2020-06-24T16:42:23 [I|app|f449e0ea] Processing by Api::V2::HostsController#facts as JSON
2020-06-24T16:42:23 [I|app|f449e0ea]   Parameters: {"facts"=>"[FILTERED]", "name"=>"wevv2tinf03.prod.examples.com", "certname"=>"wevv2tinf03.prod.examples.com", "apiv"=>"v2", "host"=>{"certname"=>"wevv2tinf03.prod.examples.com", "name"=>"wevv2tinf03.prod.examples.com"}}
2020-06-24T16:42:23 [D|app|f449e0ea] Importer Katello::RhsmFactImporter does not implement authorized_smart_proxy_features.
2020-06-24T16:42:23 [D|app|f449e0ea] Client sent certificate with subject 'wevv2tinf03.prod.examples.com' and subject alt names '["wevv2tinf03.prod.examples.com"]'
2020-06-24T16:42:23 [D|app|f449e0ea] CN and SANs were extracted from a client certificate.
2020-06-24T16:42:23 [D|app|f449e0ea] Verifying request from ["wevv2tinf03.prod.examples.com"] against ["wevv2tinf03.prod.examples.com"]
2020-06-24T16:42:25 [I|app|f449e0ea] Import facts for 'wevv2tinf03.prod.examples.com' completed. Added: 0, Updated: 21, Deleted 0 facts
2020-06-24T16:42:25 [D|tax|f449e0ea] Current location set to none
2020-06-24T16:42:25 [D|tax|f449e0ea] Current organization set to Default Organization
2020-06-24T16:42:25 [D|app|f449e0ea] skipping interface with identifier 'lo' since it was matched by 'ignored_interface_identifiers' setting 
2020-06-24T16:42:25 [D|app|f449e0ea] We have following interfaces 'eth0' based on facts
2020-06-24T16:42:25 [D|app|f449e0ea] Interface eth0 facts: {"bindings"=>[{"address"=>"10.x.x.x", "netmask"=>"255.255.x.0", "network"=>"10.x.x.0"}], "ipaddress"=>"10.x.x.x", "macaddress"=>"00:50:56:ae:c2:d5", "mtu"=>1500, "netmask"=>"255.255.240.0", "network"=>"10.x.32.0"}
2020-06-24T16:42:25 [D|app|f449e0ea] Body: {"certname":"wevv2tinf03.prod.examples.com","build":false,"id":9,"name":"wevv2tinf03.prod.examples.com","environment_id":1,"puppet_ca_proxy_id":1,"root_pass":null,"hostgroup_id":null,"lookup_value_matcher":"fqdn=wevv2tinf03.prod.examples.com","last_compile":null,"last_report":"2020-06-24T16:12:21.000Z","updated_at":"2020-06-24T16:12:26.688Z","created_at":"2020-06-11T07:52:30.934Z","architecture_id":1,"operatingsystem_id":1,"ptable_id":null,"medium_id":null,"comment":null,"disk":null,"installed_at":null,"model_id":1,"owner_id":null,"owner_type":null,"enabled":true,"managed":false,"use_image":null,"image_file":"","uuid":null,"compute_resource_id":null,"puppet_proxy_id":1,"image_id":null,"organization_id":1,"location_id":null,"otp":null,"realm_id":null,"compute_profile_id":null,"provision_method":"build","grub_pass":"","global_status":0,"pxe_loader":null,"initiated_at":null,"build_errors":null,"reported_data_attributes":{"host_id":9,"boot_time":"2020-05-29T18:58:03.000Z","virtual":true,"ram":36042,"sockets":4,"cores":8,"id":9,"created_at":"2020-06-11T07:52:34.875Z","updated_at":"2020-06-24T16:42:25.333Z"}}
2020-06-24T16:42:25 [I|app|f449e0ea] Completed 201 Created in 1569ms (Views: 7.3ms | ActiveRecord: 338.0ms)
2020-06-24T16:42:25 [I|app|04a45b19] Started GET "/node/wevv2tinf03.prod.examples.com?format=yml" for 10.x.x.x at 2020-06-24 16:42:25 +0000
2020-06-24T16:42:25 [I|app|04a45b19] Processing by HostsController#externalNodes as YML
2020-06-24T16:42:25 [I|app|04a45b19]   Parameters: {"name"=>"wevv2tinf03.prod.examples.com"}
2020-06-24T16:42:25 [D|app|04a45b19] Client sent certificate with subject 'wevv2tinf03.prod.examples.com' and subject alt names '["wevv2tinf03.prod.examples.com"]'
2020-06-24T16:42:25 [D|app|04a45b19] CN and SANs were extracted from a client certificate.
2020-06-24T16:42:25 [D|app|04a45b19] Verifying request from ["wevv2tinf03.prod.examples.com"] against ["wevv2tinf03.prod.examples.com"]
2020-06-24T16:42:25 [I|app|04a45b19]   Rendering text template
2020-06-24T16:42:25 [I|app|04a45b19]   Rendered text template (0.0ms)
2020-06-24T16:42:25 [I|app|04a45b19] Completed 200 OK in 105ms (Views: 0.9ms | ActiveRecord: 21.7ms)
2020-06-24T16:42:26 [I|app|4d784e26] Started POST "/api/config_reports" for 10.x.x.x at 2020-06-24 16:42:26 +0000
2020-06-24T16:42:26 [I|app|4d784e26] Processing by Api::V2::ConfigReportsController#create as JSON
2020-06-24T16:42:26 [I|app|4d784e26]   Parameters: {"config_report"=>"[FILTERED]", "apiv"=>"v2"}
2020-06-24T16:42:26 [D|app|4d784e26] Client sent certificate with subject 'wevv2tinf03.prod.examples.com' and subject alt names '["wevv2tinf03.prod.examples.com"]'
2020-06-24T16:42:26 [D|app|4d784e26] CN and SANs were extracted from a client certificate.
2020-06-24T16:42:26 [D|app|4d784e26] Verifying request from ["wevv2tinf03.prod.examples.com"] against ["wevv2tinf03.prod.examples.com"]
2020-06-24T16:42:26 [D|app|4d784e26] Processing report: {"host"=>"wevv2tinf03.prod.examples.com", "reported_at"=>"2020-06-24 16:42:21 UTC", "status"=>{"applied"=>0, "restarted"=>0, "failed"=>0, "failed_restarts"=>0, "skipped"=>0, "pending"=>0}, "metrics"=>{"resources"=>{"changed"=>0, "corrective_change"=>0, "failed"=>0, "failed_to_restart"=>0, "out_of_sync"=>0, "restarted"=>0, "scheduled"=>0, "skipped"=>0, "total"=>1}, "time"=>{"catalog_application"=>0.32810023706406355, "config_retrieval"=>2.1309823440387845, "convert_catalog"=>0.0840685679577291, "fact_generation"=>0.32315651001408696, "filebucket"=>0.000170498, "node_retrieval"=>1.198496067430824, "plugin_sync"=>0.2977577429264784, "total"=>4.582903317, "transaction_evaluation"=>0.009153744671493769}, "changes"=>{"total"=>0}, "events"=>{"failure"=>0, "success"=>0, "total"=>0}}, "logs"=>[{"log"=>{"sources"=>{"source"=>"Puppet"}, "messages"=>{"message"=>"Applied catalog in 0.33 seconds"}, "level"=>"notice"}}]}
2020-06-24T16:42:26 [I|app|4d784e26] Scanning report with: Foreman::PuppetReportScanner
2020-06-24T16:42:26 [D|app|4d784e26] Changes after scanning: {"host_id"=>[nil, 9], "reported_at"=>[nil, Wed, 24 Jun 2020 16:42:21 UTC +00:00], "status"=>[nil, 0], "metrics"=>[nil, "---\nresources: !ruby/hash:ActiveSupport::HashWithIndifferentAccess\n  changed: 0\n  corrective_change: 0\n  failed: 0\n  failed_to_restart: 0\n  out_of_sync: 0\n  restarted: 0\n  scheduled: 0\n  skipped: 0\n  total: 1\ntime: !ruby/hash:ActiveSupport::HashWithIndifferentAccess\n  catalog_application: 0.32810023706406355\n  config_retrieval: 2.1309823440387845\n  convert_catalog: 0.0840685679577291\n  fact_generation: 0.32315651001408696\n  filebucket: 0.000170498\n  node_retrieval: 1.198496067430824\n  plugin_sync: 0.2977577429264784\n  total: 4.582903317\n  transaction_evaluation: 0.009153744671493769\nchanges: !ruby/hash:ActiveSupport::HashWithIndifferentAccess\n  total: 0\nevents: !ruby/hash:ActiveSupport::HashWithIndifferentAccess\n  failure: 0\n  success: 0\n  total: 0\n"], "origin"=>[nil, "Puppet"]}
2020-06-24T16:42:26 [I|app|4d784e26] Imported report for wevv2tinf03.prod.examples.com in 183.0 ms, status refreshed in 55.8 ms
2020-06-24T16:42:26 [I|app|4d784e26]   Rendering api/v2/config_reports/create.json.rabl
2020-06-24T16:42:26 [I|app|4d784e26]   Rendered api/v2/config_reports/create.json.rabl (19.6ms)
2020-06-24T16:42:26 [D|app|4d784e26] Body: {"metrics":{"resources":{"changed":0,"corrective_change":0,"failed":0,"failed_to_restart":0,"out_of_sync":0,"restarted":0,"scheduled":0,"skipped":0,"total":1},"time":{"catalog_application":0.32810023706406355,"config_retrieval":2.1309823440387845,"convert_catalog":0.0840685679577291,"fact_generation":0.32315651001408696,"filebucket":0.000170498,"node_retrieval":1.198496067430824,"plugin_sync":0.2977577429264784,"total":4.582903317,"transaction_evaluation":0.009153744671493769},"changes":{"total":0},"events":{"failure":0,"success":0,"total":0}},"created_at":"2020-06-24 16:42:26 UTC","updated_at":"2020-06-24 16:42:26 UTC","id":694,"host_id":9,"host_name":"wevv2tinf03.prod.examples.com","reported_at":"2020-06-24 16:42:21 UTC","status":{"applied":0,"restarted":0,"failed":0,"failed_restarts":0,"skipped":0,"pending":0},"logs":[{"level":"notice","source":{"source":"Puppet"},"message":{"message":"Applied catalog in 0.33 seconds"}}],"summary":"Success"}
2020-06-24T16:42:26 [I|app|4d784e26] Completed 201 Created in 299ms (Views: 30.2ms | ActiveRecord: 78.3ms)
2020-06-24T16:42:30 [I|app|100b23f8] Started GET "/notification_recipients" for 10.x.x.x at 2020-06-24 16:42:30 +0000
2020-06-24T16:42:30 [I|app|100b23f8] Processing by NotificationRecipientsController#index as JSON
2020-06-24T16:42:30 [D|tax|100b23f8] Current location set to none
2020-06-24T16:42:30 [D|tax|100b23f8] Current organization set to BOS
2020-06-24T16:42:30 [D|not|100b23f8] Cache Hit: notification, reading cache for notification-4
2020-06-24T16:42:30 [D|app|100b23f8] Body: {"notifications":[]}
2020-06-24T16:42:30 [I|app|100b23f8] Completed 200 OK in 15ms (Views: 0.3ms | ActiveRecord: 2.0ms)
2020-06-24T16:42:40 [I|app|729880dd] Started GET "/notification_recipients" for 10.x.x.x at 2020-06-24 16:42:40 +0000
2020-06-24T16:42:40 [I|app|729880dd] Processing by NotificationRecipientsController#index as JSON
2020-06-24T16:42:40 [D|tax|729880dd] Current location set to none
2020-06-24T16:42:40 [D|tax|729880dd] Current organization set to BOS
2020-06-24T16:42:40 [D|not|729880dd] Cache Hit: notification, reading cache for notification-4
2020-06-24T16:42:40 [D|app|729880dd] Body: {"notifications":[]}
2020-06-24T16:42:40 [I|app|729880dd] Completed 200 OK in 15ms (Views: 0.2ms | ActiveRecord: 2.0ms)