[Pkg-puppet-devel] Bug#923976: puppet: Reports submitte
Kienan
kienan at koumbit.org
Thu Mar 7 19:54:10 GMT 2019
Package: puppet
Version: 5.5.10-1
Severity: normal
Dear Maintainer,
When running puppet 5.x with reports enabled and sent to PuppetDB 6.x,
PuppetDB fails to store the report with the following error:
2019-03-01T17:06:01.396-05:00 ERROR [p.p.command] [100] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil
nil nil nil nil nil nil {:value (not (instance? java.lang.Number
a-java.lang.String))} {:value (not (instance? java.lang.Number
a-java.lang.String))} nil {:value (not (instance? java.lang.Number
a-java.lang.String))} nil {:value (not (instance? java.lang.Number
a-java.lang.String))} nil {:value (not (instance? java.lang.Number
a-java.lang.String))} {:value (not (instance? java.lang.Number
a-java.lang.String))} {:value (not (instance? java.lang.Number
a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number
a-java.lang.String))} nil {:value (not (instance? java.lang.Number
a-java.lang.String))} nil {:value (not (instance? java.lang.Number
a-java.lang.String))} nil nil nil nil nil nil nil nil nil {:value (not
(instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil
nil]}"
A copy of the Puppet DB log file is attached with the complete
backtrace.
I'm filing this bug against the puppet package for the following reasons
* hosts running puppet 4.x (stretch) are able to submit reports to same
version of Puppet DB.
* I am able to "solve" the problem by modifying a number of places in
the puppet agent files where report information is returned and calling
"to_i" or "to_f" to force casting into a numeric value instead of a
string.
The metrics keys affected include: convert_catalog and
transaction_evaluation. The keys affected may change depending on the
manifest used for the node.
This being said, it may be appropriate to move this bug to the PuppetDB
package. I haven't been able to find any upstream bugs regarding this
behaviour.
To reproduce:
1. Install puppet, puppet-master-passenger, and puppetdb on the same
host.
2. Configure the puppet master to storeconfigs with the PuppetDB and the
agent to submit reports:
(snippet from /etc/puppet.conf)
[main]
reports = puppetdb
[master]
storeconfigs = true
storeconfig_backend = true
[agent]
report = true
3. Run puppet using a minimal node manifest:
node 'pm-buster.test' {
}
4. The puppet run should complete successfully with no additional
output.
5. Check the PuppetDB log at /var/log/puppetdb/puppetdb.log there should
be an error submitting the report present.
Note: this affects hosts running puppet 5.x which submit their reports
to the puppet master as well.
I would expect normally that reports are able to be stored without
error.
Thanks,
Kienan
-- System Information:
Debian Release: buster/sid
APT prefers testing
APT policy: (500, 'testing')
Architecture: amd64 (x86_64)
Kernel: Linux 4.19.0-2-amd64 (SMP w/2 CPU cores)
Kernel taint flags: TAINT_SOFTLOCKUP
Locale: LANG=en_CA.UTF-8, LC_CTYPE=en_CA.UTF-8 (charmap=UTF-8) (ignored: LC_ALL set to en_CA.UTF-8), LANGUAGE=en_CA.UTF-8 (charmap=UTF-8) (ignored: LC_ALL set to en_CA.UTF-8)
Shell: /bin/sh linked to /usr/bin/dash
Init: systemd (via /run/systemd/system)
Versions of packages puppet depends on:
ii adduser 3.118
ii facter 3.11.0-1.1+b1
ii hiera 3.2.0-2
ii init-system-helpers 1.56+nmu1
ii lsb-base 10.2018112800
ii ruby 1:2.5.1
ii ruby-augeas 1:0.5.0-3+b6
ii ruby-deep-merge 1.1.1-1
ii ruby-shadow 2.5.0-1+b1
Versions of packages puppet recommends:
ii debconf-utils 1.5.70
ii lsb-release 10.2018112800
ii ruby-selinux 2.8-1+b1
Versions of packages puppet suggests:
pn ruby-hocon <none>
pn ruby-rrd <none>
-- Configuration Files:
/etc/puppet/auth.conf changed:
path /puppet/v3/environments
method find
allow *
path ~ ^/puppet/v3/catalog/([^/]+)$
method find
allow $1
path ~ ^/puppet/v3/node/([^/]+)$
method find
allow $1
path ~ ^/puppet/v3/report/([^/]+)$
method save
allow $1
path /puppet/v3/file
allow *
path /puppet/v3/status
method find
allow *
path /puppet-ca/v1/certificate_revocation_list/ca
method find
allow *
path /puppet-ca/v1/certificate/ca
auth any
method find
allow *
path /puppet-ca/v1/certificate/
auth any
method find
allow *
path /puppet-ca/v1/certificate_request
auth any
method find, save
allow *
path /
auth any
/etc/puppet/hiera.yaml changed:
---
:backends:
- yaml
:hierarchy:
- "node/%{::fqdn}"
- "location/%{::location}"
- "osfamily/%{::osfamily}"
- common
:yaml:
:datadir: "/etc/puppet/code/environments/%{::environment}/hieradata"
/etc/puppet/puppet.conf changed:
[main]
basemodulepath = /etc/puppet/code/modules:/usr/share/puppet/modules
codedir = /etc/puppet/code
environmentpath = /etc/puppet/code/environments
hiera_config = $confdir/hiera.yaml
hostprivkey = $privatekeydir/$certname.pem { mode = 640 }
logdir = /var/log/puppet
pluginfactsource = puppet:///pluginfacts
pluginsource = puppet:///plugins
privatekeydir = $ssldir/private_keys { group = service }
reports = puppetdb
rundir = /var/run/puppet
server = pm-buster.test
show_diff = false
ssldir = /var/lib/puppet/ssl
vardir = /var/cache/puppet
[agent]
certname = pm-buster.test
classfile = $statedir/classes.txt
default_schedules = false
environment = production
listen = false
localconfig = $vardir/localconfig
masterport = 8140
noop = false
pluginsync = true
report = true
runinterval = 1800
splay = false
splaylimit = 1800
usecacheonfailure = true
[master]
autosign = true
ca = true
certname = pm-buster.test
parser = current
ssldir = /var/lib/puppet/ssl
storeconfigs = true
storeconfigs_backend = puppetdb
strict_variables = false
-- no debconf information
-------------- next part --------------
2019-03-01T10:51:42.035-05:00 INFO [o.e.j.u.log] Logging initialized @36576ms to org.eclipse.jetty.util.log.Slf4jLog
2019-03-01T10:51:42.568-05:00 INFO [p.t.s.w.jetty9-core] Removing buggy security provider SunPKCS11 version 11
2019-03-01T10:51:45.384-05:00 INFO [p.t.s.w.jetty9-service] Initializing web server(s).
2019-03-01T10:51:45.471-05:00 INFO [p.p.pdb-routing] Starting PuppetDB, entering maintenance mode
2019-03-01T10:51:45.497-05:00 INFO [p.t.s.s.status-service] Registering status callback function for service 'puppetdb-status', version 6.2.0
2019-03-01T10:51:45.513-05:00 INFO [p.t.s.n.nrepl-service] nREPL service disabled, not starting
2019-03-01T10:51:45.513-05:00 INFO [p.t.s.w.jetty9-service] Starting web server(s).
2019-03-01T10:51:45.972-05:00 INFO [p.t.s.w.jetty9-core] Starting web server.
2019-03-01T10:51:45.974-05:00 INFO [o.e.j.s.Server] jetty-9.4.14.v20181114; built: unknown; git: unknown; jvm 11.0.2+9-Debian-3
2019-03-01T10:51:46.018-05:00 INFO [o.e.j.s.h.ContextHandler] Started o.e.j.s.h.ContextHandler at 5ee919c2{/pdb,null,AVAILABLE}
2019-03-01T10:51:46.018-05:00 INFO [o.e.j.s.h.ContextHandler] Started o.e.j.s.h.ContextHandler at 321bcb1a{/metrics,null,AVAILABLE}
2019-03-01T10:51:46.123-05:00 INFO [o.e.j.s.AbstractConnector] Started ServerConnector at 46563b36{HTTP/1.1,[http/1.1]}{localhost:8080}
2019-03-01T10:51:46.203-05:00 INFO [o.e.j.u.s.SslContextFactory] x509=X509 at 62ac5e99(private key,h=[pm-buster.test, puppet],w=[]) for SslContextFactory at 7a088f3[provider=null,keyStore=null,trustStore=null]
2019-03-01T10:51:46.521-05:00 INFO [o.e.j.u.s.SslContextFactory] No Cipher matching 'SSL_RSA_WITH_RC4_128_SHA' is supported
2019-03-01T10:51:46.523-05:00 INFO [o.e.j.u.s.SslContextFactory] No Cipher matching 'SSL_RSA_WITH_3DES_EDE_CBC_SHA' is supported
2019-03-01T10:51:46.523-05:00 INFO [o.e.j.u.s.SslContextFactory] No Cipher matching 'SSL_RSA_WITH_RC4_128_MD5' is supported
2019-03-01T10:51:46.524-05:00 WARN [o.e.j.u.s.S.config] No Client EndPointIdentificationAlgorithm configured for SslContextFactory at 7a088f3[provider=null,keyStore=null,trustStore=null]
2019-03-01T10:51:46.525-05:00 WARN [o.e.j.u.s.S.config] Weak cipher suite TLS_RSA_WITH_AES_256_CBC_SHA256 enabled for SslContextFactory at 7a088f3[provider=null,keyStore=null,trustStore=null]
2019-03-01T10:51:46.525-05:00 WARN [o.e.j.u.s.S.config] Weak cipher suite TLS_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory at 7a088f3[provider=null,keyStore=null,trustStore=null]
2019-03-01T10:51:46.525-05:00 WARN [o.e.j.u.s.S.config] Weak cipher suite TLS_RSA_WITH_AES_256_CBC_SHA enabled for SslContextFactory at 7a088f3[provider=null,keyStore=null,trustStore=null]
2019-03-01T10:51:46.526-05:00 WARN [o.e.j.u.s.S.config] Weak cipher suite TLS_RSA_WITH_AES_128_CBC_SHA256 enabled for SslContextFactory at 7a088f3[provider=null,keyStore=null,trustStore=null]
2019-03-01T10:51:46.526-05:00 WARN [o.e.j.u.s.S.config] Weak cipher suite TLS_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory at 7a088f3[provider=null,keyStore=null,trustStore=null]
2019-03-01T10:51:46.526-05:00 WARN [o.e.j.u.s.S.config] Weak cipher suite TLS_RSA_WITH_AES_128_CBC_SHA enabled for SslContextFactory at 7a088f3[provider=null,keyStore=null,trustStore=null]
2019-03-01T10:51:46.531-05:00 INFO [o.e.j.s.AbstractConnector] Started ServerConnector at 339799ae{SSL,[ssl, http/1.1]}{localhost:8081}
2019-03-01T10:51:46.534-05:00 INFO [o.e.j.s.Server] Started @41077ms
2019-03-01T10:51:46.540-05:00 INFO [p.t.s.s.status-core] Starting background monitoring of cpu usage metrics
2019-03-01T10:51:46.544-05:00 INFO [p.t.s.s.status-service] Registering status callback function for service 'status-service', version 0.7.1
2019-03-01T10:51:46.544-05:00 INFO [p.t.s.s.status-service] Registering status service HTTP API at /status
2019-03-01T10:51:46.552-05:00 INFO [o.e.j.s.h.ContextHandler] Started o.e.j.s.h.ContextHandler at 6967d9e3{/status,null,AVAILABLE}
2019-03-01T10:51:46.556-05:00 WARN [c.z.h.HikariConfig] The initializationFailFast propery is deprecated, see initializationFailTimeout
2019-03-01T10:51:46.560-05:00 INFO [c.z.h.HikariDataSource] PDBWritePool - Starting...
2019-03-01T10:51:46.593-05:00 INFO [c.z.h.HikariDataSource] PDBWritePool - Start completed.
2019-03-01T10:51:46.593-05:00 WARN [c.z.h.HikariConfig] The initializationFailFast propery is deprecated, see initializationFailTimeout
2019-03-01T10:51:46.593-05:00 INFO [c.z.h.HikariDataSource] PDBReadPool - Starting...
2019-03-01T10:51:46.595-05:00 INFO [c.z.h.HikariDataSource] PDBReadPool - Start completed.
2019-03-01T10:51:46.596-05:00 INFO [p.p.c.services] PuppetDB version 6.2.0
2019-03-01T10:51:46.596-05:00 WARN [c.z.h.HikariConfig] The initializationFailFast propery is deprecated, see initializationFailTimeout
2019-03-01T10:51:46.596-05:00 INFO [c.z.h.HikariDataSource] PDBMigrationsPool - Starting...
2019-03-01T10:51:46.600-05:00 INFO [c.z.h.HikariDataSource] PDBMigrationsPool - Start completed.
2019-03-01T10:51:47.101-05:00 INFO [p.p.s.migrate] There are no pending migrations
2019-03-01T10:51:47.114-05:00 WARN [p.p.s.migrate] Missing PostgreSQL extension `pg_trgm`
We are unable to create the recommended pg_trgm indexes due to
the extension not being installed correctly. Run the command:
CREATE EXTENSION pg_trgm;
as the database super user on the PuppetDB database to correct
this, then restart PuppetDB.
2019-03-01T10:51:47.216-05:00 INFO [c.z.h.HikariDataSource] PDBMigrationsPool - Shutdown initiated...
2019-03-01T10:51:47.277-05:00 INFO [c.z.h.HikariDataSource] PDBMigrationsPool - Shutdown completed.
2019-03-01T10:51:47.364-05:00 INFO [p.p.pdb-routing] PuppetDB finished starting, disabling maintenance mode
2019-03-01T10:51:47.365-05:00 INFO [p.p.dashboard] Redirecting / to the PuppetDB dashboard
2019-03-01T10:51:47.368-05:00 INFO [p.p.c.services] Starting sweep of stale nodes (threshold: 7 days)
2019-03-01T10:51:47.374-05:00 INFO [o.e.j.s.h.ContextHandler] Started o.e.j.s.h.ContextHandler at 6b9971b{/,null,AVAILABLE}
2019-03-01T10:51:47.577-05:00 INFO [p.p.c.services] Finished sweep of stale nodes (threshold: 7 days)
2019-03-01T10:51:47.579-05:00 INFO [p.p.c.services] Starting purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T10:51:47.600-05:00 INFO [p.p.c.services] Finished purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T10:51:47.605-05:00 INFO [p.p.c.services] Starting sweep of stale reports (threshold: 14 days)
2019-03-01T10:51:47.613-05:00 INFO [p.p.c.services] Finished sweep of stale reports (threshold: 14 days)
2019-03-01T10:51:47.613-05:00 INFO [p.p.c.services] Starting gc packages
2019-03-01T10:51:47.629-05:00 INFO [p.p.c.services] Finished gc packages
2019-03-01T10:51:47.629-05:00 INFO [p.p.c.services] Starting database garbage collection
2019-03-01T10:51:49.819-05:00 INFO [p.p.c.services] Finished database garbage collection
2019-03-01T10:52:10.788-05:00 INFO [p.p.command] [1-1551455529513] [848 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T10:52:18.248-05:00 INFO [p.p.command] [2-1551455535799] [1066 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T10:52:25.203-05:00 ERROR [p.p.command] [3] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "d77041a7-2d54-442f-9ef1-d856bb00a661", :producer "pm-buster.test", :transaction_uuid "9853b465-43e7-4673-b25b-5b31bece782c", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file nil, :line nil, :level "info", :message "Using configured environment 'production'", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:03.219-05:00"} {:file nil, :line nil, :level "info", :message "Retrieving pluginfacts", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:03.223-05:00"} {:file nil, :line nil, :level "info", :message "Retrieving plugin", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:03.577-05:00"} {:file nil, :line nil, :level "info", :message "Retrieving locales", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:05.923-05:00"} {:file nil, :line nil, :level "info", :message "Loading facts", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:06.080-05:00"} {:file nil, :line nil, :level "info", :message "Caching catalog for pm-buster.test", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:17.621-05:00"} {:file nil, :line nil, :level "info", :message "Applying configuration version '1551455530'", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:19.203-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "\n--- /etc/resolv.conf\t2019-03-01 10:51:04.915969824 -0500\n+++ /tmp/puppet-file20190301-2927-1m5g5dp\t2019-03-01 10:52:19.916015398 -0500\n@@ -1 +1,4 @@\n+# This file is managed by Puppet, do not edit\n+search test.\n+options timeout:1 attempts:3 rotate\n nameserver 192.168.121.1\n", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test" "content"], :time "2019-03-01T10:52:19.956-05:00"} {:file nil, :line nil, :level "info", :message "Computing checksum on file /etc/resolv.conf", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:19.957-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "info", :message "Filebucketed /etc/resolv.conf to puppet with sum d13d36cc65371591ba7ffcd99ff9606f", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]", :tags ["info" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T10:52:20.042-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "\n", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test" "content"], :time "2019-03-01T10:52:20.553-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T10:52:20.553-05:00"} {:file "/etc/puppet/code/environments/production/site/profile/manifests/kgb.pp", :line 62, :level "notice", :message "ensure changed 'running' to 'stopped'", :source "/Stage[main]/Profile::Kgb/Service[kgb-bot]/ensure", :tags ["notice" "service" "kgb-bot" "class" "profile::kgb" "profile" "kgb" "profile::puppetmaster" "puppetmaster" "role::puppetmaster" "role" "node" "__node_regexp__pm-..test"], :time "2019-03-01T10:52:21.609-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 5.87 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T10:52:24.797-05:00"}], :report_format 10, :start_time "2019-03-01T10:52:00.973-05:00", :producer_timestamp "2019-03-01T10:52:24.974-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T10:52:24.658-05:00", :status "changed", :configuration_version "1551455530", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T10:52:19.956-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T10:52:19.917-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]} {:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T10:52:21.469-05:00", :property "ensure", :new_value "stopped", :old_value "running", :corrective_change true, :message "ensure changed 'running' to 'stopped'"}], :file "/etc/puppet/code/environments/production/site/profile/manifests/kgb.pp", :skipped false, :line 62, :resource_type "Service", :resource_title "kgb-bot", :timestamp "2019-03-01T10:52:21.460-05:00", :containment_path ["Stage[main]" "Profile::Kgb" "Service[kgb-bot]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 2} {:category "resources", :name "corrective_change", :value 2} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 2} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.238483582} {:category "time", :name "anchor", :value 6.22998E-4} {:category "time", :name "augeas", :value 0.1556729} {:category "time", :name "catalog_application", :value 5.870196116999992} {:category "time", :name "concat_file", :value "0.12272040000000002e-2"} {:category "time", :name "concat_fragment", :value 0.004791826999999999} {:category "time", :name "config_retrieval", :value 9.160155720999995} {:category "time", :name "convert_catalog", :value 0.5212088930000078} {:category "time", :name "cron", :value "0.10830455999999999e-1"} {:category "time", :name "exec", :value 0.03329694699999999} {:category "time", :name "fact_generation", :value 3.166122176000002} {:category "time", :name "file", :value 0.9598394740000001} {:category "time", :name "file_line", :value 0.030925632} {:category "time", :name "filebucket", :value 5.1613E-5} {:category "time", :name "group", :value 7.047539999999999E-4} {:category "time", :name "ini_setting", :value 0.011587495} {:category "time", :name "mailalias", :value 5.21121E-4} {:category "time", :name "node_retrieval", :value 2.106723505000005} {:category "time", :name "package", :value 0.008094969} {:category "time", :name "plugin_sync", :value 2.837535942999999} {:category "time", :name "postgresql_conf", :value 3.1503E-4} {:category "time", :name "postgresql_conn_validator", :value 0.044931501} {:category "time", :name "postgresql_psql", :value "0.12075166890000002e1"} {:category "time", :name "puppetdb_conn_validator", :value 0.046816893} {:category "time", :name "resources", :value 1.19959E-4} {:category "time", :name "service", :value "0.39336666200000003e0"} {:category "time", :name "ssh_authorized_key", :value 0.001369481} {:category "time", :name "sshkey", :value "0.17416799999999998e-2"} {:category "time", :name "total", :value 23.685176563} {:category "time", :name "transaction_evaluation", :value 5.662019271999995} {:category "time", :name "user", :value 0.03148150500000001} {:category "time", :name "vcsrepo", :value 0.456094928} {:category "changes", :name "total", :value 2} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 2} {:category "events", :name "total", :value 2}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "d77041a7-2d54-442f-9ef1-d856bb00a661", :producer "pm-buster.test", :transaction_uuid "9853b465-43e7-4673-b25b-5b31bece782c", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file nil, :line nil, :level "info", :message "Using configured environment 'production'", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:03.219-05:00"} {:file nil, :line nil, :level "info", :message "Retrieving pluginfacts", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:03.223-05:00"} {:file nil, :line nil, :level "info", :message "Retrieving plugin", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:03.577-05:00"} {:file nil, :line nil, :level "info", :message "Retrieving locales", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:05.923-05:00"} {:file nil, :line nil, :level "info", :message "Loading facts", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:06.080-05:00"} {:file nil, :line nil, :level "info", :message "Caching catalog for pm-buster.test", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:17.621-05:00"} {:file nil, :line nil, :level "info", :message "Applying configuration version '1551455530'", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:19.203-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "\n--- /etc/resolv.conf\t2019-03-01 10:51:04.915969824 -0500\n+++ /tmp/puppet-file20190301-2927-1m5g5dp\t2019-03-01 10:52:19.916015398 -0500\n@@ -1 +1,4 @@\n+# This file is managed by Puppet, do not edit\n+search test.\n+options timeout:1 attempts:3 rotate\n nameserver 192.168.121.1\n", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test" "content"], :time "2019-03-01T10:52:19.956-05:00"} {:file nil, :line nil, :level "info", :message "Computing checksum on file /etc/resolv.conf", :source "Puppet", :tags ["info"], :time "2019-03-01T10:52:19.957-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "info", :message "Filebucketed /etc/resolv.conf to puppet with sum d13d36cc65371591ba7ffcd99ff9606f", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]", :tags ["info" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T10:52:20.042-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "\n", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test" "content"], :time "2019-03-01T10:52:20.553-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T10:52:20.553-05:00"} {:file "/etc/puppet/code/environments/production/site/profile/manifests/kgb.pp", :line 62, :level "notice", :message "ensure changed 'running' to 'stopped'", :source "/Stage[main]/Profile::Kgb/Service[kgb-bot]/ensure", :tags ["notice" "service" "kgb-bot" "class" "profile::kgb" "profile" "kgb" "profile::puppetmaster" "puppetmaster" "role::puppetmaster" "role" "node" "__node_regexp__pm-..test"], :time "2019-03-01T10:52:21.609-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 5.87 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T10:52:24.797-05:00"}], :report_format 10, :start_time "2019-03-01T10:52:00.973-05:00", :producer_timestamp "2019-03-01T10:52:24.974-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T10:52:24.658-05:00", :status "changed", :configuration_version "1551455530", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T10:52:19.956-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T10:52:19.917-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]} {:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T10:52:21.469-05:00", :property "ensure", :new_value "stopped", :old_value "running", :corrective_change true, :message "ensure changed 'running' to 'stopped'"}], :file "/etc/puppet/code/environments/production/site/profile/manifests/kgb.pp", :skipped false, :line 62, :resource_type "Service", :resource_title "kgb-bot", :timestamp "2019-03-01T10:52:21.460-05:00", :containment_path ["Stage[main]" "Profile::Kgb" "Service[kgb-bot]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 2} {:category "resources", :name "corrective_change", :value 2} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 2} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.238483582} {:category "time", :name "anchor", :value 6.22998E-4} {:category "time", :name "augeas", :value 0.1556729} {:category "time", :name "catalog_application", :value 5.870196116999992} {:category "time", :name "concat_file", :value "0.12272040000000002e-2"} {:category "time", :name "concat_fragment", :value 0.004791826999999999} {:category "time", :name "config_retrieval", :value 9.160155720999995} {:category "time", :name "convert_catalog", :value 0.5212088930000078} {:category "time", :name "cron", :value "0.10830455999999999e-1"} {:category "time", :name "exec", :value 0.03329694699999999} {:category "time", :name "fact_generation", :value 3.166122176000002} {:category "time", :name "file", :value 0.9598394740000001} {:category "time", :name "file_line", :value 0.030925632} {:category "time", :name "filebucket", :value 5.1613E-5} {:category "time", :name "group", :value 7.047539999999999E-4} {:category "time", :name "ini_setting", :value 0.011587495} {:category "time", :name "mailalias", :value 5.21121E-4} {:category "time", :name "node_retrieval", :value 2.106723505000005} {:category "time", :name "package", :value 0.008094969} {:category "time", :name "plugin_sync", :value 2.837535942999999} {:category "time", :name "postgresql_conf", :value 3.1503E-4} {:category "time", :name "postgresql_conn_validator", :value 0.044931501} {:category "time", :name "postgresql_psql", :value "0.12075166890000002e1"} {:category "time", :name "puppetdb_conn_validator", :value 0.046816893} {:category "time", :name "resources", :value 1.19959E-4} {:category "time", :name "service", :value "0.39336666200000003e0"} {:category "time", :name "ssh_authorized_key", :value 0.001369481} {:category "time", :name "sshkey", :value "0.17416799999999998e-2"} {:category "time", :name "total", :value 23.685176563} {:category "time", :name "transaction_evaluation", :value 5.662019271999995} {:category "time", :name "user", :value 0.03148150500000001} {:category "time", :name "vcsrepo", :value 0.456094928} {:category "changes", :name "total", :value 2} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 2} {:category "events", :name "total", :value 2}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T10:57:15.405-05:00 INFO [p.p.command] [4-1551455835176] [173 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T10:57:15.926-05:00 INFO [p.p.command] [5-1551455835605] [262 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T10:58:20.945-05:00 INFO [p.p.command] [6-1551455900707] [177 ms] 'replace facts' command processed for debian-8-amd64.vagrantup.com
2019-03-01T10:58:21.092-05:00 INFO [p.p.command] [7-1551455901050] [23 ms] 'store report' puppet v4.8.2 command processed for debian-8-amd64.vagrantup.com
2019-03-01T11:01:41.143-05:00 INFO [p.p.command] [8-1551456101032] [69 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T11:01:44.592-05:00 INFO [p.p.command] [9-1551456102564] [1974 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T11:01:58.260-05:00 INFO [p.p.command] [10-1551456117769] [423 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T11:04:41.460-05:00 INFO [p.p.command] [11-1551456281312] [129 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T11:04:42.958-05:00 INFO [p.p.command] [12-1551456282817] [103 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T11:04:48.102-05:00 INFO [p.p.command] [13-1551456287831] [207 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T11:05:09.254-05:00 INFO [p.p.command] [14-1551456309153] [47 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T11:05:09.711-05:00 INFO [p.p.command] [15-1551456309327] [317 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T11:05:11.868-05:00 INFO [p.p.command] [16-1551456311456] [266 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T11:05:17.435-05:00 INFO [p.p.command] [17-1551456315176] [474 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T11:05:18.063-05:00 INFO [p.p.command] [18-1551456317647] [303 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T11:05:23.618-05:00 ERROR [p.p.command] [19] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "a24b2298-3f3f-4825-ac62-105fe3095a8f", :producer "pm-buster.test", :transaction_uuid "a436b563-6912-4cf3-8a61-fdaf937809b2", :puppet_version "5.5.10", :noop false, :corrective_change false, :logs [{:file nil, :line nil, :level "notice", :message "Applied catalog in 4.72 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T11:05:23.387-05:00"}], :report_format 10, :start_time "2019-03-01T11:05:03.415-05:00", :producer_timestamp "2019-03-01T11:05:23.493-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T11:05:23.275-05:00", :status "unchanged", :configuration_version "1551456309", :environment "production", :code_id nil, :resources [], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 0} {:category "resources", :name "corrective_change", :value 0} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 0} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.018150445} {:category "time", :name "anchor", :value 6.57819E-4} {:category "time", :name "augeas", :value 0.09498164599999998} {:category "time", :name "catalog_application", :value 4.72439934800002} {:category "time", :name "concat_file", :value 0.001170776} {:category "time", :name "concat_fragment", :value 0.004942717000000001} {:category "time", :name "config_retrieval", :value 9.075441557999966} {:category "time", :name "convert_catalog", :value "0.47644531699995696e0"} {:category "time", :name "cron", :value 0.009898126} {:category "time", :name "exec", :value "0.16716125999999998e-1"} {:category "time", :name "fact_generation", :value 4.115656595000019} {:category "time", :name "file", :value 0.206580288} {:category "time", :name "file_line", :value "0.16613286999999994e-1"} {:category "time", :name "filebucket", :value 8.9026E-5} {:category "time", :name "group", :value 5.395980000000001E-4} {:category "time", :name "ini_setting", :value 0.009769843000000002} {:category "time", :name "mailalias", :value 5.41444E-4} {:category "time", :name "node_retrieval", :value 0.3862406649999457} {:category "time", :name "package", :value 0.006246984999999999} {:category "time", :name "plugin_sync", :value "0.10696185159999914e1"} {:category "time", :name "postgresql_conf", :value 3.21201E-4} {:category "time", :name "postgresql_conn_validator", :value 0.044231919} {:category "time", :name "postgresql_psql", :value 1.006871333} {:category "time", :name "puppetdb_conn_validator", :value 0.068112395} {:category "time", :name "resources", :value 4.3281E-5} {:category "time", :name "service", :value "0.26922600599999996e0"} {:category "time", :name "ssh_authorized_key", :value "0.13582050000000001e-2"} {:category "time", :name "sshkey", :value 0.001750351} {:category "time", :name "total", :value 19.859962412} {:category "time", :name "transaction_evaluation", :value 4.556908619000069} {:category "time", :name "user", :value "0.27908618999999996e-1"} {:category "time", :name "vcsrepo", :value 0.051213614} {:category "changes", :name "total", :value 0} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 0} {:category "events", :name "total", :value 0}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "a24b2298-3f3f-4825-ac62-105fe3095a8f", :producer "pm-buster.test", :transaction_uuid "a436b563-6912-4cf3-8a61-fdaf937809b2", :puppet_version "5.5.10", :noop false, :corrective_change false, :logs [{:file nil, :line nil, :level "notice", :message "Applied catalog in 4.72 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T11:05:23.387-05:00"}], :report_format 10, :start_time "2019-03-01T11:05:03.415-05:00", :producer_timestamp "2019-03-01T11:05:23.493-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T11:05:23.275-05:00", :status "unchanged", :configuration_version "1551456309", :environment "production", :code_id nil, :resources [], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 0} {:category "resources", :name "corrective_change", :value 0} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 0} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.018150445} {:category "time", :name "anchor", :value 6.57819E-4} {:category "time", :name "augeas", :value 0.09498164599999998} {:category "time", :name "catalog_application", :value 4.72439934800002} {:category "time", :name "concat_file", :value 0.001170776} {:category "time", :name "concat_fragment", :value 0.004942717000000001} {:category "time", :name "config_retrieval", :value 9.075441557999966} {:category "time", :name "convert_catalog", :value "0.47644531699995696e0"} {:category "time", :name "cron", :value 0.009898126} {:category "time", :name "exec", :value "0.16716125999999998e-1"} {:category "time", :name "fact_generation", :value 4.115656595000019} {:category "time", :name "file", :value 0.206580288} {:category "time", :name "file_line", :value "0.16613286999999994e-1"} {:category "time", :name "filebucket", :value 8.9026E-5} {:category "time", :name "group", :value 5.395980000000001E-4} {:category "time", :name "ini_setting", :value 0.009769843000000002} {:category "time", :name "mailalias", :value 5.41444E-4} {:category "time", :name "node_retrieval", :value 0.3862406649999457} {:category "time", :name "package", :value 0.006246984999999999} {:category "time", :name "plugin_sync", :value "0.10696185159999914e1"} {:category "time", :name "postgresql_conf", :value 3.21201E-4} {:category "time", :name "postgresql_conn_validator", :value 0.044231919} {:category "time", :name "postgresql_psql", :value 1.006871333} {:category "time", :name "puppetdb_conn_validator", :value 0.068112395} {:category "time", :name "resources", :value 4.3281E-5} {:category "time", :name "service", :value "0.26922600599999996e0"} {:category "time", :name "ssh_authorized_key", :value "0.13582050000000001e-2"} {:category "time", :name "sshkey", :value 0.001750351} {:category "time", :name "total", :value 19.859962412} {:category "time", :name "transaction_evaluation", :value 4.556908619000069} {:category "time", :name "user", :value "0.27908618999999996e-1"} {:category "time", :name "vcsrepo", :value 0.051213614} {:category "changes", :name "total", :value 0} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 0} {:category "events", :name "total", :value 0}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T11:07:18.952-05:00 INFO [p.p.command] [20-1551456438725] [142 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T11:07:20.542-05:00 INFO [p.p.command] [21-1551456440274] [176 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T11:12:21.115-05:00 INFO [p.p.command] [22-1551456740814] [220 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T11:35:06.307-05:00 INFO [p.p.command] [23-1551458106159] [71 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T11:35:09.677-05:00 INFO [p.p.command] [24-1551458109073] [260 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T11:35:10.798-05:00 INFO [p.p.command] [25-1551458110348] [317 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T11:35:12.389-05:00 INFO [p.p.command] [26-1551458111886] [289 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T11:35:17.397-05:00 ERROR [p.p.command] [27] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "ca0b94ba-d38f-4515-a1a4-0ab1a29b62e2", :producer "pm-buster.test", :transaction_uuid "adc4839a-81ba-4e98-8e4d-a17064101db5", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T11:35:13.688-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 4.20 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T11:35:16.866-05:00"}], :report_format 10, :start_time "2019-03-01T11:35:02.629-05:00", :producer_timestamp "2019-03-01T11:35:17.056-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T11:35:16.766-05:00", :status "changed", :configuration_version "1551458106", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T11:35:13.583-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T11:35:13.583-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.016150343} {:category "time", :name "anchor", :value 8.99373E-4} {:category "time", :name "augeas", :value "0.10101088999999998e0"} {:category "time", :name "catalog_application", :value 4.196031463000054} {:category "time", :name "concat_file", :value "0.15430840000000001e-2"} {:category "time", :name "concat_fragment", :value 0.005170757} {:category "time", :name "config_retrieval", :value 5.952028365999922} {:category "time", :name "convert_catalog", :value 0.6189900509998552} {:category "time", :name "cron", :value 0.011428451} {:category "time", :name "exec", :value 0.01714186} {:category "time", :name "fact_generation", :value "0.24470924530000957e1"} {:category "time", :name "file", :value "0.39782662700000027e0"} {:category "time", :name "file_line", :value 0.017621198} {:category "time", :name "filebucket", :value 7.5924E-5} {:category "time", :name "group", :value 5.07741E-4} {:category "time", :name "ini_setting", :value "0.14319612000000002e-1"} {:category "time", :name "mailalias", :value 8.62389E-4} {:category "time", :name "node_retrieval", :value 0.07336096599965458} {:category "time", :name "package", :value 0.007377685999999999} {:category "time", :name "plugin_sync", :value 0.8381829330000983} {:category "time", :name "postgresql_conf", :value "0.46049199999999995e-3"} {:category "time", :name "postgresql_conn_validator", :value 0.055248738} {:category "time", :name "postgresql_psql", :value 1.053028383} {:category "time", :name "puppetdb_conn_validator", :value 0.048095735} {:category "time", :name "resources", :value 7.988E-5} {:category "time", :name "service", :value 0.278275148} {:category "time", :name "ssh_authorized_key", :value 0.001587614} {:category "time", :name "sshkey", :value "0.32843209999999998e-2"} {:category "time", :name "total", :value 14.136575548} {:category "time", :name "transaction_evaluation", :value 4.020415585000137} {:category "time", :name "user", :value 0.031541542} {:category "time", :name "vcsrepo", :value 0.053786429} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "ca0b94ba-d38f-4515-a1a4-0ab1a29b62e2", :producer "pm-buster.test", :transaction_uuid "adc4839a-81ba-4e98-8e4d-a17064101db5", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T11:35:13.688-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 4.20 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T11:35:16.866-05:00"}], :report_format 10, :start_time "2019-03-01T11:35:02.629-05:00", :producer_timestamp "2019-03-01T11:35:17.056-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T11:35:16.766-05:00", :status "changed", :configuration_version "1551458106", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T11:35:13.583-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T11:35:13.583-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.016150343} {:category "time", :name "anchor", :value 8.99373E-4} {:category "time", :name "augeas", :value "0.10101088999999998e0"} {:category "time", :name "catalog_application", :value 4.196031463000054} {:category "time", :name "concat_file", :value "0.15430840000000001e-2"} {:category "time", :name "concat_fragment", :value 0.005170757} {:category "time", :name "config_retrieval", :value 5.952028365999922} {:category "time", :name "convert_catalog", :value 0.6189900509998552} {:category "time", :name "cron", :value 0.011428451} {:category "time", :name "exec", :value 0.01714186} {:category "time", :name "fact_generation", :value "0.24470924530000957e1"} {:category "time", :name "file", :value "0.39782662700000027e0"} {:category "time", :name "file_line", :value 0.017621198} {:category "time", :name "filebucket", :value 7.5924E-5} {:category "time", :name "group", :value 5.07741E-4} {:category "time", :name "ini_setting", :value "0.14319612000000002e-1"} {:category "time", :name "mailalias", :value 8.62389E-4} {:category "time", :name "node_retrieval", :value 0.07336096599965458} {:category "time", :name "package", :value 0.007377685999999999} {:category "time", :name "plugin_sync", :value 0.8381829330000983} {:category "time", :name "postgresql_conf", :value "0.46049199999999995e-3"} {:category "time", :name "postgresql_conn_validator", :value 0.055248738} {:category "time", :name "postgresql_psql", :value 1.053028383} {:category "time", :name "puppetdb_conn_validator", :value 0.048095735} {:category "time", :name "resources", :value 7.988E-5} {:category "time", :name "service", :value 0.278275148} {:category "time", :name "ssh_authorized_key", :value 0.001587614} {:category "time", :name "sshkey", :value "0.32843209999999998e-2"} {:category "time", :name "total", :value 14.136575548} {:category "time", :name "transaction_evaluation", :value 4.020415585000137} {:category "time", :name "user", :value 0.031541542} {:category "time", :name "vcsrepo", :value 0.053786429} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T11:35:17.547-05:00 INFO [p.p.command] [28-1551458117376] [83 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T11:51:49.833-05:00 INFO [p.p.c.services] Starting sweep of stale nodes (threshold: 7 days)
2019-03-01T11:51:49.847-05:00 INFO [p.p.c.services] Finished sweep of stale nodes (threshold: 7 days)
2019-03-01T11:51:49.850-05:00 INFO [p.p.c.services] Starting purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T11:51:49.853-05:00 INFO [p.p.c.services] Finished purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T11:51:49.854-05:00 INFO [p.p.c.services] Starting sweep of stale reports (threshold: 14 days)
2019-03-01T11:51:49.856-05:00 INFO [p.p.c.services] Finished sweep of stale reports (threshold: 14 days)
2019-03-01T11:51:49.857-05:00 INFO [p.p.c.services] Starting gc packages
2019-03-01T11:51:49.859-05:00 INFO [p.p.c.services] Finished gc packages
2019-03-01T11:51:49.859-05:00 INFO [p.p.c.services] Starting database garbage collection
2019-03-01T11:51:50.015-05:00 INFO [p.p.c.services] Finished database garbage collection
2019-03-01T12:05:07.231-05:00 INFO [p.p.command] [29-1551459907163] [46 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T12:05:09.364-05:00 INFO [p.p.command] [30-1551459909290] [21 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T12:05:11.248-05:00 INFO [p.p.command] [31-1551459910836] [335 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T12:05:11.393-05:00 INFO [p.p.command] [32-1551459910939] [117 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T12:05:16.351-05:00 INFO [p.p.command] [33-1551459916016] [94 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T12:05:16.653-05:00 ERROR [p.p.command] [34] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "9c96c238-4124-4cd3-9aaa-dc796cb3cc31", :producer "pm-buster.test", :transaction_uuid "af56b74a-a6da-47a4-80c2-26a014a2da57", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T12:05:13.642-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 3.84 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T12:05:16.378-05:00"}], :report_format 10, :start_time "2019-03-01T12:05:03.483-05:00", :producer_timestamp "2019-03-01T12:05:16.525-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T12:05:16.264-05:00", :status "changed", :configuration_version "1551459907", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T12:05:13.531-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T12:05:13.530-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.015411462} {:category "time", :name "anchor", :value 6.551119999999999E-4} {:category "time", :name "augeas", :value 0.119178755} {:category "time", :name "catalog_application", :value "0.38408986180002103e1"} {:category "time", :name "concat_file", :value 0.001676251} {:category "time", :name "concat_fragment", :value 0.005806420000000003} {:category "time", :name "config_retrieval", :value 4.94423703800021} {:category "time", :name "convert_catalog", :value "0.49642435399982787e0"} {:category "time", :name "cron", :value "0.10336907000000001e-1"} {:category "time", :name "exec", :value "0.18339582000000004e-1"} {:category "time", :name "fact_generation", :value "0.24479117060000135e1"} {:category "time", :name "file", :value 0.3481824560000004} {:category "time", :name "file_line", :value "0.25796953000000008e-1"} {:category "time", :name "filebucket", :value 5.1111E-5} {:category "time", :name "group", :value 5.61145E-4} {:category "time", :name "ini_setting", :value 0.009709293} {:category "time", :name "mailalias", :value 5.72695E-4} {:category "time", :name "node_retrieval", :value 0.0905678760000228} {:category "time", :name "package", :value 0.007278469000000002} {:category "time", :name "plugin_sync", :value 0.9371830380005122} {:category "time", :name "postgresql_conf", :value 0.001751818} {:category "time", :name "postgresql_conn_validator", :value 0.053306824} {:category "time", :name "postgresql_psql", :value 1.092207949} {:category "time", :name "puppetdb_conn_validator", :value 0.030178596} {:category "time", :name "resources", :value 6.7065E-5} {:category "time", :name "service", :value 0.245478936} {:category "time", :name "ssh_authorized_key", :value 0.001870915} {:category "time", :name "sshkey", :value "0.23997299999999997e-2"} {:category "time", :name "total", :value 12.781292363} {:category "time", :name "transaction_evaluation", :value 3.596623489999729} {:category "time", :name "user", :value "0.53181260999999994e-1"} {:category "time", :name "vcsrepo", :value 0.045940647} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "9c96c238-4124-4cd3-9aaa-dc796cb3cc31", :producer "pm-buster.test", :transaction_uuid "af56b74a-a6da-47a4-80c2-26a014a2da57", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T12:05:13.642-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 3.84 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T12:05:16.378-05:00"}], :report_format 10, :start_time "2019-03-01T12:05:03.483-05:00", :producer_timestamp "2019-03-01T12:05:16.525-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T12:05:16.264-05:00", :status "changed", :configuration_version "1551459907", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T12:05:13.531-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T12:05:13.530-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.015411462} {:category "time", :name "anchor", :value 6.551119999999999E-4} {:category "time", :name "augeas", :value 0.119178755} {:category "time", :name "catalog_application", :value "0.38408986180002103e1"} {:category "time", :name "concat_file", :value 0.001676251} {:category "time", :name "concat_fragment", :value 0.005806420000000003} {:category "time", :name "config_retrieval", :value 4.94423703800021} {:category "time", :name "convert_catalog", :value "0.49642435399982787e0"} {:category "time", :name "cron", :value "0.10336907000000001e-1"} {:category "time", :name "exec", :value "0.18339582000000004e-1"} {:category "time", :name "fact_generation", :value "0.24479117060000135e1"} {:category "time", :name "file", :value 0.3481824560000004} {:category "time", :name "file_line", :value "0.25796953000000008e-1"} {:category "time", :name "filebucket", :value 5.1111E-5} {:category "time", :name "group", :value 5.61145E-4} {:category "time", :name "ini_setting", :value 0.009709293} {:category "time", :name "mailalias", :value 5.72695E-4} {:category "time", :name "node_retrieval", :value 0.0905678760000228} {:category "time", :name "package", :value 0.007278469000000002} {:category "time", :name "plugin_sync", :value 0.9371830380005122} {:category "time", :name "postgresql_conf", :value 0.001751818} {:category "time", :name "postgresql_conn_validator", :value 0.053306824} {:category "time", :name "postgresql_psql", :value 1.092207949} {:category "time", :name "puppetdb_conn_validator", :value 0.030178596} {:category "time", :name "resources", :value 6.7065E-5} {:category "time", :name "service", :value 0.245478936} {:category "time", :name "ssh_authorized_key", :value 0.001870915} {:category "time", :name "sshkey", :value "0.23997299999999997e-2"} {:category "time", :name "total", :value 12.781292363} {:category "time", :name "transaction_evaluation", :value 3.596623489999729} {:category "time", :name "user", :value "0.53181260999999994e-1"} {:category "time", :name "vcsrepo", :value 0.045940647} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T14:19:25.940-05:00 WARN [c.z.h.p.HikariPool] PDBReadPool - Thread starvation or clock leap detected (housekeeper delta=1h53m9s174ms256µs114ns).
2019-03-01T14:19:25.940-05:00 WARN [c.z.h.p.HikariPool] PDBWritePool - Thread starvation or clock leap detected (housekeeper delta=1h53m9s181ms906µs54ns).
2019-03-01T14:19:26.046-05:00 INFO [p.p.c.services] Starting sweep of stale nodes (threshold: 7 days)
2019-03-01T14:19:26.238-05:00 INFO [p.p.c.services] Finished sweep of stale nodes (threshold: 7 days)
2019-03-01T14:19:26.239-05:00 INFO [p.p.c.services] Starting purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T14:19:26.244-05:00 INFO [p.p.c.services] Finished purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T14:19:26.252-05:00 INFO [p.p.c.services] Starting sweep of stale reports (threshold: 14 days)
2019-03-01T14:19:26.259-05:00 INFO [p.p.c.services] Finished sweep of stale reports (threshold: 14 days)
2019-03-01T14:19:26.265-05:00 INFO [p.p.c.services] Starting gc packages
2019-03-01T14:19:26.270-05:00 INFO [p.p.c.services] Finished gc packages
2019-03-01T14:19:26.270-05:00 INFO [p.p.c.services] Starting database garbage collection
2019-03-01T14:19:26.586-05:00 INFO [p.p.c.services] Finished database garbage collection
2019-03-01T14:35:08.706-05:00 INFO [p.p.command] [35-1551468908342] [69 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T14:35:10.523-05:00 INFO [p.p.command] [36-1551468910421] [43 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T14:35:12.716-05:00 INFO [p.p.command] [37-1551468912494] [141 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T14:35:13.447-05:00 INFO [p.p.command] [38-1551468913066] [268 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T14:35:18.786-05:00 INFO [p.p.command] [39-1551468918311] [182 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T14:35:20.202-05:00 ERROR [p.p.command] [40] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "b17b1e40-1799-4387-9bb2-7ee0c04d5f3d", :producer "pm-buster.test", :transaction_uuid "54bd0f85-c1e0-4151-ba31-7d08f39c38f7", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T14:35:16.693-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 4.55 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T14:35:19.935-05:00"}], :report_format 10, :start_time "2019-03-01T14:35:04.287-05:00", :producer_timestamp "2019-03-01T14:35:20.074-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T14:35:19.778-05:00", :status "changed", :configuration_version "1551468908", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T14:35:16.570-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T14:35:16.569-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.01134836} {:category "time", :name "anchor", :value 8.585180000000001E-4} {:category "time", :name "augeas", :value 0.121080613} {:category "time", :name "catalog_application", :value "0.45488191040003585e1"} {:category "time", :name "concat_file", :value "0.17759619999999999e-2"} {:category "time", :name "concat_fragment", :value 0.008269593000000002} {:category "time", :name "config_retrieval", :value 6.457958567998503} {:category "time", :name "convert_catalog", :value 0.6463838390009187} {:category "time", :name "cron", :value "0.14279469000000001e-1"} {:category "time", :name "exec", :value "0.17967946999999995e-1"} {:category "time", :name "fact_generation", :value 2.763419627000985} {:category "time", :name "file", :value "0.42767202400000026e0"} {:category "time", :name "file_line", :value 0.015984944} {:category "time", :name "filebucket", :value 1.78902E-4} {:category "time", :name "group", :value 5.27507E-4} {:category "time", :name "ini_setting", :value "0.18059063999999996e-1"} {:category "time", :name "mailalias", :value 9.260210000000001E-4} {:category "time", :name "node_retrieval", :value "0.21707907800009707e0"} {:category "time", :name "package", :value 0.008047974} {:category "time", :name "plugin_sync", :value 0.8409000249994278} {:category "time", :name "postgresql_conf", :value 3.86824E-4} {:category "time", :name "postgresql_conn_validator", :value 0.065464642} {:category "time", :name "postgresql_psql", :value "0.12035237329999997e1"} {:category "time", :name "puppetdb_conn_validator", :value 0.05091857} {:category "time", :name "resources", :value 4.6974E-5} {:category "time", :name "service", :value 0.30988784} {:category "time", :name "ssh_authorized_key", :value "0.17625800000000001e-2"} {:category "time", :name "sshkey", :value 0.001939414} {:category "time", :name "total", :value 15.490525718} {:category "time", :name "transaction_evaluation", :value 4.351346636998642} {:category "time", :name "user", :value 0.033628063} {:category "time", :name "vcsrepo", :value 0.048034738} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "b17b1e40-1799-4387-9bb2-7ee0c04d5f3d", :producer "pm-buster.test", :transaction_uuid "54bd0f85-c1e0-4151-ba31-7d08f39c38f7", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T14:35:16.693-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 4.55 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T14:35:19.935-05:00"}], :report_format 10, :start_time "2019-03-01T14:35:04.287-05:00", :producer_timestamp "2019-03-01T14:35:20.074-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T14:35:19.778-05:00", :status "changed", :configuration_version "1551468908", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T14:35:16.570-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T14:35:16.569-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.01134836} {:category "time", :name "anchor", :value 8.585180000000001E-4} {:category "time", :name "augeas", :value 0.121080613} {:category "time", :name "catalog_application", :value "0.45488191040003585e1"} {:category "time", :name "concat_file", :value "0.17759619999999999e-2"} {:category "time", :name "concat_fragment", :value 0.008269593000000002} {:category "time", :name "config_retrieval", :value 6.457958567998503} {:category "time", :name "convert_catalog", :value 0.6463838390009187} {:category "time", :name "cron", :value "0.14279469000000001e-1"} {:category "time", :name "exec", :value "0.17967946999999995e-1"} {:category "time", :name "fact_generation", :value 2.763419627000985} {:category "time", :name "file", :value "0.42767202400000026e0"} {:category "time", :name "file_line", :value 0.015984944} {:category "time", :name "filebucket", :value 1.78902E-4} {:category "time", :name "group", :value 5.27507E-4} {:category "time", :name "ini_setting", :value "0.18059063999999996e-1"} {:category "time", :name "mailalias", :value 9.260210000000001E-4} {:category "time", :name "node_retrieval", :value "0.21707907800009707e0"} {:category "time", :name "package", :value 0.008047974} {:category "time", :name "plugin_sync", :value 0.8409000249994278} {:category "time", :name "postgresql_conf", :value 3.86824E-4} {:category "time", :name "postgresql_conn_validator", :value 0.065464642} {:category "time", :name "postgresql_psql", :value "0.12035237329999997e1"} {:category "time", :name "puppetdb_conn_validator", :value 0.05091857} {:category "time", :name "resources", :value 4.6974E-5} {:category "time", :name "service", :value 0.30988784} {:category "time", :name "ssh_authorized_key", :value "0.17625800000000001e-2"} {:category "time", :name "sshkey", :value 0.001939414} {:category "time", :name "total", :value 15.490525718} {:category "time", :name "transaction_evaluation", :value 4.351346636998642} {:category "time", :name "user", :value 0.033628063} {:category "time", :name "vcsrepo", :value 0.048034738} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T15:05:07.113-05:00 INFO [p.p.command] [41-1551470706992] [61 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T15:05:08.657-05:00 INFO [p.p.command] [42-1551470708569] [35 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T15:05:10.720-05:00 INFO [p.p.command] [43-1551470710539] [111 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T15:05:11.175-05:00 INFO [p.p.command] [44-1551470710935] [142 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T15:05:15.863-05:00 INFO [p.p.command] [45-1551470715485] [105 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T15:05:16.737-05:00 ERROR [p.p.command] [46] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "3b8713cb-5ffb-4b13-9ee8-bf4f017a3478", :producer "pm-buster.test", :transaction_uuid "840a5303-cd75-492c-ad4a-2eb015eb14b6", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T15:05:13.688-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 3.87 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T15:05:16.478-05:00"}], :report_format 10, :start_time "2019-03-01T15:05:03.579-05:00", :producer_timestamp "2019-03-01T15:05:16.607-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T15:05:16.371-05:00", :status "changed", :configuration_version "1551470707", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T15:05:13.536-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T15:05:13.536-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.017597596} {:category "time", :name "anchor", :value 6.5284E-4} {:category "time", :name "augeas", :value "0.15231939100000003e0"} {:category "time", :name "catalog_application", :value "0.38708464939991245e1"} {:category "time", :name "concat_file", :value "0.15257089999999997e-2"} {:category "time", :name "concat_fragment", :value "0.53421499999999995e-2"} {:category "time", :name "config_retrieval", :value 5.031752345999848} {:category "time", :name "convert_catalog", :value 0.6375204600008146} {:category "time", :name "cron", :value 0.010278383} {:category "time", :name "exec", :value "0.17361490000000007e-1"} {:category "time", :name "fact_generation", :value 2.378217944999051} {:category "time", :name "file", :value 0.3984576920000002} {:category "time", :name "file_line", :value "0.17151154000000005e-1"} {:category "time", :name "filebucket", :value 5.2526E-5} {:category "time", :name "group", :value 7.58616E-4} {:category "time", :name "ini_setting", :value 0.017925482} {:category "time", :name "mailalias", :value 5.8747E-4} {:category "time", :name "node_retrieval", :value 0.08425932799946168} {:category "time", :name "package", :value 0.008052508} {:category "time", :name "plugin_sync", :value 0.7695243200014374} {:category "time", :name "postgresql_conf", :value 4.40605E-4} {:category "time", :name "postgresql_conn_validator", :value 0.049734359} {:category "time", :name "postgresql_psql", :value 1.126586841} {:category "time", :name "puppetdb_conn_validator", :value 0.052542992} {:category "time", :name "resources", :value 7.7195E-5} {:category "time", :name "service", :value 0.262331896} {:category "time", :name "ssh_authorized_key", :value 0.002336262} {:category "time", :name "sshkey", :value 0.001998642} {:category "time", :name "total", :value 12.791951051} {:category "time", :name "transaction_evaluation", :value "0.37039868350002507e1"} {:category "time", :name "user", :value "0.48385818000000004e-1"} {:category "time", :name "vcsrepo", :value "0.54407422999999996e-1"} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "3b8713cb-5ffb-4b13-9ee8-bf4f017a3478", :producer "pm-buster.test", :transaction_uuid "840a5303-cd75-492c-ad4a-2eb015eb14b6", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T15:05:13.688-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 3.87 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T15:05:16.478-05:00"}], :report_format 10, :start_time "2019-03-01T15:05:03.579-05:00", :producer_timestamp "2019-03-01T15:05:16.607-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T15:05:16.371-05:00", :status "changed", :configuration_version "1551470707", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T15:05:13.536-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T15:05:13.536-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.017597596} {:category "time", :name "anchor", :value 6.5284E-4} {:category "time", :name "augeas", :value "0.15231939100000003e0"} {:category "time", :name "catalog_application", :value "0.38708464939991245e1"} {:category "time", :name "concat_file", :value "0.15257089999999997e-2"} {:category "time", :name "concat_fragment", :value "0.53421499999999995e-2"} {:category "time", :name "config_retrieval", :value 5.031752345999848} {:category "time", :name "convert_catalog", :value 0.6375204600008146} {:category "time", :name "cron", :value 0.010278383} {:category "time", :name "exec", :value "0.17361490000000007e-1"} {:category "time", :name "fact_generation", :value 2.378217944999051} {:category "time", :name "file", :value 0.3984576920000002} {:category "time", :name "file_line", :value "0.17151154000000005e-1"} {:category "time", :name "filebucket", :value 5.2526E-5} {:category "time", :name "group", :value 7.58616E-4} {:category "time", :name "ini_setting", :value 0.017925482} {:category "time", :name "mailalias", :value 5.8747E-4} {:category "time", :name "node_retrieval", :value 0.08425932799946168} {:category "time", :name "package", :value 0.008052508} {:category "time", :name "plugin_sync", :value 0.7695243200014374} {:category "time", :name "postgresql_conf", :value 4.40605E-4} {:category "time", :name "postgresql_conn_validator", :value 0.049734359} {:category "time", :name "postgresql_psql", :value 1.126586841} {:category "time", :name "puppetdb_conn_validator", :value 0.052542992} {:category "time", :name "resources", :value 7.7195E-5} {:category "time", :name "service", :value 0.262331896} {:category "time", :name "ssh_authorized_key", :value 0.002336262} {:category "time", :name "sshkey", :value 0.001998642} {:category "time", :name "total", :value 12.791951051} {:category "time", :name "transaction_evaluation", :value "0.37039868350002507e1"} {:category "time", :name "user", :value "0.48385818000000004e-1"} {:category "time", :name "vcsrepo", :value "0.54407422999999996e-1"} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T15:19:26.737-05:00 INFO [p.p.c.services] Starting sweep of stale nodes (threshold: 7 days)
2019-03-01T15:19:26.744-05:00 INFO [p.p.c.services] Finished sweep of stale nodes (threshold: 7 days)
2019-03-01T15:19:26.744-05:00 INFO [p.p.c.services] Starting purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T15:19:26.746-05:00 INFO [p.p.c.services] Finished purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T15:19:26.747-05:00 INFO [p.p.c.services] Starting sweep of stale reports (threshold: 14 days)
2019-03-01T15:19:26.748-05:00 INFO [p.p.c.services] Finished sweep of stale reports (threshold: 14 days)
2019-03-01T15:19:26.748-05:00 INFO [p.p.c.services] Starting gc packages
2019-03-01T15:19:26.750-05:00 INFO [p.p.c.services] Finished gc packages
2019-03-01T15:19:26.750-05:00 INFO [p.p.c.services] Starting database garbage collection
2019-03-01T15:19:26.833-05:00 INFO [p.p.c.services] Finished database garbage collection
2019-03-01T15:25:22.071-05:00 INFO [p.p.command] [47-1551471922017] [43 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T15:25:22.856-05:00 INFO [p.p.command] [48-1551471922258] [571 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T15:26:32.046-05:00 INFO [p.p.command] [49-1551471991925] [61 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T15:27:01.821-05:00 INFO [p.p.command] [50-1551472020713] [31 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T15:27:02.141-05:00 INFO [p.p.command] [51-1551472022023] [61 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T15:27:02.696-05:00 INFO [p.p.command] [52-1551472022591] [39 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T15:27:36.357-05:00 INFO [p.p.command] [53-1551472056230] [76 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T15:27:36.626-05:00 INFO [p.p.command] [54-1551472056539] [30 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T15:27:37.206-05:00 INFO [p.p.command] [55-1551472057119] [30 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T15:28:05.010-05:00 INFO [p.p.command] [56-1551472084975] [18 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T15:28:05.250-05:00 INFO [p.p.command] [57-1551472085205] [24 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T15:28:05.814-05:00 INFO [p.p.command] [58-1551472085778] [25 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T15:29:38.925-05:00 INFO [p.p.command] [59-1551472178875] [24 ms] 'replace facts' command processed for pc-stretch.test
2019-03-01T15:29:42.869-05:00 INFO [p.p.command] [60-1551472182022] [289 ms] 'replace catalog' command processed for pc-stretch.test
2019-03-01T15:29:47.523-05:00 INFO [p.p.command] [61-1551472187437] [30 ms] 'store report' puppet v4.8.2 command processed for pc-stretch.test
2019-03-01T15:31:18.138-05:00 INFO [p.p.command] [62-1551472278090] [27 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T15:31:18.371-05:00 INFO [p.p.command] [63-1551472278329] [21 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T15:31:18.756-05:00 INFO [p.p.command] [64-1551472278685] [31 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T15:35:06.674-05:00 INFO [p.p.command] [65-1551472506392] [71 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T15:35:08.572-05:00 INFO [p.p.command] [66-1551472508432] [49 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T15:35:08.935-05:00 INFO [p.p.command] [67-1551472508833] [42 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T15:35:09.507-05:00 INFO [p.p.command] [68-1551472509418] [36 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T15:35:10.596-05:00 INFO [p.p.command] [69-1551472510178] [141 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T15:35:15.915-05:00 ERROR [p.p.command] [70] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "fb79685a-e0c4-44c8-bb9f-bd35a4c8bb00", :producer "pm-buster.test", :transaction_uuid "78e9c2b4-e9cd-4a56-8f1d-b8f0323e38dd", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T15:35:12.885-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 3.87 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T15:35:15.641-05:00"}], :report_format 10, :start_time "2019-03-01T15:35:02.800-05:00", :producer_timestamp "2019-03-01T15:35:15.777-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T15:35:15.538-05:00", :status "changed", :configuration_version "1551472506", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T15:35:12.613-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T15:35:12.612-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.019795081} {:category "time", :name "anchor", :value 6.01893E-4} {:category "time", :name "augeas", :value 0.09577380999999999} {:category "time", :name "catalog_application", :value 3.869886745000258} {:category "time", :name "concat_file", :value 0.001167476} {:category "time", :name "concat_fragment", :value "0.48384970000000015e-2"} {:category "time", :name "config_retrieval", :value 4.974959022001713} {:category "time", :name "convert_catalog", :value "0.46641710000039893e0"} {:category "time", :name "cron", :value 0.01029718} {:category "time", :name "exec", :value 0.015293136} {:category "time", :name "fact_generation", :value 2.472045997998066} {:category "time", :name "file", :value "0.49866304400000017e0"} {:category "time", :name "file_line", :value 0.0181323} {:category "time", :name "filebucket", :value 7.6291E-5} {:category "time", :name "group", :value 4.3702E-4} {:category "time", :name "ini_setting", :value "0.10528771000000001e-1"} {:category "time", :name "mailalias", :value 6.84772E-4} {:category "time", :name "node_retrieval", :value "0.10985619500206667e0"} {:category "time", :name "package", :value 0.007380318000000003} {:category "time", :name "plugin_sync", :value 0.8297269499998947} {:category "time", :name "postgresql_conf", :value "0.43150700000000003e-3"} {:category "time", :name "postgresql_conn_validator", :value 0.046829087} {:category "time", :name "postgresql_psql", :value "0.10139021069999998e1"} {:category "time", :name "puppetdb_conn_validator", :value 0.02971598} {:category "time", :name "resources", :value 4.3131E-5} {:category "time", :name "service", :value 0.237520976} {:category "time", :name "ssh_authorized_key", :value 0.001436472} {:category "time", :name "sshkey", :value "0.24759179999999997e-2"} {:category "time", :name "total", :value 12.737409396} {:category "time", :name "transaction_evaluation", :value "0.36821015019995684e1"} {:category "time", :name "user", :value "0.28593181999999998e-1"} {:category "time", :name "vcsrepo", :value "0.49700184999999994e-1"} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "fb79685a-e0c4-44c8-bb9f-bd35a4c8bb00", :producer "pm-buster.test", :transaction_uuid "78e9c2b4-e9cd-4a56-8f1d-b8f0323e38dd", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T15:35:12.885-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 3.87 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T15:35:15.641-05:00"}], :report_format 10, :start_time "2019-03-01T15:35:02.800-05:00", :producer_timestamp "2019-03-01T15:35:15.777-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T15:35:15.538-05:00", :status "changed", :configuration_version "1551472506", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T15:35:12.613-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T15:35:12.612-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.019795081} {:category "time", :name "anchor", :value 6.01893E-4} {:category "time", :name "augeas", :value 0.09577380999999999} {:category "time", :name "catalog_application", :value 3.869886745000258} {:category "time", :name "concat_file", :value 0.001167476} {:category "time", :name "concat_fragment", :value "0.48384970000000015e-2"} {:category "time", :name "config_retrieval", :value 4.974959022001713} {:category "time", :name "convert_catalog", :value "0.46641710000039893e0"} {:category "time", :name "cron", :value 0.01029718} {:category "time", :name "exec", :value 0.015293136} {:category "time", :name "fact_generation", :value 2.472045997998066} {:category "time", :name "file", :value "0.49866304400000017e0"} {:category "time", :name "file_line", :value 0.0181323} {:category "time", :name "filebucket", :value 7.6291E-5} {:category "time", :name "group", :value 4.3702E-4} {:category "time", :name "ini_setting", :value "0.10528771000000001e-1"} {:category "time", :name "mailalias", :value 6.84772E-4} {:category "time", :name "node_retrieval", :value "0.10985619500206667e0"} {:category "time", :name "package", :value 0.007380318000000003} {:category "time", :name "plugin_sync", :value 0.8297269499998947} {:category "time", :name "postgresql_conf", :value "0.43150700000000003e-3"} {:category "time", :name "postgresql_conn_validator", :value 0.046829087} {:category "time", :name "postgresql_psql", :value "0.10139021069999998e1"} {:category "time", :name "puppetdb_conn_validator", :value 0.02971598} {:category "time", :name "resources", :value 4.3131E-5} {:category "time", :name "service", :value 0.237520976} {:category "time", :name "ssh_authorized_key", :value 0.001436472} {:category "time", :name "sshkey", :value "0.24759179999999997e-2"} {:category "time", :name "total", :value 12.737409396} {:category "time", :name "transaction_evaluation", :value "0.36821015019995684e1"} {:category "time", :name "user", :value "0.28593181999999998e-1"} {:category "time", :name "vcsrepo", :value "0.49700184999999994e-1"} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T15:35:35.813-05:00 INFO [p.p.command] [71-1551472535715] [43 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T15:35:36.167-05:00 INFO [p.p.command] [72-1551472536036] [69 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T15:35:36.662-05:00 INFO [p.p.command] [73-1551472536583] [36 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T15:46:07.769-05:00 INFO [p.p.command] [74-1551473167603] [115 ms] 'replace facts' command processed for pc-stretch.test
2019-03-01T15:46:10.803-05:00 INFO [p.p.command] [75-1551473170462] [263 ms] 'replace catalog' command processed for pc-stretch.test
2019-03-01T15:46:14.602-05:00 INFO [p.p.command] [76-1551473174512] [30 ms] 'store report' puppet v4.8.2 command processed for pc-stretch.test
2019-03-01T16:05:07.576-05:00 INFO [p.p.command] [77-1551474307341] [61 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T16:05:09.957-05:00 INFO [p.p.command] [78-1551474309826] [63 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T16:05:10.350-05:00 INFO [p.p.command] [79-1551474310180] [105 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T16:05:11.117-05:00 INFO [p.p.command] [80-1551474310989] [51 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T16:05:11.747-05:00 INFO [p.p.command] [81-1551474311357] [291 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T16:05:18.017-05:00 ERROR [p.p.command] [82] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "cd1b8b54-5896-4be3-a72c-6d0e4667cdcb", :producer "pm-buster.test", :transaction_uuid "7981bf55-e025-4147-a4b0-0996e7ef29a1", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T16:05:14.242-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 4.61 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T16:05:17.764-05:00"}], :report_format 10, :start_time "2019-03-01T16:05:03.265-05:00", :producer_timestamp "2019-03-01T16:05:17.893-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T16:05:17.655-05:00", :status "changed", :configuration_version "1551474307", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T16:05:14.000-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T16:05:14.000-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.022337033} {:category "time", :name "anchor", :value 6.04092E-4} {:category "time", :name "augeas", :value 0.09831086200000001} {:category "time", :name "catalog_application", :value 4.605015095999988} {:category "time", :name "concat_file", :value "0.19264540000000002e-2"} {:category "time", :name "concat_fragment", :value "0.52558729999999994e-2"} {:category "time", :name "config_retrieval", :value 5.387453999002901} {:category "time", :name "convert_catalog", :value 0.485021273001621} {:category "time", :name "cron", :value 0.012242599} {:category "time", :name "exec", :value "0.17435381000000003e-1"} {:category "time", :name "fact_generation", :value 2.847833946998435} {:category "time", :name "file", :value "0.47207306799999993e0"} {:category "time", :name "file_line", :value "0.16005230000000006e-1"} {:category "time", :name "filebucket", :value 6.4591E-5} {:category "time", :name "group", :value 5.384049999999999E-4} {:category "time", :name "ini_setting", :value "0.11614002000000003e-1"} {:category "time", :name "mailalias", :value 5.970849999999999E-4} {:category "time", :name "node_retrieval", :value 0.07935786499729147} {:category "time", :name "package", :value 0.007548346999999999} {:category "time", :name "plugin_sync", :value 0.9730425089983328} {:category "time", :name "postgresql_conf", :value 4.51083E-4} {:category "time", :name "postgresql_conn_validator", :value 0.050633585} {:category "time", :name "postgresql_psql", :value "0.12633143830000002e1"} {:category "time", :name "puppetdb_conn_validator", :value 0.031758377} {:category "time", :name "resources", :value 4.5102E-5} {:category "time", :name "service", :value 0.240967068} {:category "time", :name "ssh_authorized_key", :value "0.13836850000000002e-2"} {:category "time", :name "sshkey", :value "0.21000249999999997e-2"} {:category "time", :name "total", :value 14.390470911} {:category "time", :name "transaction_evaluation", :value 4.436016635001579} {:category "time", :name "user", :value 0.029698934} {:category "time", :name "vcsrepo", :value 0.04878053} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "cd1b8b54-5896-4be3-a72c-6d0e4667cdcb", :producer "pm-buster.test", :transaction_uuid "7981bf55-e025-4147-a4b0-0996e7ef29a1", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T16:05:14.242-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 4.61 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T16:05:17.764-05:00"}], :report_format 10, :start_time "2019-03-01T16:05:03.265-05:00", :producer_timestamp "2019-03-01T16:05:17.893-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T16:05:17.655-05:00", :status "changed", :configuration_version "1551474307", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T16:05:14.000-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T16:05:14.000-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.022337033} {:category "time", :name "anchor", :value 6.04092E-4} {:category "time", :name "augeas", :value 0.09831086200000001} {:category "time", :name "catalog_application", :value 4.605015095999988} {:category "time", :name "concat_file", :value "0.19264540000000002e-2"} {:category "time", :name "concat_fragment", :value "0.52558729999999994e-2"} {:category "time", :name "config_retrieval", :value 5.387453999002901} {:category "time", :name "convert_catalog", :value 0.485021273001621} {:category "time", :name "cron", :value 0.012242599} {:category "time", :name "exec", :value "0.17435381000000003e-1"} {:category "time", :name "fact_generation", :value 2.847833946998435} {:category "time", :name "file", :value "0.47207306799999993e0"} {:category "time", :name "file_line", :value "0.16005230000000006e-1"} {:category "time", :name "filebucket", :value 6.4591E-5} {:category "time", :name "group", :value 5.384049999999999E-4} {:category "time", :name "ini_setting", :value "0.11614002000000003e-1"} {:category "time", :name "mailalias", :value 5.970849999999999E-4} {:category "time", :name "node_retrieval", :value 0.07935786499729147} {:category "time", :name "package", :value 0.007548346999999999} {:category "time", :name "plugin_sync", :value 0.9730425089983328} {:category "time", :name "postgresql_conf", :value 4.51083E-4} {:category "time", :name "postgresql_conn_validator", :value 0.050633585} {:category "time", :name "postgresql_psql", :value "0.12633143830000002e1"} {:category "time", :name "puppetdb_conn_validator", :value 0.031758377} {:category "time", :name "resources", :value 4.5102E-5} {:category "time", :name "service", :value 0.240967068} {:category "time", :name "ssh_authorized_key", :value "0.13836850000000002e-2"} {:category "time", :name "sshkey", :value "0.21000249999999997e-2"} {:category "time", :name "total", :value 14.390470911} {:category "time", :name "transaction_evaluation", :value 4.436016635001579} {:category "time", :name "user", :value 0.029698934} {:category "time", :name "vcsrepo", :value 0.04878053} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T16:16:07.989-05:00 INFO [p.p.command] [83-1551474967898] [39 ms] 'replace facts' command processed for pc-stretch.test
2019-03-01T16:16:11.028-05:00 INFO [p.p.command] [84-1551474970797] [141 ms] 'replace catalog' command processed for pc-stretch.test
2019-03-01T16:16:15.531-05:00 INFO [p.p.command] [85-1551474975459] [14 ms] 'store report' puppet v4.8.2 command processed for pc-stretch.test
2019-03-01T16:19:26.851-05:00 INFO [p.p.c.services] Starting sweep of stale nodes (threshold: 7 days)
2019-03-01T16:19:26.862-05:00 INFO [p.p.c.services] Finished sweep of stale nodes (threshold: 7 days)
2019-03-01T16:19:26.863-05:00 INFO [p.p.c.services] Starting purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T16:19:26.866-05:00 INFO [p.p.c.services] Finished purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T16:19:26.867-05:00 INFO [p.p.c.services] Starting sweep of stale reports (threshold: 14 days)
2019-03-01T16:19:26.868-05:00 INFO [p.p.c.services] Finished sweep of stale reports (threshold: 14 days)
2019-03-01T16:19:26.868-05:00 INFO [p.p.c.services] Starting gc packages
2019-03-01T16:19:26.870-05:00 INFO [p.p.c.services] Finished gc packages
2019-03-01T16:19:26.870-05:00 INFO [p.p.c.services] Starting database garbage collection
2019-03-01T16:19:26.974-05:00 INFO [p.p.c.services] Finished database garbage collection
2019-03-01T16:35:07.514-05:00 INFO [p.p.command] [86-1551476107417] [36 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T16:35:09.415-05:00 INFO [p.p.command] [87-1551476109308] [57 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T16:35:09.815-05:00 INFO [p.p.command] [88-1551476109716] [45 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T16:35:10.467-05:00 INFO [p.p.command] [89-1551476110340] [65 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T16:35:11.311-05:00 INFO [p.p.command] [90-1551476111047] [169 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T16:35:17.863-05:00 ERROR [p.p.command] [91] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "4e7eb2ac-ef46-42d6-8334-8d7b9de0c6db", :producer "pm-buster.test", :transaction_uuid "6d48cff7-8ce3-4981-a82e-df6547f65a0a", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T16:35:13.834-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 4.16 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T16:35:16.790-05:00"}], :report_format 10, :start_time "2019-03-01T16:35:03.179-05:00", :producer_timestamp "2019-03-01T16:35:16.948-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T16:35:16.684-05:00", :status "changed", :configuration_version "1551476107", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T16:35:13.516-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T16:35:13.515-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.01843678} {:category "time", :name "anchor", :value 5.91094E-4} {:category "time", :name "augeas", :value "0.10352689999999999e0"} {:category "time", :name "catalog_application", :value 4.164324600002146} {:category "time", :name "concat_file", :value 0.001686516} {:category "time", :name "concat_fragment", :value 0.005471502} {:category "time", :name "config_retrieval", :value 4.756794267999794} {:category "time", :name "convert_catalog", :value 0.516324574000464} {:category "time", :name "cron", :value 0.016930628} {:category "time", :name "exec", :value 0.016254609} {:category "time", :name "fact_generation", :value 2.76540414199917} {:category "time", :name "file", :value 0.552862756} {:category "time", :name "file_line", :value "0.18804891999999997e-1"} {:category "time", :name "filebucket", :value 7.4026E-5} {:category "time", :name "group", :value 5.302149999999999E-4} {:category "time", :name "ini_setting", :value 0.016617769} {:category "time", :name "mailalias", :value 5.32024E-4} {:category "time", :name "node_retrieval", :value "0.12636089000079664e0"} {:category "time", :name "package", :value 0.008675651} {:category "time", :name "plugin_sync", :value "0.11558860240002105e1"} {:category "time", :name "postgresql_conf", :value 3.40114E-4} {:category "time", :name "postgresql_conn_validator", :value 0.049313405} {:category "time", :name "postgresql_psql", :value 1.077891001} {:category "time", :name "puppetdb_conn_validator", :value 0.04880925} {:category "time", :name "resources", :value 6.645E-5} {:category "time", :name "service", :value 0.258218442} {:category "time", :name "ssh_authorized_key", :value "0.18274690000000002e-2"} {:category "time", :name "sshkey", :value "0.23924769999999996e-2"} {:category "time", :name "total", :value 13.504515203} {:category "time", :name "transaction_evaluation", :value "0.39816030510010023e1"} {:category "time", :name "user", :value 0.035318698} {:category "time", :name "vcsrepo", :value 0.07380058} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "4e7eb2ac-ef46-42d6-8334-8d7b9de0c6db", :producer "pm-buster.test", :transaction_uuid "6d48cff7-8ce3-4981-a82e-df6547f65a0a", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T16:35:13.834-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 4.16 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T16:35:16.790-05:00"}], :report_format 10, :start_time "2019-03-01T16:35:03.179-05:00", :producer_timestamp "2019-03-01T16:35:16.948-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T16:35:16.684-05:00", :status "changed", :configuration_version "1551476107", :environment "production", :code_id nil, :resources [{:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T16:35:13.516-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T16:35:13.515-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 1} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 1} {:category "resources", :name "restarted", :value 0} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 720} {:category "time", :name "alternatives", :value 0.01843678} {:category "time", :name "anchor", :value 5.91094E-4} {:category "time", :name "augeas", :value "0.10352689999999999e0"} {:category "time", :name "catalog_application", :value 4.164324600002146} {:category "time", :name "concat_file", :value 0.001686516} {:category "time", :name "concat_fragment", :value 0.005471502} {:category "time", :name "config_retrieval", :value 4.756794267999794} {:category "time", :name "convert_catalog", :value 0.516324574000464} {:category "time", :name "cron", :value 0.016930628} {:category "time", :name "exec", :value 0.016254609} {:category "time", :name "fact_generation", :value 2.76540414199917} {:category "time", :name "file", :value 0.552862756} {:category "time", :name "file_line", :value "0.18804891999999997e-1"} {:category "time", :name "filebucket", :value 7.4026E-5} {:category "time", :name "group", :value 5.302149999999999E-4} {:category "time", :name "ini_setting", :value 0.016617769} {:category "time", :name "mailalias", :value 5.32024E-4} {:category "time", :name "node_retrieval", :value "0.12636089000079664e0"} {:category "time", :name "package", :value 0.008675651} {:category "time", :name "plugin_sync", :value "0.11558860240002105e1"} {:category "time", :name "postgresql_conf", :value 3.40114E-4} {:category "time", :name "postgresql_conn_validator", :value 0.049313405} {:category "time", :name "postgresql_psql", :value 1.077891001} {:category "time", :name "puppetdb_conn_validator", :value 0.04880925} {:category "time", :name "resources", :value 6.645E-5} {:category "time", :name "service", :value 0.258218442} {:category "time", :name "ssh_authorized_key", :value "0.18274690000000002e-2"} {:category "time", :name "sshkey", :value "0.23924769999999996e-2"} {:category "time", :name "total", :value 13.504515203} {:category "time", :name "transaction_evaluation", :value "0.39816030510010023e1"} {:category "time", :name "user", :value 0.035318698} {:category "time", :name "vcsrepo", :value 0.07380058} {:category "changes", :name "total", :value 1} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 1} {:category "events", :name "total", :value 1}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T16:46:07.495-05:00 INFO [p.p.command] [92-1551476767421] [53 ms] 'replace facts' command processed for pc-stretch.test
2019-03-01T16:46:10.360-05:00 INFO [p.p.command] [93-1551476770186] [121 ms] 'replace catalog' command processed for pc-stretch.test
2019-03-01T16:46:14.211-05:00 INFO [p.p.command] [94-1551476774153] [31 ms] 'store report' puppet v4.8.2 command processed for pc-stretch.test
2019-03-01T17:05:07.795-05:00 INFO [p.p.command] [95-1551477907516] [47 ms] 'replace facts' command processed for pm-buster.test
2019-03-01T17:05:10.172-05:00 INFO [p.p.command] [96-1551477910079] [46 ms] 'replace facts' command processed for pc-jessie.test
2019-03-01T17:05:10.634-05:00 INFO [p.p.command] [97-1551477910550] [34 ms] 'replace catalog' command processed for pc-jessie.test
2019-03-01T17:05:11.238-05:00 INFO [p.p.command] [98-1551477911155] [29 ms] 'store report' puppet v4.8.2 command processed for pc-jessie.test
2019-03-01T17:05:12.691-05:00 INFO [p.p.command] [99-1551477911873] [486 ms] 'replace catalog' command processed for pm-buster.test
2019-03-01T17:06:01.396-05:00 ERROR [p.p.command] [100] [store report] Fatal error on attempt 0 for pm-buster.test
clojure.lang.ExceptionInfo: throw+: {:fatal true, :cause #error {
:cause "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "22c77ac0-e914-485e-9b44-ffd9415e4a20", :producer "pm-buster.test", :transaction_uuid "660b4608-9b6d-4248-90df-6b10f3a8ead9", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/install.pp", :line 12, :level "notice", :message "created", :source "/Stage[main]/Fail2ban::Install/Package[fail2ban]/ensure", :tags ["notice" "package" "fail2ban" "class" "fail2ban::install" "install" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:56.482-05:00"} {:file nil, :line nil, :level "notice", :message "removed", :source "/Stage[main]/Fail2ban::Config/File[/etc/fail2ban/jail.d/defaults-debian.conf]/ensure", :tags ["notice" "file" "class" "fail2ban::config" "fail2ban" "config" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:57.189-05:00"} {:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/config.pp", :line 51, :level "notice", :message "content changed '{md5}ae1c0fb5a78da17d4226aab8526a7015' to '{md5}f3b512877f2dfe214591bf7ba9405bb6'", :source "/Stage[main]/Fail2ban::Config/File[/etc/fail2ban/action.d/iptables-multiport.conf]/content", :tags ["notice" "file" "class" "fail2ban::config" "fail2ban" "config" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:57.280-05:00"} {:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/config.pp", :line 59, :level "notice", :message "content changed '{md5}95558e734c333d63b0e3598037af34c2' to '{md5}5035f11588a8cecf50957c80a6b019ec'", :source "/Stage[main]/Fail2ban::Config/File[/etc/fail2ban/jail.conf]/content", :tags ["notice" "file" "class" "fail2ban::config" "fail2ban" "config" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:57.380-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:57.722-05:00"} {:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/jail.pp", :line 113, :level "notice", :message "defined content as '{md5}5de0bf7f529a038d439b99af34feb5f5'", :source "/Stage[main]/Profile::Fail2ban/Fail2ban::Jail[sshd]/File[/etc/fail2ban/jail.d/sshd.conf]/ensure", :tags ["notice" "file" "fail2ban::jail" "fail2ban" "jail" "sshd" "class" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:58.014-05:00"} {:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/service.pp", :line 12, :level "notice", :message "Triggered 'refresh' from 1 event", :source "/Stage[main]/Fail2ban::Service/Service[fail2ban]", :tags ["notice" "service" "fail2ban" "class" "fail2ban::service" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:58.408-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 47.21 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T17:06:01.057-05:00"}], :report_format 10, :start_time "2019-03-01T17:05:03.221-05:00", :producer_timestamp "2019-03-01T17:06:01.218-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T17:06:00.936-05:00", :status "changed", :configuration_version "1551477907", :environment "production", :code_id nil, :resources [{:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:14.805-05:00", :property "ensure", :new_value "present", :old_value "purged", :corrective_change false, :message "created"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/install.pp", :skipped false, :line 12, :resource_type "Package", :resource_title "fail2ban", :timestamp "2019-03-01T17:05:14.781-05:00", :containment_path ["Stage[main]" "Fail2ban::Install" "Package[fail2ban]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:56.494-05:00", :property "ensure", :new_value "absent", :old_value "file", :corrective_change false, :message "removed"}], :file nil, :skipped false, :line nil, :resource_type "File", :resource_title "/etc/fail2ban/jail.d/defaults-debian.conf", :timestamp "2019-03-01T17:05:56.493-05:00", :containment_path ["Stage[main]" "Fail2ban::Config" "File[/etc/fail2ban/jail.d/defaults-debian.conf]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:57.192-05:00", :property "content", :new_value "{md5}f3b512877f2dfe214591bf7ba9405bb6", :old_value "{md5}ae1c0fb5a78da17d4226aab8526a7015", :corrective_change false, :message "content changed '{md5}ae1c0fb5a78da17d4226aab8526a7015' to '{md5}f3b512877f2dfe214591bf7ba9405bb6'"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/config.pp", :skipped false, :line 51, :resource_type "File", :resource_title "/etc/fail2ban/action.d/iptables-multiport.conf", :timestamp "2019-03-01T17:05:57.191-05:00", :containment_path ["Stage[main]" "Fail2ban::Config" "File[/etc/fail2ban/action.d/iptables-multiport.conf]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:57.281-05:00", :property "content", :new_value "{md5}5035f11588a8cecf50957c80a6b019ec", :old_value "{md5}95558e734c333d63b0e3598037af34c2", :corrective_change false, :message "content changed '{md5}95558e734c333d63b0e3598037af34c2' to '{md5}5035f11588a8cecf50957c80a6b019ec'"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/config.pp", :skipped false, :line 59, :resource_type "File", :resource_title "/etc/fail2ban/jail.conf", :timestamp "2019-03-01T17:05:57.280-05:00", :containment_path ["Stage[main]" "Fail2ban::Config" "File[/etc/fail2ban/jail.conf]"]} {:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T17:05:57.646-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T17:05:57.645-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:57.948-05:00", :property "ensure", :new_value "present", :old_value "absent", :corrective_change false, :message "defined content as '{md5}5de0bf7f529a038d439b99af34feb5f5'"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/jail.pp", :skipped false, :line 113, :resource_type "File", :resource_title "/etc/fail2ban/jail.d/sshd.conf", :timestamp "2019-03-01T17:05:57.948-05:00", :containment_path ["Stage[main]" "Profile::Fail2ban" "Fail2ban::Jail[sshd]" "File[/etc/fail2ban/jail.d/sshd.conf]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:58.411-05:00", :property nil, :new_value "", :old_value "", :corrective_change false, :message "Triggered 'refresh' from 1 event"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/service.pp", :skipped false, :line 12, :resource_type "Service", :resource_title "fail2ban", :timestamp "2019-03-01T17:05:58.018-05:00", :containment_path ["Stage[main]" "Fail2ban::Service" "Service[fail2ban]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 7} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 7} {:category "resources", :name "restarted", :value 1} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 728} {:category "time", :name "alternatives", :value 0.016130847} {:category "time", :name "anchor", :value 7.010429999999999E-4} {:category "time", :name "augeas", :value 0.110368362} {:category "time", :name "catalog_application", :value "0.47211736878998636e2"} {:category "time", :name "concat_file", :value "0.17111080000000002e-2"} {:category "time", :name "concat_fragment", :value 0.006893721999999998} {:category "time", :name "config_retrieval", :value "0.58427004589975695e1"} {:category "time", :name "convert_catalog", :value 0.5355850689993531} {:category "time", :name "cron", :value "0.16159331000000002e-1"} {:category "time", :name "exec", :value 0.015895619} {:category "time", :name "fact_generation", :value "0.27612540909976815e1"} {:category "time", :name "file", :value "0.14974653800000002e1"} {:category "time", :name "file_line", :value "0.18781285999999998e-1"} {:category "time", :name "filebucket", :value 1.24123E-4} {:category "time", :name "group", :value 5.95759E-4} {:category "time", :name "ini_setting", :value "0.12386446999999997e-1"} {:category "time", :name "mailalias", :value 7.40985E-4} {:category "time", :name "node_retrieval", :value "0.10971519500162685e0"} {:category "time", :name "package", :value 41.71170920300002} {:category "time", :name "plugin_sync", :value "0.12318724800024938e1"} {:category "time", :name "postgresql_conf", :value 5.62453E-4} {:category "time", :name "postgresql_conn_validator", :value 0.05208104} {:category "time", :name "postgresql_psql", :value 1.04219081} {:category "time", :name "puppetdb_conn_validator", :value 0.070391815} {:category "time", :name "resources", :value 8.1904E-5} {:category "time", :name "service", :value 0.305756047} {:category "time", :name "ssh_authorized_key", :value 0.001498763} {:category "time", :name "sshkey", :value 0.002106044} {:category "time", :name "total", :value 57.71544858} {:category "time", :name "transaction_evaluation", :value "0.47009145839001576e2"} {:category "time", :name "user", :value 0.033909184} {:category "time", :name "vcsrepo", :value 0.054611584} {:category "changes", :name "total", :value 7} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 7} {:category "events", :name "total", :value 7}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil]}}
:via
[{:type clojure.lang.ExceptionInfo
:message "Value does not match schema: {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil]}"
:data {:type :schema.core/error, :schema {:catalog_uuid (maybe Str), #schema.core.OptionalKey{:k :job_id} (maybe Str), :producer (maybe Str), :transaction_uuid (maybe Str), :puppet_version java.lang.String, :noop (maybe Bool), :corrective_change (maybe Bool), :logs [{:file (maybe Str), :line (maybe Int), :level java.lang.String, :message java.lang.String, :source java.lang.String, :tags [java.lang.String], :time (pred datetime?)}], :report_format Int, :start_time (pred datetime?), :producer_timestamp (pred datetime?), :cached_catalog_status (maybe Str), :end_time (pred datetime?), :status (maybe Str), :configuration_version java.lang.String, :environment java.lang.String, :code_id (maybe Str), :resources [{:corrective_change (maybe Bool), :events [{:status java.lang.String, :corrective_change (maybe Bool), :timestamp (pred datetime?), :property (maybe Str), :new_value (maybe (protocol json/JSONable)), :old_value (maybe (protocol json/JSONable)), :message (maybe Str)}], :file (maybe Str), :skipped java.lang.Boolean, :line (maybe Int), :resource_type java.lang.String, :resource_title java.lang.String, :timestamp (pred datetime?), :containment_path [java.lang.String]}], :noop_pending (maybe Bool), :certname java.lang.String, :metrics [{:category java.lang.String, :name java.lang.String, :value java.lang.Number}]}, :value {:catalog_uuid "22c77ac0-e914-485e-9b44-ffd9415e4a20", :producer "pm-buster.test", :transaction_uuid "660b4608-9b6d-4248-90df-6b10f3a8ead9", :puppet_version "5.5.10", :noop false, :corrective_change true, :logs [{:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/install.pp", :line 12, :level "notice", :message "created", :source "/Stage[main]/Fail2ban::Install/Package[fail2ban]/ensure", :tags ["notice" "package" "fail2ban" "class" "fail2ban::install" "install" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:56.482-05:00"} {:file nil, :line nil, :level "notice", :message "removed", :source "/Stage[main]/Fail2ban::Config/File[/etc/fail2ban/jail.d/defaults-debian.conf]/ensure", :tags ["notice" "file" "class" "fail2ban::config" "fail2ban" "config" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:57.189-05:00"} {:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/config.pp", :line 51, :level "notice", :message "content changed '{md5}ae1c0fb5a78da17d4226aab8526a7015' to '{md5}f3b512877f2dfe214591bf7ba9405bb6'", :source "/Stage[main]/Fail2ban::Config/File[/etc/fail2ban/action.d/iptables-multiport.conf]/content", :tags ["notice" "file" "class" "fail2ban::config" "fail2ban" "config" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:57.280-05:00"} {:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/config.pp", :line 59, :level "notice", :message "content changed '{md5}95558e734c333d63b0e3598037af34c2' to '{md5}5035f11588a8cecf50957c80a6b019ec'", :source "/Stage[main]/Fail2ban::Config/File[/etc/fail2ban/jail.conf]/content", :tags ["notice" "file" "class" "fail2ban::config" "fail2ban" "config" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:57.380-05:00"} {:file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :line 34, :level "notice", :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'", :source "/Stage[main]/Resolvconf/File[/etc/resolv.conf]/content", :tags ["notice" "file" "class" "resolvconf" "profile::resolvconf" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:57.722-05:00"} {:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/jail.pp", :line 113, :level "notice", :message "defined content as '{md5}5de0bf7f529a038d439b99af34feb5f5'", :source "/Stage[main]/Profile::Fail2ban/Fail2ban::Jail[sshd]/File[/etc/fail2ban/jail.d/sshd.conf]/ensure", :tags ["notice" "file" "fail2ban::jail" "fail2ban" "jail" "sshd" "class" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:58.014-05:00"} {:file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/service.pp", :line 12, :level "notice", :message "Triggered 'refresh' from 1 event", :source "/Stage[main]/Fail2ban::Service/Service[fail2ban]", :tags ["notice" "service" "fail2ban" "class" "fail2ban::service" "profile::fail2ban" "profile" "role::puppetmaster" "role" "puppetmaster" "node" "__node_regexp__pm-..test"], :time "2019-03-01T17:05:58.408-05:00"} {:file nil, :line nil, :level "notice", :message "Applied catalog in 47.21 seconds", :source "Puppet", :tags ["notice"], :time "2019-03-01T17:06:01.057-05:00"}], :report_format 10, :start_time "2019-03-01T17:05:03.221-05:00", :producer_timestamp "2019-03-01T17:06:01.218-05:00", :cached_catalog_status "not_used", :end_time "2019-03-01T17:06:00.936-05:00", :status "changed", :configuration_version "1551477907", :environment "production", :code_id nil, :resources [{:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:14.805-05:00", :property "ensure", :new_value "present", :old_value "purged", :corrective_change false, :message "created"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/install.pp", :skipped false, :line 12, :resource_type "Package", :resource_title "fail2ban", :timestamp "2019-03-01T17:05:14.781-05:00", :containment_path ["Stage[main]" "Fail2ban::Install" "Package[fail2ban]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:56.494-05:00", :property "ensure", :new_value "absent", :old_value "file", :corrective_change false, :message "removed"}], :file nil, :skipped false, :line nil, :resource_type "File", :resource_title "/etc/fail2ban/jail.d/defaults-debian.conf", :timestamp "2019-03-01T17:05:56.493-05:00", :containment_path ["Stage[main]" "Fail2ban::Config" "File[/etc/fail2ban/jail.d/defaults-debian.conf]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:57.192-05:00", :property "content", :new_value "{md5}f3b512877f2dfe214591bf7ba9405bb6", :old_value "{md5}ae1c0fb5a78da17d4226aab8526a7015", :corrective_change false, :message "content changed '{md5}ae1c0fb5a78da17d4226aab8526a7015' to '{md5}f3b512877f2dfe214591bf7ba9405bb6'"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/config.pp", :skipped false, :line 51, :resource_type "File", :resource_title "/etc/fail2ban/action.d/iptables-multiport.conf", :timestamp "2019-03-01T17:05:57.191-05:00", :containment_path ["Stage[main]" "Fail2ban::Config" "File[/etc/fail2ban/action.d/iptables-multiport.conf]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:57.281-05:00", :property "content", :new_value "{md5}5035f11588a8cecf50957c80a6b019ec", :old_value "{md5}95558e734c333d63b0e3598037af34c2", :corrective_change false, :message "content changed '{md5}95558e734c333d63b0e3598037af34c2' to '{md5}5035f11588a8cecf50957c80a6b019ec'"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/config.pp", :skipped false, :line 59, :resource_type "File", :resource_title "/etc/fail2ban/jail.conf", :timestamp "2019-03-01T17:05:57.280-05:00", :containment_path ["Stage[main]" "Fail2ban::Config" "File[/etc/fail2ban/jail.conf]"]} {:corrective_change true, :events [{:status "success", :timestamp "2019-03-01T17:05:57.646-05:00", :property "content", :new_value "{md5}e6efdf3730b968df02f0d11b2b16bedb", :old_value "{md5}d13d36cc65371591ba7ffcd99ff9606f", :corrective_change true, :message "content changed '{md5}d13d36cc65371591ba7ffcd99ff9606f' to '{md5}e6efdf3730b968df02f0d11b2b16bedb'"}], :file "/etc/puppet/code/environments/production/modules/resolvconf/manifests/init.pp", :skipped false, :line 34, :resource_type "File", :resource_title "/etc/resolv.conf", :timestamp "2019-03-01T17:05:57.645-05:00", :containment_path ["Stage[main]" "Resolvconf" "File[/etc/resolv.conf]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:57.948-05:00", :property "ensure", :new_value "present", :old_value "absent", :corrective_change false, :message "defined content as '{md5}5de0bf7f529a038d439b99af34feb5f5'"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/jail.pp", :skipped false, :line 113, :resource_type "File", :resource_title "/etc/fail2ban/jail.d/sshd.conf", :timestamp "2019-03-01T17:05:57.948-05:00", :containment_path ["Stage[main]" "Profile::Fail2ban" "Fail2ban::Jail[sshd]" "File[/etc/fail2ban/jail.d/sshd.conf]"]} {:corrective_change false, :events [{:status "success", :timestamp "2019-03-01T17:05:58.411-05:00", :property nil, :new_value "", :old_value "", :corrective_change false, :message "Triggered 'refresh' from 1 event"}], :file "/etc/puppet/code/environments/production/modules/fail2ban/manifests/service.pp", :skipped false, :line 12, :resource_type "Service", :resource_title "fail2ban", :timestamp "2019-03-01T17:05:58.018-05:00", :containment_path ["Stage[main]" "Fail2ban::Service" "Service[fail2ban]"]}], :noop_pending false, :certname "pm-buster.test", :metrics [{:category "resources", :name "changed", :value 7} {:category "resources", :name "corrective_change", :value 1} {:category "resources", :name "failed", :value 0} {:category "resources", :name "failed_to_restart", :value 0} {:category "resources", :name "out_of_sync", :value 7} {:category "resources", :name "restarted", :value 1} {:category "resources", :name "scheduled", :value 0} {:category "resources", :name "skipped", :value 0} {:category "resources", :name "total", :value 728} {:category "time", :name "alternatives", :value 0.016130847} {:category "time", :name "anchor", :value 7.010429999999999E-4} {:category "time", :name "augeas", :value 0.110368362} {:category "time", :name "catalog_application", :value "0.47211736878998636e2"} {:category "time", :name "concat_file", :value "0.17111080000000002e-2"} {:category "time", :name "concat_fragment", :value 0.006893721999999998} {:category "time", :name "config_retrieval", :value "0.58427004589975695e1"} {:category "time", :name "convert_catalog", :value 0.5355850689993531} {:category "time", :name "cron", :value "0.16159331000000002e-1"} {:category "time", :name "exec", :value 0.015895619} {:category "time", :name "fact_generation", :value "0.27612540909976815e1"} {:category "time", :name "file", :value "0.14974653800000002e1"} {:category "time", :name "file_line", :value "0.18781285999999998e-1"} {:category "time", :name "filebucket", :value 1.24123E-4} {:category "time", :name "group", :value 5.95759E-4} {:category "time", :name "ini_setting", :value "0.12386446999999997e-1"} {:category "time", :name "mailalias", :value 7.40985E-4} {:category "time", :name "node_retrieval", :value "0.10971519500162685e0"} {:category "time", :name "package", :value 41.71170920300002} {:category "time", :name "plugin_sync", :value "0.12318724800024938e1"} {:category "time", :name "postgresql_conf", :value 5.62453E-4} {:category "time", :name "postgresql_conn_validator", :value 0.05208104} {:category "time", :name "postgresql_psql", :value 1.04219081} {:category "time", :name "puppetdb_conn_validator", :value 0.070391815} {:category "time", :name "resources", :value 8.1904E-5} {:category "time", :name "service", :value 0.305756047} {:category "time", :name "ssh_authorized_key", :value 0.001498763} {:category "time", :name "sshkey", :value 0.002106044} {:category "time", :name "total", :value 57.71544858} {:category "time", :name "transaction_evaluation", :value "0.47009145839001576e2"} {:category "time", :name "user", :value 0.033909184} {:category "time", :name "vcsrepo", :value 0.054611584} {:category "changes", :name "total", :value 7} {:category "events", :name "failure", :value 0} {:category "events", :name "success", :value 7} {:category "events", :name "total", :value 7}], :job_id nil}, :error {:metrics [nil nil nil nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil nil nil nil {:value (not (instance? java.lang.Number a-java.lang.String))} nil nil nil nil nil nil]}}
:at [schema.core$validator$fn__18912 invoke "core.clj" 155]}]
:trace
[[schema.core$validator$fn__18912 invoke "core.clj" 155]
[schema.core$validate invokeStatic "core.clj" 164]
[schema.core$validate invoke "core.clj" 159]
[puppetlabs.puppetdb.command$store_report$fn__40246 invoke "command.clj" 378]
[puppetlabs.puppetdb.command$store_report invokeStatic "command.clj" 377]
[puppetlabs.puppetdb.command$store_report invoke "command.clj" 376]
[puppetlabs.puppetdb.command$process_command_BANG_ invokeStatic "command.clj" 412]
[puppetlabs.puppetdb.command$process_command_BANG_ invoke "command.clj" 403]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354 invoke "command.clj" 467]
[puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348 invoke "command.clj" 448]
[puppetlabs.puppetdb.command$call_with_quick_retry invokeStatic "command.clj" 447]
[puppetlabs.puppetdb.command$call_with_quick_retry invoke "command.clj" 445]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invokeStatic "command.clj" 465]
[puppetlabs.puppetdb.command$process_command_and_respond_BANG_ invoke "command.clj" 462]
[puppetlabs.puppetdb.command$process_cmdref$fn__40364 invoke "command.clj" 530]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4 call nil -1]
[com.codahale.metrics.Timer time "Timer.java" 101]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713 invoke "metrics.clj" 14]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invokeStatic "metrics.clj" 17]
[puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_ invoke "metrics.clj" 6]
[puppetlabs.puppetdb.command$process_cmdref invokeStatic "command.clj" 526]
[puppetlabs.puppetdb.command$process_cmdref invoke "command.clj" 505]
[puppetlabs.puppetdb.command$message_handler$fn__40379 invoke "command.clj" 593]
[puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118 invoke "threadpool.clj" 117]
[puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113 invoke "threadpool.clj" 95]
[clojure.lang.AFn run "AFn.java" 22]
[java.util.concurrent.ThreadPoolExecutor runWorker "ThreadPoolExecutor.java" 1128]
[java.util.concurrent.ThreadPoolExecutor$Worker run "ThreadPoolExecutor.java" 628]
[java.lang.Thread run "Thread.java" 834]]}}
at slingshot.support$stack_trace.invoke(support.clj:201)
at puppetlabs.puppetdb.command$store_report$fn__40246.invoke(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invokeStatic(command.clj:377)
at puppetlabs.puppetdb.command$store_report.invoke(command.clj:376)
at puppetlabs.puppetdb.command$process_command_BANG_.invokeStatic(command.clj:412)
at puppetlabs.puppetdb.command$process_command_BANG_.invoke(command.clj:403)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_$fn__40354.invoke(command.clj:467)
at puppetlabs.puppetdb.command$call_with_quick_retry$fn__40348.invoke(command.clj:448)
at puppetlabs.puppetdb.command$call_with_quick_retry.invokeStatic(command.clj:447)
at puppetlabs.puppetdb.command$call_with_quick_retry.invoke(command.clj:445)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invokeStatic(command.clj:465)
at puppetlabs.puppetdb.command$process_command_and_respond_BANG_.invoke(command.clj:462)
at puppetlabs.puppetdb.command$process_cmdref$fn__40364.invoke(command.clj:530)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713$fn__38714.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics.proxy$java.lang.Object$Callable$7da976d4.call(Unknown Source)
at com.codahale.metrics.Timer.time(Timer.java:101)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_$fn__38712$fn__38713.invoke(metrics.clj:14)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invokeStatic(metrics.clj:17)
at puppetlabs.puppetdb.utils.metrics$multitime_BANG__STAR_.invoke(metrics.clj:6)
at puppetlabs.puppetdb.command$process_cmdref.invokeStatic(command.clj:526)
at puppetlabs.puppetdb.command$process_cmdref.invoke(command.clj:505)
at puppetlabs.puppetdb.command$message_handler$fn__40379.invoke(command.clj:593)
at puppetlabs.puppetdb.threadpool$dochan$fn__40117$fn__40118.invoke(threadpool.clj:117)
at puppetlabs.puppetdb.threadpool$call_on_threadpool$fn__40113.invoke(threadpool.clj:95)
at clojure.lang.AFn.run(AFn.java:22)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:834)
2019-03-01T17:16:08.204-05:00 INFO [p.p.command] [101-1551478568107] [46 ms] 'replace facts' command processed for pc-stretch.test
2019-03-01T17:16:14.346-05:00 INFO [p.p.command] [102-1551478571208] [3042 ms] 'replace catalog' command processed for pc-stretch.test
2019-03-01T17:16:43.357-05:00 INFO [p.p.command] [103-1551478603232] [65 ms] 'store report' puppet v4.8.2 command processed for pc-stretch.test
2019-03-01T17:19:26.983-05:00 INFO [p.p.c.services] Starting sweep of stale nodes (threshold: 7 days)
2019-03-01T17:19:26.993-05:00 INFO [p.p.c.services] Finished sweep of stale nodes (threshold: 7 days)
2019-03-01T17:19:26.995-05:00 INFO [p.p.c.services] Starting purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T17:19:26.999-05:00 INFO [p.p.c.services] Finished purge deactivated and expired nodes (threshold: 14 days)
2019-03-01T17:19:27.000-05:00 INFO [p.p.c.services] Starting sweep of stale reports (threshold: 14 days)
2019-03-01T17:19:27.002-05:00 INFO [p.p.c.services] Finished sweep of stale reports (threshold: 14 days)
2019-03-01T17:19:27.002-05:00 INFO [p.p.c.services] Starting gc packages
2019-03-01T17:19:27.004-05:00 INFO [p.p.c.services] Finished gc packages
2019-03-01T17:19:27.004-05:00 INFO [p.p.c.services] Starting database garbage collection
2019-03-01T17:19:27.400-05:00 INFO [p.p.c.services] Finished database garbage collection
2019-03-01T17:26:37.044-05:00 INFO [p.t.internal] Shutting down due to JVM shutdown hook.
2019-03-01T17:26:37.064-05:00 INFO [p.t.internal] Beginning shutdown sequence
2019-03-01T17:26:37.092-05:00 INFO [p.p.command] Halted delayed command processsing
2019-03-01T17:26:37.122-05:00 INFO [p.p.c.services] Shutdown request received; puppetdb exiting.
2019-03-01T17:26:37.135-05:00 INFO [p.p.c.services] Periodic activities halted
2019-03-01T17:26:37.136-05:00 INFO [c.z.h.HikariDataSource] PDBWritePool - Shutdown initiated...
2019-03-01T17:26:37.206-05:00 INFO [c.z.h.HikariDataSource] PDBWritePool - Shutdown completed.
2019-03-01T17:26:37.206-05:00 INFO [c.z.h.HikariDataSource] PDBReadPool - Shutdown initiated...
2019-03-01T17:26:37.246-05:00 INFO [c.z.h.HikariDataSource] PDBReadPool - Shutdown completed.
2019-03-01T17:26:37.254-05:00 INFO [p.t.s.w.jetty9-service] Shutting down web server(s).
2019-03-01T17:26:37.292-05:00 INFO [p.t.s.w.jetty9-core] Shutting down web server.
2019-03-01T17:26:37.348-05:00 INFO [o.e.j.s.AbstractConnector] Stopped ServerConnector at 46563b36{HTTP/1.1,[http/1.1]}{localhost:8080}
2019-03-01T17:26:37.354-05:00 INFO [o.e.j.s.AbstractConnector] Stopped ServerConnector at 339799ae{SSL,[ssl, http/1.1]}{localhost:8081}
2019-03-01T17:26:37.358-05:00 INFO [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler at 6b9971b{/,null,UNAVAILABLE}
2019-03-01T17:26:37.359-05:00 INFO [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler at 6967d9e3{/status,null,UNAVAILABLE}
2019-03-01T17:26:37.360-05:00 INFO [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler at 321bcb1a{/metrics,null,UNAVAILABLE}
2019-03-01T17:26:37.360-05:00 INFO [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler at 5ee919c2{/pdb,null,UNAVAILABLE}
2019-03-01T17:26:37.367-05:00 INFO [p.t.s.w.jetty9-core] Web server shutdown
2019-03-01T17:26:37.377-05:00 INFO [p.t.internal] Finished shutdown sequence
More information about the Pkg-puppet-devel
mailing list