Signed puppet node not showing up in all hosts menu

Support
,
1

Hello guys,

Can anybody help me finding out why one of my signed puppet node just not showing up in Foreman’s all hosts tab on dashboard?

The host is listed under smart proxy certificates as valid, but it’s not appearing on the dashboard list.

Puppet client runs without issues on the puppet node. I also can check for example the facts sent to puppetserver, and all the facts are registered.

Already have some ubuntu, and debian nodes in foreman listed, and working perfectly well.
The node I wanna add to foreman dashboard has network binding, and many loopback interfaces, but otherwise it is identical to my other nodes. So I just don’t get what the problem is.

puppet client 6.15.0 / Debian 10
puppetserver version: 6.11.0 / Ubuntu 18.04
foreman smart proxy version 1.24.2

Help would be much appreciated!
Thank you in advance!

1 Like
2

Hi and welcome to the forum :slight_smile:

Since this is the cause for most cases with this description:
Have you checked if the host can be found when switching location and organization to “Any/Any” (using the dropdown menu in the top bar)?
When imported via Puppet facts, hosts usually get added without any location and organization assigned to them unless you explicitly set everything up for that to work.
If you can find the host in the any context, you can change it’s location and organization from the “All hosts” page by selecting it’s checkbox and, from the “Select Action” Menu, running “Assign Organization” and “Assign Location” respectively.

If you want new nodes to be assigned automatically, there should be an option under Administer → Settings to set a global default (Puppet → Default Organization/Location), or you can setup facts before the first Puppet run that hint the desired values to Foreman. Those can also be changed in the settings (Puppet → location/organization fact), the defaults are foreman_location and foreman_organization.

Regards

1 Like
3

Hi Areyus!

Thanks for the quick and helpful feedback!

Actually all the defaults are set properly in foreman settings as far as I can see.
As you proposed in ANY/ANY view the host is really available in the the host list which is good, but the problem is that all of the values are empty in the list like Operating system / Model.

As I checked when I try to run the following script
/etc/puppetlabs/puppet/node.rb CERTNAME, than I get the following error:

During the fact upload the server responded with: 500 Internal Server Error. Error is ignored and the execution continues.
{
“error”: {“message”:“Validation failed: Description has already been taken, Title has already been taken”}
}

parameters:
foreman_subnets:
foreman_interfaces:

  • ip:
    ip6:
    mac:
    name: hostname.domain.com
    attrs: {}
    virtual: true
    link: true
    identifier:
    managed: true
    primary: true
    provision: true
    subnet:
    subnet6:
    tag: ‘’
    attached_to: ‘’
    type: Bond
    attached_devices: ‘’
    mode: balance-rr
    bond_options: ‘’
    root_pw:
    foreman_config_groups:
    puppetmaster: ‘’
    classes:

So most of the values are empty.
Maybe you’ve got a hint what’s wrong in this case?

Thanks for having a look at it!

4

From that, I would assume there was an error while importing the host.
Do you have /var/log/foreman/production.log from the time of the import still around? If so, could you check that for any errors in fact import?

5

Hi,

Thanks for coming back to me!

You know it’s strange that puppet has all the facts imported under this folder, but somehow foreman just cannot import that fact information.
/opt/puppetlabs/server/data/puppetserver/yaml/facts/

I don’t know if this is what you need:

2020-05-11T06:38:27 [I|app|e68258dd] Started POST “/api/hosts/facts” for 10.22.1.3 at 2020-05-11 06:38:27 +0200
2020-05-11T06:38:27 [I|app|e68258dd] Processing by Api::V2::HostsController#facts as JSON
2020-05-11T06:38:27 [I|app|e68258dd] Parameters: {“facts”=>"[FILTERED]", “name”=>“apache-test1.organization.com”, “certname”=>“apache-test1.organization.com”, “apiv”=>“v2”, “host”=>{“certname”=>“apache-test1.organization.com”, “name”=>“apache-test1.organization.com”}}
2020-05-11T06:38:27 [I|app|e68258dd] Import facts for ‘apache-test1.organization.com’ completed. Added: 0, Updated: 0, Deleted 0 facts
2020-05-11T06:38:27 [W|app|e68258dd] Action failed
2020-05-11T06:38:27 [I|app|e68258dd] Rendering api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout
2020-05-11T06:38:27 [I|app|e68258dd] Rendered api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout (0.4ms)
2020-05-11T06:38:27 [I|app|e68258dd] Completed 500 Internal Server Error in 567ms (Views: 1.7ms | ActiveRecord: 114.9ms)
2020-05-11T06:38:27 [I|app|d192127b] Started GET “/node/apache-test1.organization.com?format=yml” for 10.22.1.3 at 2020-05-11 06:38:27 +0200
2020-05-11T06:38:27 [I|app|d192127b] Processing by HostsController#externalNodes as YML
2020-05-11T06:38:27 [I|app|d192127b] Parameters: {“name”=>“apache-test1.organization.com”}
2020-05-11T06:38:27 [I|app|d192127b] Rendering text template
2020-05-11T06:38:27 [I|app|d192127b] Rendered text template (0.0ms)
2020-05-11T06:38:27 [I|app|d192127b] Completed 200 OK in 25ms (Views: 0.3ms | ActiveRecord: 3.9ms)
2020-05-11T06:38:30 [I|app|61c4291a] Started POST “/api/hosts/facts” for 10.22.1.3 at 2020-05-11 06:38:30 +0200
2020-05-11T06:38:30 [I|app|61c4291a] Processing by Api::V2::HostsController#facts as JSON
2020-05-11T06:38:30 [I|app|61c4291a] Parameters: {“facts”=>"[FILTERED]", “name”=>“apache-test1.organization.com”, “certname”=>“apache-test1.organization.com”, “apiv”=>“v2”, “host”=>{“certname”=>“apache-test1.organization.com”, “name”=>“apache-test1.organization.com”}}
2020-05-11T06:38:31 [I|app|61c4291a] Import facts for ‘apache-test1.organization.com’ completed. Added: 0, Updated: 15, Deleted 0 facts
2020-05-11T06:38:31 [W|app|61c4291a] Action failed
2020-05-11T06:38:31 [I|app|61c4291a] Rendering api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout
2020-05-11T06:38:31 [I|app|61c4291a] Rendered api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout (0.2ms)
2020-05-11T06:38:31 [I|app|61c4291a] Completed 500 Internal Server Error in 548ms (Views: 0.6ms | ActiveRecord: 117.7ms)
2020-05-11T06:38:31 [I|app|c5a61fec] Started GET “/node/apache-test1.organization.com?format=yml” for10.22.1.3 at 2020-05-11 06:38:31 +0200
2020-05-11T06:38:31 [I|app|c5a61fec] Processing by HostsController#externalNodes as YML
2020-05-11T06:38:31 [I|app|c5a61fec] Parameters: {“name”=>“apache-test1.organization.com”}
2020-05-11T06:38:31 [I|app|c5a61fec] Rendering text template
2020-05-11T06:38:31 [I|app|c5a61fec] Rendered text template (0.0ms)
2020-05-11T06:38:31 [I|app|c5a61fec] Completed 200 OK in 24ms (Views: 0.3ms | ActiveRecord: 4.8ms)
2020-05-11T06:38:31 [I|app|eda994fb] Started POST “/api/config_reports” for10.22.1.3 at 2020-05-11 06:38:31 +0200
2020-05-11T06:38:31 [I|app|eda994fb] Processing by Api::V2::ConfigReportsController#create as JSON
2020-05-11T06:38:31 [I|app|eda994fb] Parameters: {“config_report”=>"[FILTERED]", “apiv”=>“v2”}
2020-05-11T06:38:31 [I|app|eda994fb] Scanning report with: Foreman::PuppetReportScanner
2020-05-11T06:38:31 [I|app|eda994fb] Imported report for apache-test1.organization.com in 15.1 ms, status refreshed in 5.4 ms
2020-05-11T06:38:31 [I|app|eda994fb] Rendering api/v2/config_reports/create.json.rabl
2020-05-11T06:38:31 [I|app|eda994fb] Rendered api/v2/config_reports/create.json.rabl (3.5ms)
2020-05-11T06:38:31 [I|app|eda994fb] Completed 201 Created in 33ms (Views: 3.5ms | ActiveRecord: 10.6ms)
2020-05-11T06:38:52 [W|app|] Creating scope :completer_scope. Overwriting existing method Location.completer_scope.
2020-05-11T06:38:52 [W|app|] Creating scope :completer_scope. Overwriting existing method Organization.completer_scope.
2020-05-11T06:38:54 [F|app|] Failed running Dynflow daemon
2020-05-11T06:38:54 [F|app|] superclass mismatch for class Command

