Project

General

Profile

Bug #13573 » foreman_production.log

foreman server side log file - Dirk Mayer, 02/10/2016 11:08 AM

 
2016-02-10 18:23:09 [app] [I] Connecting to database specified by database.yml
2016-02-10 18:23:10 [app] [I] Finished registering 4 hooks for Host::Managed#create
2016-02-10 18:23:13 [app] [W] ForemanConfigMonitor: skipping engine hook (uninitialized constant ForemanConfigMonitor::HostsHelperExtensions)
2016-02-10 18:23:19 [app] [I]
|
| Started GET "/" for 127.0.0.1 at 2016-02-10 18:23:19 +0100
2016-02-10 18:23:19 [app] [I]
|
| Started GET "/" for 127.0.0.1 at 2016-02-10 18:23:19 +0100
2016-02-10 18:23:19 [app] [I]
|
| Started GET "/node/z440dm.localdomain?format=yml" for 172.17.31.1 at 2016-02-10 18:23:19 +0100
2016-02-10 18:23:20 [app] [I] Processing by DashboardController#index as HTML
2016-02-10 18:23:20 [app] [I] Redirected to https://z440dm.localdomain/
2016-02-10 18:23:20 [app] [I] Filter chain halted as #<Proc:0x000000052d8d68@/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/force_ssl.rb:28> rendered or redirected
2016-02-10 18:23:20 [app] [I] Completed 301 Moved Permanently in 12ms (ActiveRecord: 0.0ms)
2016-02-10 18:23:20 [app] [I] Processing by DashboardController#index as HTML
2016-02-10 18:23:20 [app] [I] Redirected to https://z440dm.localdomain/
2016-02-10 18:23:20 [app] [I] Filter chain halted as #<Proc:0x000000052d8d68@/opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/force_ssl.rb:28> rendered or redirected
2016-02-10 18:23:20 [app] [I] Completed 301 Moved Permanently in 8ms (ActiveRecord: 0.0ms)
2016-02-10 18:23:20 [app] [I] Processing by HostsController#externalNodes as YML
2016-02-10 18:23:20 [app] [I] Parameters: {"name"=>"z440dm.localdomain"}
2016-02-10 18:23:21 [app] [I] Rendered text template (0.0ms)
2016-02-10 18:23:21 [app] [I] Completed 200 OK in 1167ms (Views: 205.9ms | ActiveRecord: 172.5ms)
2016-02-10 18:23:22 [app] [I]
|
| Started GET "/" for 127.0.0.1 at 2016-02-10 18:23:22 +0100
2016-02-10 18:23:22 [app] [I] Processing by DashboardController#index as HTML
2016-02-10 18:23:22 [app] [I] Redirected to https://z440dm.localdomain/users/login
2016-02-10 18:23:22 [app] [I] Filter chain halted as :require_login rendered or redirected
2016-02-10 18:23:22 [app] [I] Completed 302 Found in 100ms (ActiveRecord: 0.3ms)
2016-02-10 18:23:27 [app] [I]
|
| Started POST "/api/hosts/facts" for 172.17.31.1 at 2016-02-10 18:23:27 +0100
2016-02-10 18:23:27 [app] [I] Processing by Api::V2::HostsController#facts as JSON
2016-02-10 18:23:27 [app] [I] Parameters: {"facts"=>"[FILTERED]", "name"=>"z440dm.localdomain", "certname"=>"z440dm.localdomain", "apiv"=>"v2", :host=>{"name"=>"z440dm.localdomain", "certname"=>"z440dm.localdomain"}}
2016-02-10 18:23:27 [app] [I] Import facts for 'z440dm.localdomain' completed. Added: 0, Updated: 9, Deleted 0 facts
2016-02-10 18:23:27 [app] [I] Completed 201 Created in 444ms (Views: 6.4ms | ActiveRecord: 0.0ms)
2016-02-10 18:23:30 [app] [I]
|
| Started GET "/node/z440dm.localdomain?format=yml" for 172.17.31.1 at 2016-02-10 18:23:30 +0100
2016-02-10 18:23:30 [app] [I] Processing by HostsController#externalNodes as YML
2016-02-10 18:23:30 [app] [I] Parameters: {"name"=>"z440dm.localdomain"}
2016-02-10 18:23:30 [app] [I] Rendered text template (0.0ms)
2016-02-10 18:23:30 [app] [I] Completed 200 OK in 181ms (Views: 0.4ms | ActiveRecord: 6.7ms)
2016-02-10 18:23:34 [app] [I]
|
| Started GET "/api/v2/fact_values?search=fact%20=%20ipaddress%20or%20fact%20=%20ipaddress_application_highway&per_page=20" for 172.17.31.1 at 2016-02-10 18:23:34 +0100
2016-02-10 18:23:34 [app] [I] Processing by Api::V2::FactValuesController#index as JSON
2016-02-10 18:23:34 [app] [I] Parameters: {"search"=>"fact = ipaddress or fact = ipaddress_application_highway", "per_page"=>"20", "apiv"=>"v2", "fact_value"=>{}}
2016-02-10 18:23:34 [app] [I] Authorized user puppet_provider(puppet provider)
2016-02-10 18:23:35 [app] [I] Rendered api/v2/fact_values/index.json.rabl within api/v2/layouts/index_layout (0.8ms)
2016-02-10 18:23:35 [app] [I] Completed 200 OK in 912ms (Views: 257.3ms | ActiveRecord: 470.3ms)
2016-02-10 18:23:38 [app] [I]
|
| Started GET "/api/v2/hosts?search=hostgroup%20=%20Hypervisor%20or%20hostgroup%20=%20DeploymentServer&per_page=20" for 172.17.31.1 at 2016-02-10 18:23:38 +0100
2016-02-10 18:23:38 [app] [I] Processing by Api::V2::HostsController#index as JSON
2016-02-10 18:23:38 [app] [I] Parameters: {"search"=>"hostgroup = Hypervisor or hostgroup = DeploymentServer", "per_page"=>"20", "apiv"=>"v2", :host=>{}}
2016-02-10 18:23:38 [app] [I] Authorized user puppet_provider(puppet provider)
2016-02-10 18:23:39 [app] [I] Rendered api/v2/hosts/index.json.rabl within api/v2/layouts/index_layout (614.4ms)
2016-02-10 18:23:39 [app] [I] Completed 200 OK in 1086ms (Views: 260.8ms | ActiveRecord: 766.5ms)
2016-02-10 18:23:39 [app] [I]
|
| Started GET "/api/v2/fact_values?search=(fact%20=%20root_sshkey_pub%20or%20fact%20=%20fqdn)%20and%20(%20%20host=opnode1adm.localdomain%20or%20host=z440dm.localdomain%20)&per_page=20" for 172.17.31.1 at 2016-02-10 18:23:39 +0100
2016-02-10 18:23:39 [app] [I] Processing by Api::V2::FactValuesController#index as JSON
2016-02-10 18:23:39 [app] [I] Parameters: {"search"=>"(fact = root_sshkey_pub or fact = fqdn) and ( host=opnode1adm.localdomain or host=z440dm.localdomain )", "per_page"=>"20", "apiv"=>"v2", "fact_value"=>{}}
2016-02-10 18:23:39 [app] [I] Authorized user puppet_provider(puppet provider)
2016-02-10 18:23:39 [app] [I] Rendered api/v2/fact_values/index.json.rabl within api/v2/layouts/index_layout (0.3ms)
2016-02-10 18:23:39 [app] [I] Completed 200 OK in 49ms (Views: 2.6ms | ActiveRecord: 28.6ms)
2016-02-10 18:23:40 [app] [I]
|
| Started GET "/api/v2/hosts?search=hostgroup%20=%20Hypervisor%20or%20hostgroup%20=%20DeploymentServer&per_page=20" for 172.17.31.1 at 2016-02-10 18:23:40 +0100
2016-02-10 18:23:40 [app] [I] Processing by Api::V2::HostsController#index as JSON
2016-02-10 18:23:40 [app] [I] Parameters: {"search"=>"hostgroup = Hypervisor or hostgroup = DeploymentServer", "per_page"=>"20", "apiv"=>"v2", :host=>{}}
2016-02-10 18:23:40 [app] [I] Authorized user puppet_provider(puppet provider)
2016-02-10 18:23:40 [app] [I] Rendered api/v2/hosts/index.json.rabl within api/v2/layouts/index_layout (23.0ms)
2016-02-10 18:23:40 [app] [I] Completed 200 OK in 74ms (Views: 23.8ms | ActiveRecord: 32.0ms)
2016-02-10 18:23:40 [app] [I]
|
| Started GET "/api/v2/fact_values?search=(fact%20=%20sshrsakey%20or%20fact%20=%20fqdn)%20and%20(%20%20host=opnode1adm.localdomain%20or%20host=z440dm.localdomain%20)&per_page=20" for 172.17.31.1 at 2016-02-10 18:23:40 +0100
2016-02-10 18:23:40 [app] [I] Processing by Api::V2::FactValuesController#index as JSON
2016-02-10 18:23:40 [app] [I] Parameters: {"search"=>"(fact = sshrsakey or fact = fqdn) and ( host=opnode1adm.localdomain or host=z440dm.localdomain )", "per_page"=>"20", "apiv"=>"v2", "fact_value"=>{}}
2016-02-10 18:23:40 [app] [I] Authorized user puppet_provider(puppet provider)
2016-02-10 18:23:40 [app] [I] Rendered api/v2/fact_values/index.json.rabl within api/v2/layouts/index_layout (0.3ms)
2016-02-10 18:23:40 [app] [I] Completed 200 OK in 87ms (Views: 2.6ms | ActiveRecord: 66.6ms)
2016-02-10 18:23:41 [app] [I]
|
| Started GET "/api/v2/hosts?search=hostgroup%20=%20ApplicationNode%20or%20hostgroup%20=%20TechnologyNode&per_page=20" for 172.17.31.1 at 2016-02-10 18:23:41 +0100
2016-02-10 18:23:41 [app] [I] Processing by Api::V2::HostsController#index as JSON
2016-02-10 18:23:41 [app] [I] Parameters: {"search"=>"hostgroup = ApplicationNode or hostgroup = TechnologyNode", "per_page"=>"20", "apiv"=>"v2", :host=>{}}
2016-02-10 18:23:41 [app] [I] Authorized user puppet_provider(puppet provider)
2016-02-10 18:23:41 [app] [I] Rendered api/v2/hosts/index.json.rabl within api/v2/layouts/index_layout (82.6ms)
2016-02-10 18:23:41 [app] [I] Completed 200 OK in 286ms (Views: 31.5ms | ActiveRecord: 138.7ms)
2016-02-10 18:23:41 [app] [I]
|
| Started GET "/api/v2/fact_values?search=(fact%20=%20cygwin_ecdsakey_pub%20or%20fact%20=%20fqdn)%20and%20(%20%20host=r80dm.localdomain%20or%20host=tecsrvdm.localdomain%20)&per_page=20" for 172.17.31.1 at 2016-02-10 18:23:41 +0100
2016-02-10 18:23:41 [app] [I] Processing by Api::V2::FactValuesController#index as JSON
2016-02-10 18:23:41 [app] [I] Parameters: {"search"=>"(fact = cygwin_ecdsakey_pub or fact = fqdn) and ( host=r80dm.localdomain or host=tecsrvdm.localdomain )", "per_page"=>"20", "apiv"=>"v2", "fact_value"=>{}}
2016-02-10 18:23:41 [app] [I] Authorized user puppet_provider(puppet provider)
2016-02-10 18:23:41 [app] [I] Rendered api/v2/fact_values/index.json.rabl within api/v2/layouts/index_layout (0.3ms)
2016-02-10 18:23:41 [app] [I] Completed 200 OK in 71ms (Views: 2.6ms | ActiveRecord: 51.2ms)
2016-02-10 18:24:18 [app] [I]
|
| Started GET "/api/compute_resources?search=name+%3D+%22z440dm%22" for 172.17.31.1 at 2016-02-10 18:24:18 +0100
2016-02-10 18:24:18 [app] [I] Processing by Api::V2::ComputeResourcesController#index as JSON
2016-02-10 18:24:18 [app] [I] Parameters: {"search"=>"name = \"z440dm\"", "apiv"=>"v2", "compute_resource"=>{}}
2016-02-10 18:24:18 [app] [I] Authorized user admin(Admin User)
2016-02-10 18:24:18 [app] [I] Rendered api/v2/compute_resources/index.json.rabl within api/v2/layouts/index_layout (128.5ms)
2016-02-10 18:24:18 [app] [I] Completed 200 OK in 334ms (Views: 296.1ms | ActiveRecord: 28.7ms)
2016-02-10 18:24:18 [app] [I]
|
| Started GET "/api/compute_resources/1/images?page=1&per_page=1000" for 172.17.31.1 at 2016-02-10 18:24:18 +0100
2016-02-10 18:24:18 [app] [I] Processing by Api::V2::ImagesController#index as JSON
2016-02-10 18:24:18 [app] [I] Parameters: {"page"=>"1", "per_page"=>"1000", "apiv"=>"v2", "compute_resource_id"=>"1", "image"=>{}}
2016-02-10 18:24:18 [app] [I] Authorized user admin(Admin User)
2016-02-10 18:24:18 [app] [I] Rendered api/v2/images/index.json.rabl within api/v2/layouts/index_layout (48.6ms)
2016-02-10 18:24:18 [app] [I] Completed 200 OK in 147ms (Views: 95.5ms | ActiveRecord: 41.7ms)
2016-02-10 18:24:20 [app] [I]
|
| Started GET "/api/compute_resources?search=name+%3D+%22z440dm%22" for 172.17.31.1 at 2016-02-10 18:24:20 +0100
2016-02-10 18:24:20 [app] [I] Processing by Api::V2::ComputeResourcesController#index as JSON
2016-02-10 18:24:20 [app] [I] Parameters: {"search"=>"name = \"z440dm\"", "apiv"=>"v2", "compute_resource"=>{}}
2016-02-10 18:24:20 [app] [I] Authorized user admin(Admin User)
2016-02-10 18:24:20 [app] [I] Rendered api/v2/compute_resources/index.json.rabl within api/v2/layouts/index_layout (2.2ms)
2016-02-10 18:24:20 [app] [I] Completed 200 OK in 155ms (Views: 80.4ms | ActiveRecord: 65.1ms)
2016-02-10 18:24:20 [app] [I]
|
| Started GET "/api/compute_resources/1/images?page=1&per_page=1000" for 172.17.31.1 at 2016-02-10 18:24:20 +0100
2016-02-10 18:24:20 [app] [I] Processing by Api::V2::ImagesController#index as JSON
2016-02-10 18:24:20 [app] [I] Parameters: {"page"=>"1", "per_page"=>"1000", "apiv"=>"v2", "compute_resource_id"=>"1", "image"=>{}}
2016-02-10 18:24:20 [app] [I] Authorized user admin(Admin User)
2016-02-10 18:24:20 [app] [I] Rendered api/v2/images/index.json.rabl within api/v2/layouts/index_layout (5.9ms)
2016-02-10 18:24:20 [app] [I] Completed 200 OK in 107ms (Views: 7.2ms | ActiveRecord: 89.3ms)
2016-02-10 18:24:33 [app] [I]
|
| Started GET "/api/compute_resources?page=1&per_page=1000" for 172.17.31.1 at 2016-02-10 18:24:33 +0100
2016-02-10 18:24:33 [app] [I] Processing by Api::V2::ComputeResourcesController#index as JSON
2016-02-10 18:24:33 [app] [I] Parameters: {"page"=>"1", "per_page"=>"1000", "apiv"=>"v2", "compute_resource"=>{}}
2016-02-10 18:24:33 [app] [I] Authorized user admin(Admin User)
2016-02-10 18:24:33 [app] [I] Rendered api/v2/compute_resources/index.json.rabl within api/v2/layouts/index_layout (3.0ms)
2016-02-10 18:24:33 [app] [I] Completed 200 OK in 78ms (Views: 4.0ms | ActiveRecord: 65.1ms)
2016-02-10 18:24:34 [app] [I]
|
| Started GET "/api/hosts/" for 127.0.0.1 at 2016-02-10 18:24:34 +0100
2016-02-10 18:24:34 [app] [I] Processing by Api::V2::HostsController#index as JSON
2016-02-10 18:24:34 [app] [I] Parameters: {"search"=>"os = windows", "apiv"=>"v2", :host=>{}}
2016-02-10 18:24:34 [app] [I] Authorized user puppet_provider(puppet provider)
2016-02-10 18:24:34 [app] [I] Rendered api/v2/hosts/index.json.rabl within api/v2/layouts/index_layout (22.8ms)
2016-02-10 18:24:34 [app] [I] Completed 200 OK in 87ms (Views: 23.9ms | ActiveRecord: 44.1ms)
2016-02-10 18:24:34 [app] [I]
|
| Started GET "/api/hosts/" for 127.0.0.1 at 2016-02-10 18:24:34 +0100
2016-02-10 18:24:34 [app] [I] Processing by Api::V2::HostsController#index as JSON
2016-02-10 18:24:34 [app] [I] Parameters: {"search"=>"os = windows", "apiv"=>"v2", :host=>{}}
2016-02-10 18:24:34 [app] [I] Authorized user puppet_provider(puppet provider)
2016-02-10 18:24:34 [app] [I] Rendered api/v2/hosts/index.json.rabl within api/v2/layouts/index_layout (22.8ms)
2016-02-10 18:24:34 [app] [I] Completed 200 OK in 118ms (Views: 24.0ms | ActiveRecord: 75.4ms)
2016-02-10 18:24:39 [app] [I]
|
| Started POST "/api/reports" for 172.17.31.1 at 2016-02-10 18:24:39 +0100
2016-02-10 18:24:39 [app] [I] Processing by Api::V2::ReportsController#create as JSON
2016-02-10 18:24:39 [app] [I] Parameters: {"report"=>"[FILTERED]", "apiv"=>"v2"}
2016-02-10 18:24:39 [app] [I] processing report for z440dm.localdomain
2016-02-10 18:24:40 [app] [I] Imported report for z440dm.localdomain in 0.24 seconds
2016-02-10 18:24:40 [app] [I] Rendered api/v2/reports/create.json.rabl (209.5ms)
2016-02-10 18:24:40 [app] [I] Completed 201 Created in 539ms (Views: 128.9ms | ActiveRecord: 367.8ms)
2016-02-10 18:25:16 [app] [I]
|
| Started POST "/api/hosts" for 172.17.31.1 at 2016-02-10 18:25:16 +0100
2016-02-10 18:25:16 [app] [I] Processing by Api::V2::HostsController#create as JSON
2016-02-10 18:25:16 [app] [I] Parameters: {"host"=>{"name"=>"host01.localdomain", "environment_id"=>1, "architecture_id"=>1, "domain_id"=>1, "puppet_proxy_id"=>1, "operatingsystem_id"=>2, "subnet_id"=>1, "compute_resource_id"=>1, "hostgroup_id"=>4, "puppet_ca_proxy_id"=>1, "build"=>true, "enabled"=>true, "provision_method"=>"image", "managed"=>true, "compute_attributes"=>{"cpus"=>"4", "memory"=>"8589934592", "start"=>"1", "image_id"=>"/var/lib/libvirt/images/win2k8-tsnode.qcow2", "volumes_attributes"=>{"0"=>{"pool_name"=>"virt-images", "capacity"=>"600G", "format_type"=>"qcow2", "allocation"=>"0G"}}}, "overwrite"=>true, "host_parameters_attributes"=>{}, "interfaces_attributes"=>{"0"=>{"primary"=>"true", "provision"=>"true", "ip"=>"172.17.31.15", "compute_attributes"=>{"type"=>"bridge", "bridge"=>"br0", "model"=>"virtio"}}, "1"=>{"compute_attributes"=>{"type"=>"bridge", "bridge"=>"br1", "model"=>"virtio"}}, "2"=>{"\"compute_type"=>"bridge", "compute_attributes"=>{"bridge"=>"br2", "model"=>"virtio"}}}}, "apiv"=>"v2"}
2016-02-10 18:25:16 [app] [I] Authorized user admin(Admin User)
2016-02-10 18:25:16 [app] [W] Action failed
| Net::Validations::Error: Invalid MAC
| /usr/share/foreman/lib/net/validations.rb:40:in `validate_mac'
| /usr/share/foreman/lib/net/dhcp/record.rb:7:in `initialize'
| /usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:20:in `new'
| /usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:20:in `dhcp_record'
| /usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:158:in `queue_remove_dhcp_conflicts'
| /usr/share/foreman/app/models/concerns/orchestration/dhcp.rb:111:in `queue_dhcp'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:468:in `_run__799164169179065892__validation__791363530628322923__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_validation_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activemodel-3.2.8/lib/active_model/validations/callbacks.rb:53:in `run_validations!'
| /opt/rh/ruby193/root/usr/share/gems/gems/activemodel-3.2.8/lib/active_model/validations.rb:194:in `valid?'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/validations.rb:69:in `valid?'
| /usr/share/foreman/app/models/concerns/orchestration.rb:75:in `valid?'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/autosave_association.rb:300:in `association_valid?'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/autosave_association.rb:289:in `block in validate_collection_association'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/autosave_association.rb:289:in `each'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/autosave_association.rb:289:in `validate_collection_association'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/autosave_association.rb:217:in `block in add_autosave_association_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/autosave_association.rb:161:in `instance_eval'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/autosave_association.rb:161:in `block in define_non_cyclic_method'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:561:in `_run__2750214975464474580__validate__791363530628322923__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_validate_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activemodel-3.2.8/lib/active_model/validations.rb:227:in `run_validations!'
| /opt/rh/ruby193/root/usr/share/gems/gems/activemodel-3.2.8/lib/active_model/validations/callbacks.rb:53:in `block in run_validations!'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:480:in `_run__2750214975464474580__validation__791363530628322923__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_validation_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activemodel-3.2.8/lib/active_model/validations/callbacks.rb:53:in `run_validations!'
| /opt/rh/ruby193/root/usr/share/gems/gems/activemodel-3.2.8/lib/active_model/validations.rb:194:in `valid?'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/validations.rb:69:in `valid?'
| /usr/share/foreman/app/models/concerns/orchestration.rb:75:in `valid?'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/validations.rb:77:in `perform_validations'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/validations.rb:50:in `save'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/attribute_methods/dirty.rb:22:in `save'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/transactions.rb:241:in `block (2 levels) in save'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/transactions.rb:295:in `block in with_transaction_returning_status'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/database_statements.rb:192:in `transaction'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/transactions.rb:208:in `transaction'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/transactions.rb:293:in `with_transaction_returning_status'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/transactions.rb:241:in `block in save'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/transactions.rb:252:in `rollback_active_record_state!'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/transactions.rb:240:in `save'
| /usr/share/foreman/app/models/concerns/foreman/sti.rb:29:in `save_with_type'
| /usr/share/foreman/app/controllers/api/v2/hosts_controller.rb:96:in `create'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:167:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rendering.rb:10:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:18:in `block in process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:584:in `block (5 levels) in _run__457338306763575945__process_action__4257871783658253370__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7741'
| /usr/share/foreman/app/controllers/api/v2/base_controller.rb:151:in `disable_json_root'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7741'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:550:in `block (4 levels) in _run__457338306763575945__process_action__4257871783658253370__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7740'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:326:in `around'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:310:in `_callback_around_2658'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7740'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:527:in `block (3 levels) in _run__457338306763575945__process_action__4257871783658253370__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7739'
| /usr/share/foreman/app/controllers/concerns/application_shared.rb:13:in `set_timezone'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7739'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:526:in `block (2 levels) in _run__457338306763575945__process_action__4257871783658253370__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7738'
| /usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7738'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:448:in `block in _run__457338306763575945__process_action__4257871783658253370__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7737'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:326:in `around'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:310:in `_callback_around_13'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7737'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:414:in `_run__457338306763575945__process_action__4257871783658253370__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/callbacks.rb:17:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rescue.rb:29:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `block in instrument'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/notifications.rb:123:in `instrument'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/base.rb:121:in `process'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/abstract_controller/rendering.rb:45:in `process'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:203:in `dispatch'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_controller/metal.rb:246:in `block in action'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:36:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/mapper.rb:42:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
| /opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
| /opt/rh/ruby193/root/usr/share/gems/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/routing/route_set.rb:600:in `call'
| /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.2.6/lib/apipie/static_dispatcher.rb:65:in `call'
| /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.2.6/lib/apipie/extractor/recorder.rb:97:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'
| /opt/theforeman/tfm/root/usr/share/gems/gems/apipie-rails-0.2.6/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/etag.rb:23:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/conditionalget.rb:35:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/head.rb:14:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
| /usr/share/foreman/lib/middleware/catch_json_parse_errors.rb:9:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/flash.rb:242:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:205:in `context'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/session/abstract/id.rb:200:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/cookies.rb:339:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/query_cache.rb:64:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/activerecord-3.2.8/lib/active_record/connection_adapters/abstract/connection_pool.rb:473:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `_run__794702485088090864__call__791363530628322923__callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:405:in `__run_callback'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/callbacks.rb:81:in `run_callbacks'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:26:in `call_app'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/rack/logger.rb:16:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/request_id.rb:22:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/methodoverride.rb:21:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/runtime.rb:17:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/activesupport-3.2.8/lib/active_support/cache/strategy/local_cache.rb:72:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/lock.rb:15:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/actionpack-3.2.8/lib/action_dispatch/middleware/static.rb:62:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/engine.rb:479:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/application.rb:223:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/railties-3.2.8/lib/rails/railtie/configurable.rb:30:in `method_missing'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/builder.rb:134:in `call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/urlmap.rb:64:in `block in call'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/urlmap.rb:49:in `each'
| /opt/rh/ruby193/root/usr/share/gems/gems/rack-1.4.1/lib/rack/urlmap.rb:49:in `call'
| /usr/share/gems/gems/passenger-4.0.18/lib/phusion_passenger/rack/thread_handler_extension.rb:77:in `process_request'
| /usr/share/gems/gems/passenger-4.0.18/lib/phusion_passenger/request_handler/thread_handler.rb:140:in `accept_and_process_next_request'
| /usr/share/gems/gems/passenger-4.0.18/lib/phusion_passenger/request_handler/thread_handler.rb:108:in `main_loop'
| /usr/share/gems/gems/passenger-4.0.18/lib/phusion_passenger/request_handler.rb:441:in `block (3 levels) in start_threads'
| /opt/theforeman/tfm/root/usr/share/gems/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:in `call'
| /opt/theforeman/tfm/root/usr/share/gems/gems/logging-1.8.2/lib/logging/diagnostic_context.rb:323:in `block in create_with_logging_context'
2016-02-10 18:25:16 [app] [I] Rendered api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout (0.6ms)
2016-02-10 18:25:16 [app] [I] Completed 500 Internal Server Error in 352ms (Views: 216.8ms | ActiveRecord: 95.5ms)
(1-1/4)