Also seeing the following in
/var/log/puppetlabs/puppetserver/puppetserver.log when I run the puppet
agent command:
2016-12-21 12:47:40,724 WARN [qtp1406891402-75] [c.p.p.ShellUtils]
Executed an external process which logged to STDERR: Could not send facts
to Foreman: Permission denied - /etc/puppetlabs/puppet/ssl/client_key.pem
2016-12-21 12:47:40,726 ERROR [qtp1406891402-75] [puppetserver] Puppet
Server Error: Failed to find katello.innes.net via exec: Execution of
'/etc/puppetlabs/puppet/node.rb katello.innes.net' returned 1:
file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/execution.rb:44:in
execute' file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/execution.rb:14:in
initialize_execution_stub'
org/jruby/RubyProc.java:281:in call' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/execution.rb:194:in
execute'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/node/exec.rb:33:in
execute' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/exec.rb:19:in
find'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/node/exec.rb:17:in
find' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/indirection.rb:194:in
find'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:121:in
do_find' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:48:in
call'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/context.rb:65:in
override' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet.rb:241:in
override'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:47:in
call' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:82:in
process'
org/jruby/RubyArray.java:1613:in each' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:81:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:60:in
process' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler/around_profiler.rb:58:in
profile'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler.rb:51:in
profile' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:58:in
process'
file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/master.rb:42:in
handleRequest' Puppet$$Server$$Master_505362540.gen:13:in
handleRequest'
request_handler_core.clj:273:in invoke' jruby_request.clj:46:in
invoke'
jruby_request.clj:31:in invoke' request_handler_service.clj:34:in
handle_request'
request_handler.clj:3:in invoke' request_handler.clj:3:in
invoke'
core.clj:2515:in invoke' ring_middleware.clj:284:in
invoke'
core.clj:168:in invoke' core.clj:211:in
invoke'
core.clj:45:in invoke' core.clj:343:in
invoke'
core.clj:51:in invoke' ringutils.clj:83:in
invoke'
master_core.clj:411:in invoke' ring.clj:21:in
invoke'
ring.clj:12:in invoke' comidi.clj:249:in
invoke'
jetty9_core.clj:424:in invoke' normalized_uri_helpers.clj:80:in
invoke'
2016-12-21 12:47:41,309 WARN [qtp1406891402-73] [c.p.p.ShellUtils]
Executed an external process which logged to STDERR: Could not send facts
to Foreman: Permission denied - /etc/puppetlabs/puppet/ssl/client_key.pem
2016-12-21 12:47:41,317 ERROR [qtp1406891402-73] [puppetserver] Puppet
Failed when searching for node katello.innes.net: Failed to find
katello.innes.net via exec: Execution of '/etc/puppetlabs/puppet/node.rb
katello.innes.net' returned 1:
file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/execution.rb:44:in
execute' file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/execution.rb:14:in
initialize_execution_stub'
org/jruby/RubyProc.java:281:in call' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/execution.rb:194:in
execute'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/node/exec.rb:33:in
execute' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/exec.rb:19:in
find'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/node/exec.rb:17:in
find' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/indirection.rb:194:in
find'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/catalog/compiler.rb:295:in
find_node' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler/around_profiler.rb:58:in
profile'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler.rb:51:in
profile' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/catalog/compiler.rb:292:in
find_node'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/catalog/compiler.rb:332:in
node_from_request' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/catalog/compiler.rb:50:in
find'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/indirection.rb:194:in
find' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:121:in
do_find'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:48:in
call' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/context.rb:65:in
override'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet.rb:241:in override' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:47:in
call'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:82:in
process' org/jruby/RubyArray.java:1613:in
each'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:81:in
process' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:60:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler/around_profiler.rb:58:in
profile' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler.rb:51:in
profile'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:58:in
process' file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/master.rb:42:in
handleRequest'
Puppet$$Server$$Master_505362540.gen:13:in handleRequest' request_handler_core.clj:273:in
invoke'
jruby_request.clj:46:in invoke' jruby_request.clj:31:in
invoke'
request_handler_service.clj:34:in handle_request' request_handler.clj:3:in
invoke'
request_handler.clj:3:in invoke' core.clj:2515:in
invoke'
ring_middleware.clj:284:in invoke' core.clj:168:in
invoke'
core.clj:211:in invoke' core.clj:45:in
invoke'
core.clj:343:in invoke' core.clj:51:in
invoke'
ringutils.clj:83:in invoke' master_core.clj:428:in
invoke'
ring.clj:21:in invoke' ring.clj:12:in
invoke'
comidi.clj:249:in invoke' jetty9_core.clj:424:in
invoke'
normalized_uri_helpers.clj:80:in invoke' 2016-12-21 12:47:41,317 ERROR [qtp1406891402-73] [puppetserver] Puppet Server Error: Failed when searching for node katello.innes.net: Failed to find katello.innes.net via exec: Execution of '/etc/puppetlabs/puppet/node.rb katello.innes.net' returned 1: file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/execution.rb:44:in
execute'
file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/execution.rb:14:in
initialize_execution_stub' org/jruby/RubyProc.java:281:in
call'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/execution.rb:194:in
execute' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/node/exec.rb:33:in
execute'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/exec.rb:19:in
find' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/node/exec.rb:17:in
find'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/indirection.rb:194:in
find' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/catalog/compiler.rb:295:in
find_node'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler/around_profiler.rb:58:in
profile' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler.rb:51:in
profile'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/catalog/compiler.rb:292:in
find_node' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/catalog/compiler.rb:332:in
node_from_request'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/catalog/compiler.rb:50:in
find' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/indirection.rb:194:in
find'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:121:in
do_find' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:48:in
call'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/context.rb:65:in
override' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet.rb:241:in
override'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:47:in
call' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:82:in
process'
org/jruby/RubyArray.java:1613:in each' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:81:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:60:in
process' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler/around_profiler.rb:58:in
profile'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler.rb:51:in
profile' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:58:in
process'
file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/master.rb:42:in
handleRequest' Puppet$$Server$$Master_505362540.gen:13:in
handleRequest'
request_handler_core.clj:273:in invoke' jruby_request.clj:46:in
invoke'
jruby_request.clj:31:in invoke' request_handler_service.clj:34:in
handle_request'
request_handler.clj:3:in invoke' request_handler.clj:3:in
invoke'
core.clj:2515:in invoke' ring_middleware.clj:284:in
invoke'
core.clj:168:in invoke' core.clj:211:in
invoke'
core.clj:45:in invoke' core.clj:343:in
invoke'
core.clj:51:in invoke' ringutils.clj:83:in
invoke'
master_core.clj:428:in invoke' ring.clj:21:in
invoke'
ring.clj:12:in invoke' comidi.clj:249:in
invoke'
jetty9_core.clj:424:in invoke' normalized_uri_helpers.clj:80:in
invoke'
2016-12-21 12:47:41,355 ERROR [qtp1406891402-76] [puppetserver] Puppet
Report processor failed: Could not send report to Foreman at
https://katello.innes.net/api/config_reports: Permission denied -
/etc/puppetlabs/puppet/ssl/client_key.pem
["org/jruby/RubyIO.java:3804:in read'", "org/jruby/RubyIO.java:3987:in
read'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/reports/foreman.rb:58:in
process'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:37:in
process'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:53:in
processors'", "org/jruby/RubyArray.java:1613:in
each'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:51:in
processors'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:30:in
process'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:14:in
save'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/indirection.rb:285:in
save'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:176:in
do_save'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:48:in
call'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/context.rb:65:in
override'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet.rb:241:in
override'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:47:in
call'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:82:in
process'", "org/jruby/RubyArray.java:1613:in each'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:81:in
process'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:60:in
process'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler/around_profiler.rb:58:in
profile'",
"/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler.rb:51:in
profile'", "/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:58:in
process'",
"file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/master.rb:42:in
handleRequest'", "Puppet$$Server$$Master_505362540.gen:13:in
handleRequest'", "request_handler_core.clj:273:in invoke'", "jruby_request.clj:46:in
invoke'", "jruby_request.clj:31:in invoke'", "request_handler_service.clj:34:in
handle_request'",
"request_handler.clj:3:in invoke'", "request_handler.clj:3:in
invoke'",
"core.clj:2515:in invoke'", "ring_middleware.clj:284:in
invoke'",
"core.clj:168:in invoke'", "core.clj:211:in
invoke'", "core.clj:45:in
invoke'", "core.clj:343:in
invoke'", "core.clj:51:in invoke'", "ringutils.clj:83:in
invoke'", "master_core.clj:430:in invoke'", "ring.clj:21:in
invoke'", "ring.clj:12:in invoke'", "comidi.clj:249:in
invoke'", "jetty9_core.clj:424:in invoke'", "normalized_uri_helpers.clj:80:in
invoke'"]
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/reports/foreman.rb:67:in
process' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:37:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:53:in
processors' org/jruby/RubyArray.java:1613:in
each'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:51:in
processors' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:30:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/report/processor.rb:14:in
save' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/indirector/indirection.rb:285:in
save'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:176:in
do_save' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:48:in
call'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/context.rb:65:in
override' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet.rb:241:in
override'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/api/indirected_routes.rb:47:in
call' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:82:in
process'
org/jruby/RubyArray.java:1613:in each' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:81:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/route.rb:87:in
process'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:60:in
process' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler/around_profiler.rb:58:in
profile'
/opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/profiler.rb:51:in
profile' /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/network/http/handler.rb:58:in
process'
file:/opt/puppetlabs/server/apps/puppetserver/puppet-server-release.jar!/puppetserver-lib/puppet/server/master.rb:42:in
handleRequest' Puppet$$Server$$Master_505362540.gen:13:in
handleRequest'
request_handler_core.clj:273:in invoke' jruby_request.clj:46:in
invoke'
jruby_request.clj:31:in invoke' request_handler_service.clj:34:in
handle_request'
request_handler.clj:3:in invoke' request_handler.clj:3:in
invoke'
core.clj:2515:in invoke' ring_middleware.clj:284:in
invoke'
core.clj:168:in invoke' core.clj:211:in
invoke'
core.clj:45:in invoke' core.clj:343:in
invoke'
core.clj:51:in invoke' ringutils.clj:83:in
invoke'
master_core.clj:430:in invoke' ring.clj:21:in
invoke'
ring.clj:12:in invoke' comidi.clj:249:in
invoke'
jetty9_core.clj:424:in invoke' normalized_uri_helpers.clj:80:in
invoke'
···
On Wednesday, 21 December 2016 12:45:34 UTC, Duncan Innes wrote:
>
> Something might be going wrong with this latest release. I'm testing the
> latest Katello/Foreman release too and the server does not manage to
> register to itself.
>
> Running the puppet command suggested above gives:
>
> root@katello ~]# puppet agent -t --server `hostname -f`
> Warning: Unable to fetch my node definition, but the agent run will
> continue:
> Warning: Error 500 on SERVER: Server Error: Failed to find
> katello.innes.net via exec: Execution of '/etc/puppetlabs/puppet/node.rb
> katello.innes.net' returned 1:
> Info: Retrieving pluginfacts
> Info: Retrieving plugin
> Error: Could not retrieve catalog from remote server: Error 500 on SERVER:
> Server Error: Failed when searching for node katello.innes.net: Failed to
> find katello.innes.net via exec: Execution of
> '/etc/puppetlabs/puppet/node.rb katello.innes.net' returned 1:
> Warning: Not using cache on failed catalog
> Error: Could not retrieve catalog; skipping run
> [root@katello ~]#
>
> This is a Puppet 4 build I've installed.
>