6

This is somewhat what I was looking for. Still, the output is not very helpful since it’s obviously lacking proper error messages…
Do you have to logs from the “original” run still around (read: the first time you tried importing the system)? Those might have some more info. I assume the current error is due to the fact that the system is already somewhat in Foreman, but not propperly configured and it is trying to add the host a second time.

Also: Have you tried removing the host from Foreman and re-importing it?
If you want to try that: Before you remove it, make sure it is not associated with a VM (if it actually is a virtual machine) and the interfaces are set to “unmanaged” (remove the ticks at “managed” for each interface) so DNS/DHCP records won’t get deleted.

7

Well I tried to find out when the very first log entires had been registered for the problematic host.
Anyway what is another clue is that I have the following message when I click on the host in the host list. Failure: undefined method `title’ for nil:NilClass

This is the log what I could found, maybe you could see sone relevant infos in there. Thanks again for having a look at it.

2020-05-06T20:31:17 [I|app|a22d6e69] Started PUT "/smart_proxies/1-puppet-foreman-server/puppetca/apache-test1.organization.com" for 10.27.2.9 at 2020-05-06 20:31:17 +0200
2020-05-06T20:31:17 [I|app|a22d6e69] Processing by PuppetcaController#update as HTML
2020-05-06T20:31:17 [I|app|a22d6e69]   Parameters: {"authenticity_token"=>"cKXOupWiX87smIs6HRrW7RKwcYfN+EWvAg78wLgPtxdjBBDeL+/8fJ7BUiyNM62WW/xEUF8F6H4UvESLgrNyrQ==", "smart_proxy_id"=>"1-puppet-foreman-server", "id"=>"apache-test1.organization.com"}
2020-05-06T20:31:18 [I|app|a22d6e69] Redirected to https://puppet-foreman-server/smart_proxies/1-puppet-foreman-server#certificates
2020-05-06T20:31:18 [I|app|a22d6e69] Completed 302 Found in 87ms (ActiveRecord: 2.4ms)
2020-05-06T20:31:18 [I|app|797e8875] Started GET "/smart_proxies/1-puppet-foreman-server" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|797e8875] Processing by SmartProxiesController#show as HTML
2020-05-06T20:31:18 [I|app|797e8875]   Parameters: {"id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|797e8875]   Rendering smart_proxies/show.html.erb within layouts/application
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/plugins/_plugin_version.html.erb (0.0ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/plugins/_no_template.html.erb (0.1ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/plugins/_plugin_version.html.erb (0.0ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/plugins/_tftp.html.erb (0.2ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/plugins/_logs.html.erb (0.1ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/plugins/_plugin_version.html.erb (0.0ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/plugins/_puppet.html.erb (2.1ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/plugins/_plugin_version.html.erb (0.0ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/plugins/_puppet_ca.html.erb (2.2ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered smart_proxies/show.html.erb within layouts/application (25.3ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendered layouts/_application_content.html.erb (0.6ms)
2020-05-06T20:31:18 [I|app|797e8875]   Rendering layouts/base.html.erb
2020-05-06T20:31:18 [I|app|797e8875]   Rendered layouts/base.html.erb (10.5ms)
2020-05-06T20:31:18 [I|app|797e8875] Completed 200 OK in 47ms (Views: 31.8ms | ActiveRecord: 7.6ms)
2020-05-06T20:31:18 [I|app|4199473f] Started GET "/smart_proxies/1-puppet-foreman-server/ping" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|38c8d1eb] Started GET "/smart_proxies/1-puppet-foreman-server/tftp_server" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|4199473f] Processing by SmartProxiesController#ping as */*
2020-05-06T20:31:18 [I|app|4199473f]   Parameters: {"id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|38c8d1eb] Processing by SmartProxiesController#tftp_server as */*
2020-05-06T20:31:18 [I|app|38c8d1eb]   Parameters: {"id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|4199473f] Completed 200 OK in 13ms (Views: 0.2ms | ActiveRecord: 2.7ms)
2020-05-06T20:31:18 [I|app|90d31158] Started GET "/smart_proxies/1-puppet-foreman-server/failed_modules?smart_proxy_id=1-puppet-foreman-server" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|38c8d1eb] Completed 200 OK in 13ms (Views: 0.2ms | ActiveRecord: 2.9ms)
2020-05-06T20:31:18 [I|app|90d31158] Processing by SmartProxiesController#failed_modules as HTML
2020-05-06T20:31:18 [I|app|90d31158]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server", "id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|90d31158]   Rendered smart_proxies/logs/_failed_modules.html.erb (0.1ms)
2020-05-06T20:31:18 [I|app|90d31158] Completed 200 OK in 19ms (Views: 0.4ms | ActiveRecord: 2.7ms)
2020-05-06T20:31:18 [I|app|1ccc972a] Started GET "/smart_proxies/1-puppet-foreman-server/modules_card?smart_proxy_id=1-puppet-foreman-server" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|17906ff1] Started GET "/smart_proxies/1-puppet-foreman-server/errors_card?smart_proxy_id=1-puppet-foreman-server" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|1ccc972a] Processing by SmartProxiesController#modules_card as HTML
2020-05-06T20:31:18 [I|app|1ccc972a]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server", "id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|17906ff1] Processing by SmartProxiesController#errors_card as HTML
2020-05-06T20:31:18 [I|app|17906ff1]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server", "id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|42b3ce4a] Started GET "/smart_proxies/1-puppet-foreman-server/puppetca/1-puppet-foreman-server/counts" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|42b3ce4a] Processing by PuppetcaController#counts as HTML
2020-05-06T20:31:18 [I|app|42b3ce4a]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server", "id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|1ccc972a]   Rendered smart_proxies/logs/_modules_card.html.erb (0.8ms)
2020-05-06T20:31:18 [I|app|1ccc972a] Completed 200 OK in 32ms (Views: 3.4ms | ActiveRecord: 9.9ms)
2020-05-06T20:31:18 [I|app|17906ff1]   Rendered smart_proxies/logs/_errors_card.html.erb (7.1ms)
2020-05-06T20:31:18 [I|app|17906ff1] Completed 200 OK in 44ms (Views: 7.5ms | ActiveRecord: 6.9ms)
2020-05-06T20:31:18 [I|app|45de8f78] Started GET "/smart_proxies/1-puppet-foreman-server/autosign" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|45de8f78] Processing by AutosignController#index as HTML
2020-05-06T20:31:18 [I|app|45de8f78]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|90a308b4] Started GET "/smart_proxies/1-puppet-foreman-server/autosign/new" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|45de8f78]   Rendered autosign/_list.html.erb (0.1ms)
2020-05-06T20:31:18 [I|app|45de8f78] Completed 200 OK in 15ms (Views: 0.5ms | ActiveRecord: 5.6ms)
2020-05-06T20:31:18 [I|app|90a308b4] Processing by AutosignController#new as HTML
2020-05-06T20:31:18 [I|app|90a308b4]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|e643f859] Started GET "/smart_proxies/1-puppet-foreman-server/puppet_dashboard" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|e643f859] Processing by SmartProxiesController#puppet_dashboard as HTML
2020-05-06T20:31:18 [I|app|e643f859]   Parameters: {"id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|42b3ce4a]   Rendered puppetca/_counts.html.erb (0.1ms)
2020-05-06T20:31:18 [I|app|42b3ce4a] Completed 200 OK in 83ms (Views: 0.5ms | ActiveRecord: 8.5ms)
2020-05-06T20:31:18 [I|app|90a308b4]   Rendered common/_modal.html.erb (4.5ms)
2020-05-06T20:31:18 [I|app|90a308b4]   Rendered autosign/_form.html.erb (5.1ms)
2020-05-06T20:31:18 [I|app|90a308b4] Completed 200 OK in 36ms (Views: 5.4ms | ActiveRecord: 13.5ms)
2020-05-06T20:31:18 [I|app|e2a8e1b2] Started GET "/smart_proxies/1-puppet-foreman-server/autosign/1-puppet-foreman-server/counts" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|e2a8e1b2] Processing by AutosignController#counts as HTML
2020-05-06T20:31:18 [I|app|e2a8e1b2]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server", "id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|b9732976] Started GET "/smart_proxies/1-puppet-foreman-server/puppetca" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|e34d7954] Started GET "/smart_proxies/1-puppet-foreman-server/log_pane?smart_proxy_id=1-puppet-foreman-server" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|54bddab8] Started GET "/smart_proxies/1-puppet-foreman-server/puppet_environments" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|b9732976] Processing by PuppetcaController#index as HTML
2020-05-06T20:31:18 [I|app|b9732976]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|54bddab8] Processing by SmartProxiesController#puppet_environments as HTML
2020-05-06T20:31:18 [I|app|54bddab8]   Parameters: {"id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|f1031b00] Started GET "/smart_proxies/1-puppet-foreman-server/puppetca/1-puppet-foreman-server/expiry" for 10.27.2.9 at 2020-05-06 20:31:18 +0200
2020-05-06T20:31:18 [I|app|e34d7954] Processing by SmartProxiesController#log_pane as HTML
2020-05-06T20:31:18 [I|app|e34d7954]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server", "id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|e2a8e1b2]   Rendered autosign/_counts.html.erb (0.6ms)
2020-05-06T20:31:18 [I|app|e2a8e1b2] Completed 200 OK in 21ms (Views: 10.9ms | ActiveRecord: 2.3ms)
2020-05-06T20:31:18 [I|app|f1031b00] Processing by PuppetcaController#expiry as HTML
2020-05-06T20:31:18 [I|app|f1031b00]   Parameters: {"smart_proxy_id"=>"1-puppet-foreman-server", "id"=>"1-puppet-foreman-server"}
2020-05-06T20:31:18 [I|app|54bddab8]   Rendered smart_proxies/plugins/_puppet_envs.html.erb (1.0ms)
2020-05-06T20:31:18 [I|app|54bddab8] Completed 200 OK in 18ms (Views: 3.6ms | ActiveRecord: 4.5ms)
2020-05-06T20:31:18 [I|app|f1031b00]   Rendered puppetca/_expiry.html.erb (0.5ms)
2020-05-06T20:31:18 [I|app|f1031b00] Completed 200 OK in 29ms (Views: 2.7ms | ActiveRecord: 17.5ms)
2020-05-06T20:31:18 [I|app|e34d7954]   Rendered common/_modal.html.erb (0.4ms)
2020-05-06T20:31:18 [I|app|e34d7954]   Rendered smart_proxies/logs/_modal.html.erb (2.8ms)
2020-05-06T20:31:18 [I|app|b9732976]   Rendered puppetca/_list.html.erb (17.4ms)
2020-05-06T20:31:18 [I|app|b9732976] Completed 200 OK in 52ms (Views: 21.6ms | ActiveRecord: 20.0ms)
2020-05-06T20:31:18 [I|app|e643f859]   Rendered dashboard/_reports_widget.html.erb (55.6ms)
2020-05-06T20:31:18 [I|app|e643f859]   Rendered dashboard/_distribution_widget.html.erb (11.7ms)
2020-05-06T20:31:19 [I|app|e643f859]   Rendered dashboard/_status_links.html.erb (1.2ms)
2020-05-06T20:31:19 [I|app|e643f859]   Rendered dashboard/_status_chart_widget.html.erb (17.1ms)
2020-05-06T20:31:19 [I|app|e643f859]   Rendered smart_proxies/plugins/_puppet_dashboard.html.erb (97.0ms)
2020-05-06T20:31:19 [I|app|e643f859] Completed 200 OK in 118ms (Views: 52.5ms | ActiveRecord: 50.7ms)
2020-05-06T20:31:19 [I|app|b1caf888] Started GET "/notification_recipients" for 10.27.2.9 at 2020-05-06 20:31:19 +0200
2020-05-06T20:31:19 [I|app|b1caf888] Processing by NotificationRecipientsController#index as JSON
2020-05-06T20:31:19 [I|app|b1caf888] Completed 200 OK in 6ms (Views: 0.1ms | ActiveRecord: 1.0ms)
2020-05-06T20:31:19 [I|app|e34d7954]   Rendered smart_proxies/logs/_list.html.erb (673.3ms)
2020-05-06T20:31:19 [I|app|e34d7954] Completed 200 OK in 714ms (Views: 675.7ms | ActiveRecord: 23.6ms)
2020-05-06T20:31:29 [I|app|ce9e9e70] Started GET "/node/apache-test1.organization.com?format=yml" for 10.33.20.2 at 2020-05-06 20:31:29 +0200
2020-05-06T20:31:29 [I|app|ce9e9e70] Processing by HostsController#externalNodes as YML
2020-05-06T20:31:29 [I|app|ce9e9e70]   Parameters: {"name"=>"apache-test1.organization.com"}
2020-05-06T20:31:29 [I|app|ce9e9e70] Completed 404 Not Found in 10ms (ActiveRecord: 3.0ms)
2020-05-06T20:31:33 [I|app|028993e5] Started POST "/api/hosts/facts" for 10.33.20.2 at 2020-05-06 20:31:33 +0200
2020-05-06T20:31:33 [I|app|028993e5] Processing by Api::V2::HostsController#facts as JSON
2020-05-06T20:31:33 [I|app|028993e5]   Parameters: {"facts"=>"[FILTERED]", "name"=>"apache-test1.organization.com", "certname"=>"apache-test1.organization.com", "apiv"=>"v2", "host"=>{"certname"=>"apache-test1.organization.com", "name"=>"apache-test1.organization.com"}}
2020-05-06T20:31:34 [I|app|028993e5] Import facts for 'apache-test1.organization.com' completed. Added: 392, Updated: 0, Deleted 0 facts
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on mac 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on ip 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on type Nic::Managed
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on name apache-test1.organization.com
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on host_id 27
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on subnet_id 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on domain_id 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on attrs {}
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on provider 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on username 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on password [redacted]
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on virtual false
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on link true
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on identifier 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on tag 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on attached_to 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on managed true
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on mode balance-rr
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on attached_devices 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on bond_options 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on primary true
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on provision true
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on compute_attributes {}
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on ip6 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Managed (47) create event on subnet6_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on name apache-test1.organization.com
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on root_pass 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on architecture_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on operatingsystem_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on environment_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on ptable_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on medium_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on build false
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on comment 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on disk 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on installed_at 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on model_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on hostgroup_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on owner_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on owner_type 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on enabled true
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on puppet_ca_proxy_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on managed false
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on use_image 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on image_file 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on uuid 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on compute_resource_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on puppet_proxy_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on certname apache-test1.organization.com
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on image_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on organization_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on location_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on otp 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on realm_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on compute_profile_id 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on provision_method 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on grub_pass 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on pxe_loader 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on initiated_at 
2020-05-06T20:31:35 [I|aud|028993e5] Host::Base (27) create event on build_errors 
2020-05-06T20:31:35 [I|aud|028993e5] Nic::Bond (47) update event on type Nic::Managed, Nic::Bond
2020-05-06T20:31:35 [I|aud|028993e5] Model (3) create event on name ProLiant DL360p Gen8
2020-05-06T20:31:35 [I|aud|028993e5] Model (3) create event on info 
2020-05-06T20:31:35 [I|aud|028993e5] Model (3) create event on vendor_class 
2020-05-06T20:31:35 [I|aud|028993e5] Model (3) create event on hardware_model 
2020-05-06T20:31:35 [W|app|028993e5] Action failed
2020-05-06T20:31:35 [I|app|028993e5]   Rendering api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout
2020-05-06T20:31:35 [I|app|028993e5]   Rendered api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout (1.7ms)
2020-05-06T20:31:35 [I|app|028993e5] Completed 500 Internal Server Error in 1175ms (Views: 55.9ms | ActiveRecord: 399.7ms)
8

Forgot to mention that the only way I used to “re-import” the nodes is by revoking the certificate, and than resign it. Is there a way ti actually import the node again?

9

This looks like the correct log. Sadly, it is still very silent, but at least we can confirm there was an error importing the system.
I would assume the problem was creating the hardware model info, but I am not deep enough into ruby and rails to say for sure wether it’s that step or the one that would have triggered next.

Simply revoking and re-signing the certificate won’t get you very far. That is only a Puppet-sided thing and has nothing to do with Foreman core.
If you want to re-import the system, you would have to completely remove it from Foreman and again add it via fact import. That is possible, but can be dangerous if you manage (for example) VM compute ressources or DNS/DHCP entries with Foreman. You would have to make sure that all external ressources for that system are disassociated/management disabled before actually removing the host from Foreman.

10

You can try changing the logging level to debug, that should hopefully show more info about the problem.

11

Thanks for the clarification @tbrisker, I completely ignored that.
It’s still questionable if this yields any usefull info, since the recurring error upon each puppet run seams to be a different one than the original cause. But it is worth a try, and should help if a second host with that problem shows up or the current one get’s re-added as mentioned above.

12

Hi there,

i have the same probleme here.
I think it may have to do with the following topic: Bug #29110: Uploading facts from Debian 10 (Buster) nodes returns error message from Foreman - Foreman

I also had hosts with the “`title’ for nil:NilClass” error (the one tested below is one of them), but that has gone away since i updated to foreman 2.0.

In the Ticket i linked it says it is fixed in 2.0.1, i have not updated yet.

If you still want to see a debug log, here you go:

2020-05-14T17:43:59 [I|app|ca0931a9] Started POST "/api/hosts/facts" for 10.0.0.156 at 2020-05-14 17:43:59 +0200
2020-05-14T17:43:59 [I|app|ca0931a9] Processing by Api::V2::HostsController#facts as JSON
2020-05-14T17:43:59 [I|app|ca0931a9]   Parameters: {"facts"=>"[FILTERED]", "name"=>"test-hostname.company.de", "certname"=>"test-hostname.company.de", "apiv"=>"v2", "host"=>{"certname"=>"test-hostname.company.de", "name"=>"test-hostname.company.de"}}
2020-05-14T17:43:59 [D|app|ca0931a9] Client sent certificate with subject 'foreman.company.de' and subject alt names '["puppet", "foreman.company.de"]'
2020-05-14T17:43:59 [D|app|ca0931a9] CN and SANs were extracted from a client certificate.
2020-05-14T17:43:59 [D|app|ca0931a9] Verifying request from ["puppet", "foreman.company.de"] against ["foreman.company.de"]
2020-05-14T17:44:00 [I|app|ca0931a9] Import facts for 'test-hostname.company.de' completed. Added: 0, Updated: 0, Deleted 0 facts
2020-05-14T17:44:00 [D|tax|ca0931a9] Current location set to company
2020-05-14T17:44:00 [D|tax|ca0931a9] Current organization set to IT
2020-05-14T17:44:00 [D|app|ca0931a9] skipping interface with identifier 'docker0' since it was matched by 'ignored_interface_identifiers' setting
2020-05-14T17:44:00 [D|app|ca0931a9] skipping interface with identifier 'lo' since it was matched by 'ignored_interface_identifiers' setting
2020-05-14T17:44:00 [D|app|ca0931a9] We have following interfaces 'eth0, eth1' based on facts
2020-05-14T17:44:00 [D|app|ca0931a9] Interface eth0 facts: {"bindings"=>[{"address"=>"10.0.100.185", "netmask"=>"255.255.0.0", "network"=>"10.0.0.0"}], "bindings6"=>[{"address"=>"fe80::4c68:ddff:fef8:f2ac", "netmask"=>"ffff:ffff:ffff:ffff::", "network"=>"fe80::"}], "dhcp"=>"10.0.0.1", "ipaddress"=>"10.0.100.185", "ipaddress6"=>"fe80::4c68:ddff:fef8:f2ac", "macaddress"=>"4e:68:dd:f8:f2:ac", "mtu"=>1500, "netmask"=>"255.255.0.0", "netmask6"=>"ffff:ffff:ffff:ffff::", "network"=>"10.0.0.0", "network6"=>"fe80::", "scope6"=>"link"}
2020-05-14T17:44:00 [D|app|ca0931a9] Interface eth1 facts: {"macaddress"=>"0a:b2:02:4f:02:7b", "mtu"=>1500}
2020-05-14T17:44:00 [W|app|ca0931a9] Action failed
2020-05-14T17:44:00 [D|app|ca0931a9] Backtrace for 'Action failed' error (ActiveRecord::RecordInvalid): Validation failed: Description has already been taken, Title has already been taken
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/validations.rb:80:in `raise_validation_error'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/validations.rb:52:in `save!'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:315:in `block in save!'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:387:in `block in with_transaction_returning_status'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `block in transaction'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:239:in `block in within_new_transaction'
/usr/lib/ruby/2.5.0/monitor.rb:226:in `mon_synchronize'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:236:in `within_new_transaction'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `transaction'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:212:in `transaction'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:385:in `with_transaction_returning_status'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:315:in `save!'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/suppressor.rb:48:in `save!'
/usr/share/foreman/app/services/puppet_fact_parser.rb:34:in `operatingsystem'
/usr/share/foreman/app/models/host/base.rb:199:in `block in set_non_empty_values'
/usr/share/foreman/app/models/host/base.rb:198:in `each'
/usr/share/foreman/app/models/host/base.rb:198:in `set_non_empty_values'
/usr/share/foreman/app/models/host/base.rb:193:in `populate_fields_from_facts'
/usr/share/foreman/app/models/host/managed.rb:424:in `populate_fields_from_facts'
/usr/share/foreman/app/models/concerns/puppet_host_extensions.rb:3:in `populate_fields_from_facts'
/usr/share/foreman/app/models/host/base.rb:168:in `block in parse_facts'
/usr/share/foreman/app/services/foreman/telemetry_helper.rb:27:in `telemetry_duration_histogram'
/usr/share/foreman/app/models/host/base.rb:167:in `parse_facts'
/usr/share/foreman/app/models/host/base.rb:153:in `import_facts'
/usr/share/foreman/app/models/host/managed.rb:343:in `import_facts'
/usr/share/foreman/app/controllers/api/v2/hosts_controller.rb:286:in `facts'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:194:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/rendering.rb:30:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:42:in `block in process_action'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:136:in `run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:41:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/rescue.rb:22:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:34:in `block in process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `block in instrument'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/notifications/instrumenter.rb:23:in `instrument'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `instrument'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:32:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/params_wrapper.rb:256:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/railties/controller_runtime.rb:24:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:134:in `process'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionview-5.2.1/lib/action_view/rendering.rb:32:in `process'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal.rb:191:in `dispatch'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal.rb:252:in `dispatch'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:52:in `dispatch'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:34:in `serve'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/mapper.rb:18:in `block in <class:Constraints>'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/mapper.rb:48:in `serve'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:52:in `block in serve'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `each'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `serve'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:840:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/apipie-rails-0.5.17/lib/apipie/static_dispatcher.rb:66:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/apipie-rails-0.5.17/lib/apipie/extractor/recorder.rb:137:in `call'
/usr/share/foreman/lib/foreman/middleware/telemetry.rb:10:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/tempfile_reaper.rb:15:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/etag.rb:25:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/conditional_get.rb:38:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/head.rb:12:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/session/abstract/id.rb:259:in `context'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/session/abstract/id.rb:253:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/cookies.rb:670:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:98:in `run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:26:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/debug_exceptions.rb:61:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/rack/logger.rb:38:in `call_app'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/rack/logger.rb:28:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/request_id.rb:27:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/method_override.rb:22:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/runtime.rb:22:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/executor.rb:14:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/sendfile.rb:111:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/secure_headers-6.3.0/lib/secure_headers/middleware.rb:11:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/engine.rb:524:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `public_send'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `method_missing'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/urlmap.rb:68:in `block in call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/urlmap.rb:53:in `each'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/urlmap.rb:53:in `call'
/usr/lib/ruby/vendor_ruby/phusion_passenger/rack/thread_handler_extension.rb:97:in `process_request'
/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler/thread_handler.rb:160:in `accept_and_process_next_request'
/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler/thread_handler.rb:113:in `main_loop'
/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler.rb:416:in `block (3 levels) in start_threads'
/usr/lib/ruby/vendor_ruby/phusion_passenger/utils.rb:113:in `block in create_thread_and_abort_on_exception'
/usr/share/foreman/vendor/ruby/2.5.0/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
2020-05-14T17:44:00 [I|app|ca0931a9]   Rendering api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout
2020-05-14T17:44:00 [I|app|ca0931a9]   Rendered api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout (0.6ms)
2020-05-14T17:44:00 [I|app|ca0931a9] Completed 500 Internal Server Error in 493ms (Views: 2.3ms | ActiveRecord: 103.0ms)
2020-05-14T17:44:00 [I|app|c1c56e6d] Started GET "/node/test-hostname.company.de?format=yml" for 10.0.0.156 at 2020-05-14 17:44:00 +0200
2020-05-14T17:44:00 [I|app|c1c56e6d] Processing by HostsController#externalNodes as YML
2020-05-14T17:44:00 [I|app|c1c56e6d]   Parameters: {"name"=>"test-hostname.company.de"}
2020-05-14T17:44:00 [D|app|c1c56e6d] Client sent certificate with subject 'foreman.company.de' and subject alt names '["puppet", "foreman.company.de"]'
2020-05-14T17:44:00 [D|app|c1c56e6d] CN and SANs were extracted from a client certificate.
2020-05-14T17:44:00 [D|app|c1c56e6d] Verifying request from ["puppet", "foreman.company.de"] against ["foreman.company.de"]
2020-05-14T17:44:00 [I|app|c1c56e6d]   Rendering text template
2020-05-14T17:44:00 [I|app|c1c56e6d]   Rendered text template (0.0ms)
2020-05-14T17:44:00 [I|app|c1c56e6d] Completed 200 OK in 42ms (Views: 0.4ms | ActiveRecord: 7.4ms)
2020-05-14T17:44:00 [I|app|b74e37b6] Started POST "/api/hosts/facts" for 10.0.0.156 at 2020-05-14 17:44:00 +0200
2020-05-14T17:44:00 [I|app|b74e37b6] Processing by Api::V2::HostsController#facts as JSON
2020-05-14T17:44:00 [I|app|b74e37b6]   Parameters: {"facts"=>"[FILTERED]", "name"=>"test-hostname.company.de", "certname"=>"test-hostname.company.de", "apiv"=>"v2", "host"=>{"certname"=>"test-hostname.company.de", "name"=>"test-hostname.company.de"}}
2020-05-14T17:44:00 [D|app|b74e37b6] Client sent certificate with subject 'foreman.company.de' and subject alt names '["puppet", "foreman.company.de"]'
2020-05-14T17:44:00 [D|app|b74e37b6] CN and SANs were extracted from a client certificate.
2020-05-14T17:44:00 [D|app|b74e37b6] Verifying request from ["puppet", "foreman.company.de"] against ["foreman.company.de"]
2020-05-14T17:44:01 [I|app|b74e37b6] Import facts for 'test-hostname.company.de' completed. Added: 0, Updated: 21, Deleted 0 facts
2020-05-14T17:44:01 [D|tax|b74e37b6] Current location set to company
2020-05-14T17:44:01 [D|tax|b74e37b6] Current organization set to IT
2020-05-14T17:44:01 [D|app|b74e37b6] skipping interface with identifier 'docker0' since it was matched by 'ignored_interface_identifiers' setting
2020-05-14T17:44:01 [D|app|b74e37b6] skipping interface with identifier 'lo' since it was matched by 'ignored_interface_identifiers' setting
2020-05-14T17:44:01 [D|app|b74e37b6] We have following interfaces 'eth0, eth1' based on facts
2020-05-14T17:44:01 [D|app|b74e37b6] Interface eth0 facts: {"bindings"=>[{"address"=>"10.0.100.185", "netmask"=>"255.255.0.0", "network"=>"10.0.0.0"}], "bindings6"=>[{"address"=>"fe80::4c68:ddff:fef8:f2ac", "netmask"=>"ffff:ffff:ffff:ffff::", "network"=>"fe80::"}], "dhcp"=>"10.0.0.1", "ipaddress"=>"10.0.100.185", "ipaddress6"=>"fe80::4c68:ddff:fef8:f2ac", "macaddress"=>"4e:68:dd:f8:f2:ac", "mtu"=>1500, "netmask"=>"255.255.0.0", "netmask6"=>"ffff:ffff:ffff:ffff::", "network"=>"10.0.0.0", "network6"=>"fe80::", "scope6"=>"link"}
2020-05-14T17:44:01 [D|app|b74e37b6] Interface eth1 facts: {"macaddress"=>"0a:b2:02:4f:02:7b", "mtu"=>1500}
2020-05-14T17:44:01 [W|app|b74e37b6] Action failed
2020-05-14T17:44:01 [D|app|b74e37b6] Backtrace for 'Action failed' error (ActiveRecord::RecordInvalid): Validation failed: Description has already been taken, Title has already been taken
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/validations.rb:80:in `raise_validation_error'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/validations.rb:52:in `save!'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:315:in `block in save!'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:387:in `block in with_transaction_returning_status'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `block in transaction'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:239:in `block in within_new_transaction'
/usr/lib/ruby/2.5.0/monitor.rb:226:in `mon_synchronize'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/transaction.rb:236:in `within_new_transaction'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/connection_adapters/abstract/database_statements.rb:254:in `transaction'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:212:in `transaction'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:385:in `with_transaction_returning_status'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/transactions.rb:315:in `save!'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/suppressor.rb:48:in `save!'
/usr/share/foreman/app/services/puppet_fact_parser.rb:34:in `operatingsystem'
/usr/share/foreman/app/models/host/base.rb:199:in `block in set_non_empty_values'
/usr/share/foreman/app/models/host/base.rb:198:in `each'
/usr/share/foreman/app/models/host/base.rb:198:in `set_non_empty_values'
/usr/share/foreman/app/models/host/base.rb:193:in `populate_fields_from_facts'
/usr/share/foreman/app/models/host/managed.rb:424:in `populate_fields_from_facts'
/usr/share/foreman/app/models/concerns/puppet_host_extensions.rb:3:in `populate_fields_from_facts'
/usr/share/foreman/app/models/host/base.rb:168:in `block in parse_facts'
/usr/share/foreman/app/services/foreman/telemetry_helper.rb:27:in `telemetry_duration_histogram'
/usr/share/foreman/app/models/host/base.rb:167:in `parse_facts'
/usr/share/foreman/app/models/host/base.rb:153:in `import_facts'
/usr/share/foreman/app/models/host/managed.rb:343:in `import_facts'
/usr/share/foreman/app/controllers/api/v2/hosts_controller.rb:286:in `facts'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:194:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/rendering.rb:30:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:42:in `block in process_action'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/audited-4.9.0/lib/audited/sweeper.rb:14:in `around'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:118:in `block in run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:136:in `run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/abstract_controller/callbacks.rb:41:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/rescue.rb:22:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:34:in `block in process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `block in instrument'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/notifications/instrumenter.rb:23:in `instrument'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/notifications.rb:168:in `instrument'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/instrumentation.rb:32:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal/params_wrapper.rb:256:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activerecord-5.2.1/lib/active_record/railties/controller_runtime.rb:24:in `process_action'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/abstract_controller/base.rb:134:in `process'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionview-5.2.1/lib/action_view/rendering.rb:32:in `process'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal.rb:191:in `dispatch'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_controller/metal.rb:252:in `dispatch'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:52:in `dispatch'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:34:in `serve'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/mapper.rb:18:in `block in <class:Constraints>'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/mapper.rb:48:in `serve'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:52:in `block in serve'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `each'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/journey/router.rb:35:in `serve'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/routing/route_set.rb:840:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/apipie-rails-0.5.17/lib/apipie/static_dispatcher.rb:66:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/apipie-rails-0.5.17/lib/apipie/extractor/recorder.rb:137:in `call'
/usr/share/foreman/lib/foreman/middleware/telemetry.rb:10:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/tempfile_reaper.rb:15:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/etag.rb:25:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/conditional_get.rb:38:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/head.rb:12:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/session/abstract/id.rb:259:in `context'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/session/abstract/id.rb:253:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/cookies.rb:670:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/callbacks.rb:98:in `run_callbacks'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/callbacks.rb:26:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/debug_exceptions.rb:61:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/show_exceptions.rb:33:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/rack/logger.rb:38:in `call_app'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/rack/logger.rb:28:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/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'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/remote_ip.rb:81:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/request_id.rb:27:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/method_override.rb:22:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/runtime.rb:22:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/activesupport-5.2.1/lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/executor.rb:14:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/actionpack-5.2.1/lib/action_dispatch/middleware/static.rb:127:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/sendfile.rb:111:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/secure_headers-6.3.0/lib/secure_headers/middleware.rb:11:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/engine.rb:524:in `call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `public_send'
/usr/share/foreman/vendor/ruby/2.5.0/gems/railties-5.2.1/lib/rails/railtie.rb:190:in `method_missing'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/urlmap.rb:68:in `block in call'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/urlmap.rb:53:in `each'
/usr/share/foreman/vendor/ruby/2.5.0/gems/rack-2.0.9/lib/rack/urlmap.rb:53:in `call'
/usr/lib/ruby/vendor_ruby/phusion_passenger/rack/thread_handler_extension.rb:97:in `process_request'
/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler/thread_handler.rb:160:in `accept_and_process_next_request'
/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler/thread_handler.rb:113:in `main_loop'
/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler.rb:416:in `block (3 levels) in start_threads'
/usr/lib/ruby/vendor_ruby/phusion_passenger/utils.rb:113:in `block in create_thread_and_abort_on_exception'
/usr/share/foreman/vendor/ruby/2.5.0/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
2020-05-14T17:44:01 [I|app|b74e37b6]   Rendering api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout
2020-05-14T17:44:01 [I|app|b74e37b6]   Rendered api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout (0.2ms)
2020-05-14T17:44:01 [I|app|b74e37b6] Completed 500 Internal Server Error in 511ms (Views: 0.7ms | ActiveRecord: 172.7ms)
2020-05-14T17:44:01 [I|app|93f5247c] Started GET "/node/test-hostname.company.de?format=yml" for 10.0.0.156 at 2020-05-14 17:44:01 +0200
2020-05-14T17:44:01 [I|app|93f5247c] Processing by HostsController#externalNodes as YML
2020-05-14T17:44:01 [I|app|93f5247c]   Parameters: {"name"=>"test-hostname.company.de"}
2020-05-14T17:44:01 [D|app|93f5247c] Client sent certificate with subject 'foreman.company.de' and subject alt names '["puppet", "foreman.company.de"]'
2020-05-14T17:44:01 [D|app|93f5247c] CN and SANs were extracted from a client certificate.
2020-05-14T17:44:01 [D|app|93f5247c] Verifying request from ["puppet", "foreman.company.de"] against ["foreman.company.de"]
2020-05-14T17:44:01 [I|app|93f5247c]   Rendering text template
2020-05-14T17:44:01 [I|app|93f5247c]   Rendered text template (0.0ms)
2020-05-14T17:44:01 [I|app|93f5247c] Completed 200 OK in 21ms (Views: 0.3ms | ActiveRecord: 5.5ms)
2020-05-14T17:44:01 [I|app|2f18d2e0] Started POST "/api/config_reports" for 10.0.0.156 at 2020-05-14 17:44:01 +0200
2020-05-14T17:44:01 [I|app|2f18d2e0] Processing by Api::V2::ConfigReportsController#create as JSON
2020-05-14T17:44:01 [I|app|2f18d2e0]   Parameters: {"config_report"=>"[FILTERED]", "apiv"=>"v2"}
2020-05-14T17:44:01 [D|app|2f18d2e0] Client sent certificate with subject 'foreman.company.de' and subject alt names '["puppet", "foreman.company.de"]'
2020-05-14T17:44:01 [D|app|2f18d2e0] CN and SANs were extracted from a client certificate.
2020-05-14T17:44:01 [D|app|2f18d2e0] Verifying request from ["puppet", "foreman.company.de"] against ["foreman.company.de"]
2020-05-14T17:44:01 [D|app|2f18d2e0] Processing report: {"host"=>"test-hostname.company.de", "reported_at"=>"2020-05-14 15:43:59 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"=>7}, "time"=>{"catalog_application"=>0.015794476988958195, "config_retrieval"=>0.7782935239956714, "convert_catalog"=>0.03270320400770288, "fact_generation"=>0.19871026100008748, "filebucket"=>5.447e-05, "node_retrieval"=>0.8132439859909937, "plugin_sync"=>0.11074797999754082, "schedule"=>0.000428345, "startup_time"=>0.630769328, "total"=>2.60099015, "transaction_evaluation"=>0.007963262993143871}, "changes"=>{"total"=>0}, "events"=>{"failure"=>0, "success"=>0, "total"=>0}}, "logs"=>[{"log"=>{"sources"=>{"source"=>"Puppet"}, "messages"=>{"message"=>"Using configured environment 'production'"}, "level"=>"info"}}, {"log"=>{"sources"=>{"source"=>"Puppet"}, "messages"=>{"message"=>"Retrieving pluginfacts"}, "level"=>"info"}}, {"log"=>{"sources"=>{"source"=>"Puppet"}, "messages"=>{"message"=>"Retrieving plugin"}, "level"=>"info"}}, {"log"=>{"sources"=>{"source"=>"Puppet"}, "messages"=>{"message"=>"Retrieving locales"}, "level"=>"info"}}, {"log"=>{"sources"=>{"source"=>"Puppet"}, "messages"=>{"message"=>"Caching catalog for test-hostname.company.de"}, "level"=>"info"}}, {"log"=>{"sources"=>{"source"=>"Puppet"}, "messages"=>{"message"=>"Applying configuration version '1589471041'"}, "level"=>"info"}}, {"log"=>{"sources"=>{"source"=>"Puppet"}, "messages"=>{"message"=>"Applied catalog in 0.02 seconds"}, "level"=>"notice"}}]}
2020-05-14T17:44:01 [I|app|2f18d2e0] Scanning report with: Foreman::PuppetReportScanner
2020-05-14T17:44:01 [D|app|2f18d2e0] Changes after scanning: {"host_id"=>[nil, 24], "reported_at"=>[nil, Thu, 14 May 2020 15:43:59 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: 7\ntime: !ruby/hash:ActiveSupport::HashWithIndifferentAccess\n  catalog_application: 0.015794476988958195\n  config_retrieval: 0.7782935239956714\n  convert_catalog: 0.03270320400770288\n  fact_generation: 0.19871026100008748\n  filebucket: 5.447e-05\n  node_retrieval: 0.8132439859909937\n  plugin_sync: 0.11074797999754082\n  schedule: 0.000428345\n  startup_time: 0.630769328\n  total: 2.60099015\n  transaction_evaluation: 0.007963262993143871\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-05-14T17:44:01 [I|app|2f18d2e0] Imported report for test-hostname.company.de in 70.8 ms, status refreshed in 8.4 ms
2020-05-14T17:44:01 [I|app|2f18d2e0]   Rendering api/v2/config_reports/create.json.rabl
2020-05-14T17:44:01 [I|app|2f18d2e0]   Rendered api/v2/config_reports/create.json.rabl (14.1ms)
2020-05-14T17:44:01 [D|app|2f18d2e0] 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":7},"time":{"catalog_application":0.015794476988958195,"config_retrieval":0.7782935239956714,"convert_catalog":0.03270320400770288,"fact_generation":0.19871026100008748,"filebucket":5.447e-05,"node_retrieval":0.8132439859909937,"plugin_sync":0.11074797999754082,"schedule":0.000428345,"startup_time":0.630769328,"total":2.60099015,"transaction_evaluation":0.007963262993143871},"changes":{"total":0},"events":{"failure":0,"success":0,"total":0}},"created_at":"2020-05-14T15:44:01.709Z","updated_at":"2020-05-14T15:44:01.709Z","id":29661,"host_id":24,"host_name":"test-hostname.company.de","reported_at":"2020-05-14T15:43:59.000Z","status":{"applied":0,"restarted":0,"failed":0,"failed_restarts":0,"skipped":0,"pending":0},"logs":[{"level":"info","source":{"source":"Puppet"},"message":{"message":"Using configured environment 'production'"}},{"level":"info","source":{"source":"Puppet"},"message":{"message":"Retrieving pluginfacts"}},{"level":"info","source":{"source":"Puppet"},"message":{"message":"Retrieving plugin"}},{"level":"info","source":{"source":"Puppet"},"message":{"message":"Retrieving locales"}},{"level":"info","source":{"source":"Puppet"},"message":{"message":"Caching catalog for test-hostname.company.de"}},{"level":"info","source":{"source":"Puppet"},"message":{"message":"Applying configuration version '1589471041'"}},{"level":"notice","source":{"source":"Puppet"},"message":{"message":"Applied catalog in 0.02 seconds"}}],"summary":"Success"}
2020-05-14T17:44:01 [I|app|2f18d2e0] Completed 201 Created in 112ms (Views: 14.8ms | ActiveRecord: 50.2ms
13

Hello mates,

Could anyone finally find out any further about why those debian 10 hosts won’t show up in foreman?
I tried to fix it for hours, but just no success at all. :frowning:

14

Hello guys!

Problem seems to be solved by the latest Foreman update, so far it works just fine signing debian nodes as well, they appear in the dashboard OK.

Many thanks for the Foreman community!

1 Like