Skip to content

Instantly share code, notes, and snippets.

@mullr

mullr/*cider-repl puppetdb*.txt

Created February 23, 2017 21:12
Show Gist options
  • Save mullr/f11e41c71141b90d426df362e3c8a16e to your computer and use it in GitHub Desktop.
Save mullr/f11e41c71141b90d426df362e3c8a16e to your computer and use it in GitHub Desktop.
Download ZIP
Raw
*cider-repl puppetdb*.txt
2017-02-23 13:09:54,814 DEBUG [nREPL-worker-7] [p.t.internal] Initializing lifecycle worker loop.
2017-02-23 13:09:54,816 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@38522fc0
2017-02-23 13:09:54,817 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@aff599e
2017-02-23 13:09:54,821 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@438a6b1
2017-02-23 13:09:54,822 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@3695c6ba
2017-02-23 13:09:54,825 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@732ee
2017-02-23 13:09:54,833 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@4505ac8a
2017-02-23 13:09:54,833 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@32aa44b0
2017-02-23 13:09:54,836 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@35c62e1d
2017-02-23 13:09:54,839 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@77a8cc81
2017-02-23 13:09:54,840 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@748a0b3a
2017-02-23 13:09:54,842 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@5eadc19b
2017-02-23 13:09:54,843 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@4da3d707
2017-02-23 13:09:54,845 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@46957ce2
2017-02-23 13:09:54,846 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@252a53c5
2017-02-23 13:09:54,849 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@219827bb
2017-02-23 13:09:54,849 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@267a2f44
2017-02-23 13:09:54,852 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@56f2b69
2017-02-23 13:09:54,853 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@178733ce
2017-02-23 13:09:54,855 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@56a7df2c
2017-02-23 13:09:54,856 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@271699bc
2017-02-23 13:09:54,859 DEBUG [Hikari connection adder (pool PDBWritePool)] [c.z.h.p.HikariPool] PDBWritePool - Added connection org.postgresql.jdbc.PgConnection@21640a46
2017-02-23 13:09:54,859 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@4a0993d0
2017-02-23 13:09:54,862 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@3d7e85da
2017-02-23 13:09:54,865 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@13c9c8f8
2017-02-23 13:09:54,868 DEBUG [Hikari connection adder (pool PDBReadPool)] [c.z.h.p.HikariPool] PDBReadPool - Added connection org.postgresql.jdbc.PgConnection@16844
2017-02-23 13:09:54,877 DEBUG [async-dispatch-5] [p.t.internal] Lifecycle worker executing :boot lifecycle task.
2017-02-23 13:09:54,877 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':ConfigService'
2017-02-23 13:09:54,877 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':SchedulerService'
2017-02-23 13:09:54,877 DEBUG [async-dispatch-5] [p.t.s.s.scheduler-service] Initializing Scheduler Service
2017-02-23 13:09:54,877 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':WebserverService'
2017-02-23 13:09:54,877 INFO [async-dispatch-5] [p.t.s.w.jetty9-service] Initializing web server(s).
2017-02-23 13:09:54,878 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':WebroutingService'
2017-02-23 13:09:54,878 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':StatusService'
2017-02-23 13:09:54,878 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':AuthorizationService'
2017-02-23 13:09:54,880 DEBUG [async-dispatch-5] [p.t.s.a.authorization-service] Transformed auth.conf rules:
({:type :path,
:path #"^\Q/\E",
:acl #{},
:method :any,
:sort-order 1,
:name "allow all",
:allow-unauthenticated true})
2017-02-23 13:09:54,880 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':PoolManagerService'
2017-02-23 13:09:54,880 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':PuppetProfilerService'
2017-02-23 13:09:54,880 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':ShutdownService'
2017-02-23 13:09:54,881 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':JRubyPuppetService'
2017-02-23 13:09:54,881 INFO [async-dispatch-5] [p.s.j.jruby-puppet-service] Initializing the JRuby service
2017-02-23 13:09:54,881 INFO [async-dispatch-5] [p.s.j.jruby-puppet-core] Facter jar not found in ruby load path
2017-02-23 13:09:54,881 INFO [async-dispatch-5] [p.s.j.jruby-pool-manager-service] Initializing the JRuby service
2017-02-23 13:09:54,881 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':PuppetServerConfigService'
2017-02-23 13:09:54,885 DEBUG [clojure-agent-send-pool-7] [p.s.j.i.jruby-agents] Initializing JRubyInstances with the following settings:
{:gem-path "vendor/puppetserver-gems",
:compat-version "1.9",
:compile-mode :off,
:max-active-instances 1,
:environment-vars {},
:lifecycle
{:shutdown-on-error #function[clojure.core/partial/fn--4759],
:initialize-pool-instance
#function[puppetlabs.services.jruby.jruby-puppet-core/eval69870/get-initialize-pool-instance-fn--69875/fn--69876/fn--69877],
:cleanup
#function[puppetlabs.services.jruby.jruby-puppet-core/eval69897/cleanup-fn--69902],
:initialize-scripting-container
#function[puppetlabs.services.jruby-pool-manager.jruby-core/eval69328/default-initialize-scripting-container--69333]},
:max-borrows-per-instance 0,
:gem-home "vendor/puppetserver-gems",
:ruby-load-path ("puppetserver-lib" "./puppet/lib"),
:borrow-timeout 1200000}
2017-02-23 13:09:54,885 DEBUG [clojure-agent-send-pool-7] [p.s.j.i.jruby-agents] Priming JRubyInstance 1 of 1
2017-02-23 13:09:54,885 INFO [clojure-agent-send-pool-7] [p.s.j.i.jruby-internal] Creating JRubyInstance with id 1.
2017-02-23 13:09:56,751 WARN [clojure-agent-send-pool-7] [puppetserver] Puppet Support for ruby version 1.9.3 is deprecated and will be removed in a future release. See https://docs.puppet.com/puppet/latest/system_requirements.html#ruby for a list of supported ruby versions.
(at /home/mullr/devel/puppetdb/vendor/puppetserver-gems/gems/puppet-4.9.2/lib/puppet.rb:167:in `Puppet')
2017-02-23 13:09:56,766 INFO [clojure-agent-send-pool-7] [puppetserver] Puppet Puppet settings initialized; run mode: master
2017-02-23 13:09:56,767 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:56,768 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:56,768 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Applying settings catalog for sections main, master, ssl, metrics
2017-02-23 13:09:56,789 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:56,789 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:56,791 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:56,791 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:56,793 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:56,793 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:56,795 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:56,795 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:56,799 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:56,799 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:56,801 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:56,801 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:56,812 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:56,812 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:56,814 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:56,814 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:56,823 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Failed to load library 'selinux' for feature 'selinux'
2017-02-23 13:09:56,825 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'confdir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,825 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'codedir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-code]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-code", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,825 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'vardir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,826 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'logdir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/log]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/log", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,826 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'statedir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/state]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/state", :mode=>"1755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,826 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'rundir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/run]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/run", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,827 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'libdir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/lib]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/lib", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,827 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'preview_outputdir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/preview]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/preview", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,827 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'certdir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,828 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'ssldir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl", :mode=>"771", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,828 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'publickeydir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/public_keys]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/public_keys", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,828 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'requestdir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certificate_requests]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certificate_requests", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,829 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'privatekeydir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private_keys]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private_keys", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,829 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'privatedir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,830 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'hostcert': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs/localhost.pem]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs/localhost.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,830 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'hostprivkey': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private_keys/localhost.pem]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private_keys/localhost.pem", :mode=>"640", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,830 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'hostpubkey': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/public_keys/localhost.pem]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/public_keys/localhost.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,831 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'localcacert': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs/ca.pem]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs/ca.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,831 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'hostcrl': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/crl.pem]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/crl.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,832 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'masterhttplog': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/log/masterhttp.log]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/log/masterhttp.log", :mode=>"660", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,832 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'bucketdir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/bucket]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/bucket", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,833 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'yamldir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/yaml]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/yaml", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,833 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'server_datadir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/server_data]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/server_data", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,833 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'reportdir': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/reports]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/reports", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,834 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Using settings: adding file resource 'pluginfactdest': 'File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/facts.d]{:path=>"/home/mullr/devel/puppetdb/target/puppetserver/master-var/facts.d", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'
2017-02-23 13:09:56,935 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:56,936 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:56,937 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:56,937 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:56,941 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:56,941 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:56,943 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:56,943 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:56,953 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Failed to load library 'shadow' for feature 'libshadow'
2017-02-23 13:09:56,980 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:56,980 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:56,982 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:56,982 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:56,992 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Failed to load library 'cfpropertylist' for feature 'cfpropertylist'
2017-02-23 13:09:57,040 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/log] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,041 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/state] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,041 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/run] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,041 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/lib] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,042 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/preview] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,042 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl]
2017-02-23 13:09:57,042 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf]
2017-02-23 13:09:57,043 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/public_keys] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl]
2017-02-23 13:09:57,043 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certificate_requests] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl]
2017-02-23 13:09:57,043 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private_keys] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl]
2017-02-23 13:09:57,043 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl]
2017-02-23 13:09:57,044 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs/localhost.pem] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs]
2017-02-23 13:09:57,044 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private_keys/localhost.pem] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private_keys]
2017-02-23 13:09:57,044 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/public_keys/localhost.pem] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/public_keys]
2017-02-23 13:09:57,045 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs/ca.pem] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs]
2017-02-23 13:09:57,045 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/crl.pem] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl]
2017-02-23 13:09:57,045 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/log/masterhttp.log] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/log]
2017-02-23 13:09:57,045 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/bucket] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,046 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/yaml] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,046 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/server_data] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,046 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/reports] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,047 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-var/facts.d] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-var]
2017-02-23 13:09:57,047 DEBUG [clojure-agent-send-pool-7] [puppetserver] /File[/home/mullr/devel/puppetdb/target/puppetserver/master-code/environments/production] Adding autorequire relationship with File[/home/mullr/devel/puppetdb/target/puppetserver/master-code]
2017-02-23 13:09:57,063 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Finishing transaction 31210
2017-02-23 13:09:57,063 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:57,064 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:57,065 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:57,065 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:57,069 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:57,069 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:57,070 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:57,071 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:57,071 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:57,072 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:57,073 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:57,073 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:57,075 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:57,075 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:57,077 DEBUG [clojure-agent-send-pool-7] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:57,077 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:57,078 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Routes Registered:
2017-02-23 13:09:57,078 DEBUG [clojure-agent-send-pool-7] [puppetserver] Puppet Route /^\/puppet\//
2017-02-23 13:09:57,079 INFO [clojure-agent-send-pool-7] [p.s.j.i.jruby-agents] Finished creating JRubyInstance 1 of 1
2017-02-23 13:09:57,113 DEBUG [async-dispatch-5] [p.s.c.puppet-server-config-service] Initializing with the following settings from core Puppet:
{:keylength 4096,
:ca-name "Puppet CA: localhost",
:hostcrl
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/crl.pem",
:hostprivkey
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private_keys/localhost.pem",
:csrdir
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/ca/requests",
:capub
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/ca/ca_pub.pem",
:hostcert
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs/localhost.pem",
:requestdir
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certificate_requests",
:ssl-client-verify-header "HTTP_X_CLIENT_VERIFY",
:dns-alt-names "",
:puppet-version "4.9.2",
:cacert
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/ca/ca_crt.pem",
:localcacert
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs/ca.pem",
:autosign true,
:csr-attributes
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/csr_attributes.yaml",
:privatekeydir
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/private_keys",
:trusted-oid-mapping-file
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/custom_trusted_oid_mapping.yaml",
:ca-ttl 157680000,
:cakey
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/ca/ca_key.pem",
:cacrl
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/ca/ca_crl.pem",
:serial
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/ca/serial",
:manage-internal-file-permissions true,
:certdir
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/certs",
:codedir "/home/mullr/devel/puppetdb/target/puppetserver/master-code",
:cert-inventory
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/ca/inventory.txt",
:hostpubkey
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/public_keys/localhost.pem",
:ssl-client-header "HTTP_X_CLIENT_DN",
:certname "localhost",
:data-binding-terminus
#object[org.jruby.RubySymbol 0x204d5991 "hiera"],
:signeddir
"/home/mullr/devel/puppetdb/target/puppetserver/master-conf/ssl/ca/signed",
:allow-duplicate-certs false}
2017-02-23 13:09:57,113 INFO [async-dispatch-5] [p.s.c.puppet-server-config-core] Initializing webserver settings from core Puppet
2017-02-23 13:09:57,113 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':CaService'
2017-02-23 13:09:57,114 DEBUG [async-dispatch-5] [p.p.certificate-authority] No custom OID mapping configuration file found at /home/mullr/devel/puppetdb/target/puppetserver/master-conf/custom_trusted_oid_mapping.yaml, custom OID mappings will not be loaded
2017-02-23 13:09:57,114 INFO [async-dispatch-5] [p.p.certificate-authority] CA already initialized for SSL
2017-02-23 13:09:57,115 INFO [async-dispatch-5] [p.s.c.certificate-authority-service] CA Service adding a ring handler
2017-02-23 13:09:57,115 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':tk-service75860'
2017-02-23 13:09:57,116 INFO [async-dispatch-5] [p.s.p.puppet-admin-service] Starting Puppet Admin web app
2017-02-23 13:09:57,116 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':MetricsService'
2017-02-23 13:09:57,116 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':VersionedCodeService'
2017-02-23 13:09:57,116 INFO [async-dispatch-5] [p.s.v.versioned-code-service] No code-id-command set for versioned-code-service. Code-id will be nil.
2017-02-23 13:09:57,117 INFO [async-dispatch-5] [p.s.v.versioned-code-service] No code-content-command set for versioned-code-service. Attempting to fetch code content will fail.
2017-02-23 13:09:57,117 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':RequestHandlerService'
2017-02-23 13:09:57,117 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':MasterService'
2017-02-23 13:09:57,119 DEBUG [clojure-agent-send-off-pool-26] [p.d.version-check] Checking for newer versions of {:artifact-id "puppetserver", :group-id "puppetlabs.dev"}
2017-02-23 13:09:57,119 INFO [async-dispatch-5] [p.p.certificate-authority] Master already initialized for SSL
2017-02-23 13:09:57,120 INFO [async-dispatch-5] [p.s.m.master-service] Master Service adding ring handlers
2017-02-23 13:09:57,122 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'init' for service ':tk-service75706'
2017-02-23 13:09:57,125 WARN [async-dispatch-5] [o.e.j.s.h.ContextHandler] Empty contextPath
2017-02-23 13:09:57,126 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':ConfigService'
2017-02-23 13:09:57,126 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':SchedulerService'
2017-02-23 13:09:57,126 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':WebserverService'
2017-02-23 13:09:57,126 INFO [async-dispatch-5] [p.t.s.w.jetty9-service] Starting web server(s).
2017-02-23 13:09:57,127 INFO [async-dispatch-5] [p.t.s.w.jetty9-core] webserver config overridden for key 'ssl-cert'
2017-02-23 13:09:57,128 INFO [async-dispatch-5] [p.t.s.w.jetty9-core] webserver config overridden for key 'ssl-key'
2017-02-23 13:09:57,128 INFO [async-dispatch-5] [p.t.s.w.jetty9-core] webserver config overridden for key 'ssl-ca-cert'
2017-02-23 13:09:57,128 INFO [async-dispatch-5] [p.t.s.w.jetty9-core] webserver config overridden for key 'ssl-crl-path'
2017-02-23 13:09:57,139 INFO [async-dispatch-5] [p.t.s.w.jetty9-core] Starting web server.
2017-02-23 13:09:57,139 INFO [async-dispatch-5] [o.e.j.s.Server] jetty-9.2.10.v20150310
2017-02-23 13:09:57,155 INFO [async-dispatch-5] [o.e.j.s.h.ContextHandler] Started o.e.j.s.h.ContextHandler@1c046fc2{/puppet-ca,null,AVAILABLE}
2017-02-23 13:09:57,155 INFO [async-dispatch-5] [o.e.j.s.h.ContextHandler] Started o.e.j.s.h.ContextHandler@7943c53{/puppet-admin-api,null,AVAILABLE}
2017-02-23 13:09:57,155 INFO [async-dispatch-5] [o.e.j.s.h.ContextHandler] Started o.e.j.s.h.ContextHandler@1846d3c9{/puppet,null,AVAILABLE}
2017-02-23 13:09:57,155 INFO [async-dispatch-5] [o.e.j.s.h.ContextHandler] Started o.e.j.s.h.ContextHandler@5726fd59{/,null,AVAILABLE}
2017-02-23 13:09:57,162 INFO [async-dispatch-5] [o.e.j.s.ServerConnector] Started ServerConnector@1b3889fc{SSL-HTTP/1.1}{0.0.0.0:8140}
2017-02-23 13:09:57,162 INFO [async-dispatch-5] [o.e.j.s.Server] Started @1377268ms
2017-02-23 13:09:57,162 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':WebroutingService'
2017-02-23 13:09:57,162 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':StatusService'
2017-02-23 13:09:57,163 INFO [async-dispatch-5] [p.t.s.s.status-core] Starting background monitoring of cpu usage metrics
2017-02-23 13:09:57,163 INFO [async-dispatch-5] [p.t.s.s.status-service] Registering status callback function for service 'status-service', version 0.7.1
2017-02-23 13:09:57,164 INFO [async-dispatch-5] [p.t.s.s.status-service] Registering status service HTTP API at /status
2017-02-23 13:09:57,165 INFO [async-dispatch-5] [o.e.j.s.h.ContextHandler] Started o.e.j.s.h.ContextHandler@3282ca8d{/status,null,AVAILABLE}
2017-02-23 13:09:57,165 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':AuthorizationService'
2017-02-23 13:09:57,165 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':PoolManagerService'
2017-02-23 13:09:57,165 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':PuppetProfilerService'
2017-02-23 13:09:57,165 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':ShutdownService'
2017-02-23 13:09:57,165 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':JRubyPuppetService'
2017-02-23 13:09:57,166 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':PuppetServerConfigService'
2017-02-23 13:09:57,166 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':CaService'
2017-02-23 13:09:57,166 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':tk-service75860'
2017-02-23 13:09:57,166 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':MetricsService'
2017-02-23 13:09:57,166 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':VersionedCodeService'
2017-02-23 13:09:57,166 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':RequestHandlerService'
2017-02-23 13:09:57,166 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':MasterService'
2017-02-23 13:09:57,166 INFO [async-dispatch-5] [p.s.m.master-service] Puppet Server has successfully started and is now ready to handle requests
2017-02-23 13:09:57,166 DEBUG [async-dispatch-5] [p.t.internal] Running lifecycle function 'start' for service ':tk-service75706'
2017-02-23 13:09:57,166 INFO [async-dispatch-5] [p.s.l.legacy-routes-service] The legacy routing service has successfully started and is now ready to handle requests
2017-02-23 13:09:57,167 DEBUG [async-dispatch-5] [p.t.internal] Lifecycle worker completed :boot lifecycle task; awaiting next task.
2017-02-23 13:09:58,828 DEBUG [qtp228636330-1172] [p.r.core] Processing :get /puppet/v3/node/my-agent
2017-02-23 13:09:58,993 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /^\/puppet\//
2017-02-23 13:09:58,993 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /v3/
2017-02-23 13:09:58,993 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /^\/environments$/
2017-02-23 13:09:58,994 DEBUG [qtp228636330-1172] [puppetserver] Puppet Did not match path ("/node/my-agent")
2017-02-23 13:09:58,994 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /^\/environment\/[^\/]+$/
2017-02-23 13:09:58,994 DEBUG [qtp228636330-1172] [puppetserver] Puppet Did not match path ("/node/my-agent")
2017-02-23 13:09:58,994 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /.*/
2017-02-23 13:09:58,994 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:58,995 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:58,996 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:58,996 DEBUG [qtp228636330-1172] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:58,997 DEBUG [qtp228636330-1172] [puppetserver] Puppet Using PuppetServer AuthConfig for master routes
2017-02-23 13:09:58,997 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:58,998 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:58,999 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:58,999 DEBUG [qtp228636330-1172] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:59,002 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:59,003 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:59,005 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:59,005 DEBUG [qtp228636330-1172] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:59,006 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:59,006 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:59,008 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:59,008 DEBUG [qtp228636330-1172] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:59,036 DEBUG [qtp228636330-1172] [puppetserver] Puppet Configuring PuppetDB terminuses with config file /home/mullr/devel/puppetdb/target/puppetserver/master-conf/puppetdb.conf
2017-02-23 13:09:59,342 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing HTTP request to URI: '/pdb/query/v4/nodes/my-agent/facts'
2017-02-23 13:09:59,349 DEBUG [qtp1798651069-1125] [c.z.h.p.PoolBase] PDBReadPool - Reset (nothing) on connection org.postgresql.jdbc.PgConnection@439f9eb8
2017-02-23 13:09:59,352 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb facts find query_nodes my-agent] (103 ms) PuppetDB: Query for nodes facts: /pdb/query/v4/nodes/my-agent/facts
2017-02-23 13:09:59,352 DEBUG [qtp228636330-1172] [puppetserver] Puppet HTTP 404 (probably normal) when connecting to localhost on 33295 at route /pdb/query/v4/nodes/my-agent/facts, error message received was ''. Failing over to the next PuppetDB server_url in the 'server_urls' list
2017-02-23 13:09:59,353 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb facts find my-agent] (317 ms) PuppetDB: facts#find
2017-02-23 13:09:59,360 INFO [qtp228636330-1172] [puppetserver] Puppet Caching facts for my-agent
2017-02-23 13:09:59,361 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb facts encode] (0 ms) PuppetDB: Encode facts command submission payload
2017-02-23 13:09:59,362 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb payload format] (0 ms) PuppetDB: Format payload
2017-02-23 13:09:59,461 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing HTTP request to URI: '/pdb/cmd/v1'
2017-02-23 13:09:59,461 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing command with a content-length of 6,018 bytes
2017-02-23 13:09:59,470 DEBUG [qtp1798651069-1125] [p.p.command] [0-1,487,884,199,462] 'replace facts' command enqueued for my-agent
2017-02-23 13:09:59,472 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb command submit] (104 ms) PuppetDB: Submit command HTTP post
2017-02-23 13:09:59,488 INFO [qtp228636330-1172] [puppetserver] Puppet 'replace_facts' command for my-agent submitted to PuppetDB with UUID 49efd6ba-6f72-4c2a-902f-31f38eb9dbd8
2017-02-23 13:09:59,488 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb command submit replace facts 5] (126 ms) PuppetDB: Submitted command 'replace facts' version '5'
2017-02-23 13:09:59,488 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb facts save my-agent] (127 ms) PuppetDB: facts#save
2017-02-23 13:09:59,489 INFO [qtp228636330-1172] [puppetserver] Puppet Caching node for my-agent
2017-02-23 13:09:59,502 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:59,502 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:59,506 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:59,506 DEBUG [qtp228636330-1172] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:59,517 DEBUG [qtp228636330-1172] [puppetserver] Puppet Failed to load library 'msgpack' for feature 'msgpack'
2017-02-23 13:09:59,518 DEBUG [qtp228636330-1172] [puppetserver] Puppet Puppet::Network::Format[msgpack]: feature msgpack is missing
2017-02-23 13:09:59,518 DEBUG [qtp228636330-1172] [puppetserver] Puppet node supports formats: pson yaml binary
2017-02-23 13:09:59,519 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [http v3_render Puppet::Network::Format[pson]] (1 ms) Rendered result in Puppet::Network::Format[pson]
2017-02-23 13:09:59,519 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [http v3_response] (0 ms) Sent response
2017-02-23 13:09:59,519 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [http GET /puppet/v3/node/my-agent] (526 ms) Processed request GET /puppet/v3/node/my-agent
2017-02-23 13:09:59,542 DEBUG [cmd-proc-thread-1] [c.z.h.p.PoolBase] PDBWritePool - Reset (nothing) on connection org.postgresql.jdbc.PgConnection@78925e78
2017-02-23 13:09:59,542 INFO [cmd-proc-thread-1] [p.p.command] [0-1487884199462] 'replace facts' command processed for my-agent
2017-02-23 13:09:59,583 DEBUG [qtp228636330-1170] [p.r.core] Processing :get /puppet/v3/file_metadatas/pluginfacts
2017-02-23 13:09:59,591 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /^\/puppet\//
2017-02-23 13:09:59,592 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /v3/
2017-02-23 13:09:59,592 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /^\/environments$/
2017-02-23 13:09:59,592 DEBUG [qtp228636330-1170] [puppetserver] Puppet Did not match path ("/file_metadatas/pluginfacts")
2017-02-23 13:09:59,592 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /^\/environment\/[^\/]+$/
2017-02-23 13:09:59,592 DEBUG [qtp228636330-1170] [puppetserver] Puppet Did not match path ("/file_metadatas/pluginfacts")
2017-02-23 13:09:59,593 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /.*/
2017-02-23 13:09:59,593 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:59,593 DEBUG [qtp228636330-1170] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:59,595 DEBUG [qtp228636330-1170] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:59,595 DEBUG [qtp228636330-1170] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:59,597 DEBUG [qtp228636330-1170] [puppetserver] Puppet Warning: calling Plugins.search with empty module path.
2017-02-23 13:09:59,597 DEBUG [qtp228636330-1170] [puppetserver] Puppet Puppet::Network::Format[msgpack]: feature msgpack is missing
2017-02-23 13:09:59,598 DEBUG [qtp228636330-1170] [puppetserver] Puppet file_metadata supports formats: pson yaml binary
2017-02-23 13:09:59,598 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [http GET /puppet/v3/file_metadatas/pluginfacts] (7 ms) Processed request GET /puppet/v3/file_metadatas/pluginfacts
2017-02-23 13:09:59,606 DEBUG [qtp228636330-1171] [p.r.core] Processing :get /puppet/v3/file_metadatas/plugins
2017-02-23 13:09:59,609 DEBUG [qtp228636330-1171] [puppetserver] Puppet Evaluating match for Route /^\/puppet\//
2017-02-23 13:09:59,609 DEBUG [qtp228636330-1171] [puppetserver] Puppet Evaluating match for Route /v3/
2017-02-23 13:09:59,609 DEBUG [qtp228636330-1171] [puppetserver] Puppet Evaluating match for Route /^\/environments$/
2017-02-23 13:09:59,609 DEBUG [qtp228636330-1171] [puppetserver] Puppet Did not match path ("/file_metadatas/plugins")
2017-02-23 13:09:59,609 DEBUG [qtp228636330-1171] [puppetserver] Puppet Evaluating match for Route /^\/environment\/[^\/]+$/
2017-02-23 13:09:59,610 DEBUG [qtp228636330-1171] [puppetserver] Puppet Did not match path ("/file_metadatas/plugins")
2017-02-23 13:09:59,610 DEBUG [qtp228636330-1171] [puppetserver] Puppet Evaluating match for Route /.*/
2017-02-23 13:09:59,610 DEBUG [qtp228636330-1171] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:09:59,610 DEBUG [qtp228636330-1171] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:09:59,611 DEBUG [qtp228636330-1171] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:09:59,612 DEBUG [qtp228636330-1171] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:09:59,612 DEBUG [qtp228636330-1171] [puppetserver] Puppet Warning: calling Plugins.search with empty module path.
2017-02-23 13:09:59,613 DEBUG [qtp228636330-1171] [puppetserver] Puppet Puppet::Network::Format[msgpack]: feature msgpack is missing
2017-02-23 13:09:59,613 DEBUG [qtp228636330-1171] [puppetserver] Puppet file_metadata supports formats: pson yaml binary
2017-02-23 13:09:59,613 DEBUG [qtp228636330-1171] [c.p.p.LoggingPuppetProfiler] [http GET /puppet/v3/file_metadatas/plugins] (4 ms) Processed request GET /puppet/v3/file_metadatas/plugins
2017-02-23 13:10:00,682 DEBUG [qtp228636330-1172] [p.r.core] Processing :post /puppet/v3/catalog/my-agent
2017-02-23 13:10:00,685 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /^\/puppet\//
2017-02-23 13:10:00,685 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /v3/
2017-02-23 13:10:00,685 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /^\/environments$/
2017-02-23 13:10:00,686 DEBUG [qtp228636330-1172] [puppetserver] Puppet Did not match path ("/catalog/my-agent")
2017-02-23 13:10:00,686 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /^\/environment\/[^\/]+$/
2017-02-23 13:10:00,686 DEBUG [qtp228636330-1172] [puppetserver] Puppet Did not match path ("/catalog/my-agent")
2017-02-23 13:10:00,686 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evaluating match for Route /.*/
2017-02-23 13:10:00,686 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:10:00,686 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:10:00,688 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:10:00,688 DEBUG [qtp228636330-1172] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:10:00,688 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:10:00,688 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:10:00,689 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:10:00,689 DEBUG [qtp228636330-1172] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:10:00,711 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler init_server_facts] (20 ms) Setup server facts for compiling
2017-02-23 13:10:00,712 DEBUG [qtp228636330-1172] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:10:00,712 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:10:00,714 DEBUG [qtp228636330-1172] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:10:00,714 DEBUG [qtp228636330-1172] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:10:00,728 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb facts encode] (1 ms) PuppetDB: Encode facts command submission payload
2017-02-23 13:10:00,728 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb payload format] (0 ms) PuppetDB: Format payload
2017-02-23 13:10:00,822 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing HTTP request to URI: '/pdb/cmd/v1'
2017-02-23 13:10:00,823 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing command with a content-length of 6,018 bytes
2017-02-23 13:10:00,832 DEBUG [qtp1798651069-1125] [p.p.command] [1-1,487,884,200,824] 'replace facts' command enqueued for my-agent
2017-02-23 13:10:00,834 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb command submit] (105 ms) PuppetDB: Submit command HTTP post
2017-02-23 13:10:00,834 INFO [qtp228636330-1172] [puppetserver] Puppet 'replace_facts' command for my-agent submitted to PuppetDB with UUID 787ed36a-2faf-40d4-a8f2-c4713dc55b83
2017-02-23 13:10:00,835 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb command submit replace facts 5] (107 ms) PuppetDB: Submitted command 'replace facts' version '5'
2017-02-23 13:10:00,835 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb facts save my-agent] (108 ms) PuppetDB: facts#save
2017-02-23 13:10:00,835 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler find_facts] (119 ms) Found facts
2017-02-23 13:10:00,869 DEBUG [cmd-proc-thread-1] [c.z.h.p.PoolBase] PDBWritePool - Reset (nothing) on connection org.postgresql.jdbc.PgConnection@78925e78
2017-02-23 13:10:00,870 INFO [cmd-proc-thread-1] [p.p.command] [1-1487884200824] 'replace facts' command processed for my-agent
2017-02-23 13:10:00,939 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing HTTP request to URI: '/pdb/query/v4/nodes/my-agent/facts'
2017-02-23 13:10:00,943 DEBUG [qtp1798651069-1125] [c.z.h.p.PoolBase] PDBReadPool - Reset (nothing) on connection org.postgresql.jdbc.PgConnection@439f9eb8
2017-02-23 13:10:00,985 DEBUG [qtp1798651069-1125] [p.p.q.engine] new-selection
=> {:from [[:factsets :fs]],
:join
[[:facts :f]
[:= :fs.id :f.factset_id]
[:fact_values :fv]
[:= :f.fact_value_id :fv.id]
[:fact_paths :fp]
[:= :f.fact_path_id :fp.id]
[:value_types :vt]
[:= :vt.id :fv.value_type_id]],
:left-join [[:environments :env] [:= :fs.environment_id :env.id]],
:where
[:and
[:= :fp.depth 0]
(:and
[:or
[:=
:fs.certname
"?"]]2017-02-23 13:10:00,961 DEBUG [qtp1798651069-1125] [p.p.q.engine] new-selection
=> {:from [:certnames],
:left-join
[:catalogs
[:= :catalogs.certname :certnames.certname]
[:factsets :fs]
[:= :certnames.certname :fs.certname]
:reports
[:= :certnames.latest_report_id :reports.id]
[:environments :catalog_environment]
[:= :catalog_environment.id :catalogs.environment_id]
:report_statuses
[:= :reports.status_id :report_statuses.id]
[:environments :facts_environment]
[:= :facts_environment.id :fs.environment_id]
[:environments :reports_environment]
[:= :reports_environment.id :reports.environment_id]],
:where
(:and [:is :certnames.deactivated nil] [:is :certnames.expired nil]),
:select [[:certnames.certname "certname"]]}
2017-02-23 13:10:00,971 DEBUG [qtp1798651069-1125] [p.p.q.engine] (first (hcore/format (honeysql-from-query query)))
=> "SELECT certnames.certname AS certname FROM certnames LEFT JOIN catalogs ON catalogs.certname = certnames.certname LEFT JOIN factsets fs ON certnames.certname = fs.certname LEFT JOIN reports ON certnames.latest_report_id = reports.id LEFT JOIN environments catalog_environment ON catalog_environment.id = catalogs.environment_id LEFT JOIN report_statuses ON reports.status_id = report_statuses.id LEFT JOIN environments facts_environment ON facts_environment.id = fs.environment_id LEFT JOIN environments reports_environment ON reports_environment.id = reports.environment_id WHERE (certnames.deactivated IS NULL AND certnames.expired IS NULL)"
[:in
[:fs.certname]
{:s
" ( SELECT certnames.certname AS certname FROM certnames LEFT JOIN catalogs ON catalogs.certname = certnames.certname LEFT JOIN factsets fs ON certnames.certname = fs.certname LEFT JOIN reports ON certnames.latest_report_id = reports.id LEFT JOIN environments catalog_environment ON catalog_environment.id = catalogs.environment_id LEFT JOIN report_statuses ON reports.status_id = report_statuses.id LEFT JOIN environments facts_environment ON facts_environment.id = fs.environment_id LEFT JOIN environments reports_environment ON reports_environment.id = reports.environment_id WHERE (certnames.deactivated IS NULL AND certnames.expired IS NULL) ) "}])],
:select
[[:fs.certname "certname"]
[:fp.name "name"]
[:fv.value "value"]
[:env.environment "environment"]]}
2017-02-23 13:10:00,999 DEBUG [qtp1798651069-1125] [p.p.q.engine] (first (hcore/format (honeysql-from-query query)))
=> "SELECT fs.certname AS certname, fp.name AS name, fv.value AS value, env.environment AS environment FROM factsets fs INNER JOIN facts f ON fs.id = f.factset_id INNER JOIN fact_values fv ON f.fact_value_id = fv.id INNER JOIN fact_paths fp ON f.fact_path_id = fp.id INNER JOIN value_types vt ON vt.id = fv.value_type_id LEFT JOIN environments env ON fs.environment_id = env.id WHERE (fp.depth = 0 AND ((fs.certname = ?) AND ((fs.certname) in ( SELECT certnames.certname AS certname FROM certnames LEFT JOIN catalogs ON catalogs.certname = certnames.certname LEFT JOIN factsets fs ON certnames.certname = fs.certname LEFT JOIN reports ON certnames.latest_report_id = reports.id LEFT JOIN environments catalog_environment ON catalog_environment.id = catalogs.environment_id LEFT JOIN report_statuses ON reports.status_id = report_statuses.id LEFT JOIN environments facts_environment ON facts_environment.id = fs.environment_id LEFT JOIN environments reports_environment ON reports_environment.id = reports.environment_id WHERE (certnames.deactivated IS NULL AND certnames.expired IS NULL) ) )))"
2017-02-23 13:10:01,009 DEBUG [qtp1798651069-1125] [c.z.h.p.PoolBase] PDBReadPool - Reset (nothing) on connection org.postgresql.jdbc.PgConnection@439f9eb8
2017-02-23 13:10:01,013 DEBUG [clojure-agent-send-off-pool-28] [c.z.h.p.PoolBase] PDBReadPool - Reset (nothing) on connection org.postgresql.jdbc.PgConnection@52b759a6
2017-02-23 13:10:01,015 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb facts find query_nodes my-agent] (179 ms) PuppetDB: Query for nodes facts: /pdb/query/v4/nodes/my-agent/facts
2017-02-23 13:10:01,023 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb facts find parse_response my-agent] (8 ms) PuppetDB: Parse fact query response (size: 12350)
2017-02-23 13:10:01,023 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb facts find my-agent] (188 ms) PuppetDB: facts#find
2017-02-23 13:10:01,023 DEBUG [qtp228636330-1172] [puppetserver] Puppet Using cached facts for my-agent
2017-02-23 13:10:01,024 INFO [qtp228636330-1172] [puppetserver] Puppet Caching node for my-agent
2017-02-23 13:10:01,039 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler find_node] (204 ms) Found node information
2017-02-23 13:10:01,075 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler set_node_params] (3 ms) Compile: Set node parameters
2017-02-23 13:10:01,085 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler create_settings_scope] (9 ms) Compile: Created settings scope
2017-02-23 13:10:01,087 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_capability_mappings] (0 ms) Compile: Evaluated capability mappings
2017-02-23 13:10:01,161 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_resource Class[main]] (73 ms) Evaluated resource Class[main]
2017-02-23 13:10:01,161 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_main] (74 ms) Compile: Evaluated main
2017-02-23 13:10:01,161 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_site] (0 ms) Compile: Evaluated site
2017-02-23 13:10:01,161 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_ast_node] (0 ms) Compile: Evaluated AST node
2017-02-23 13:10:01,162 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_node_classes] (0 ms) Compile: Evaluated node classes
2017-02-23 13:10:01,162 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_applications] (0 ms) Compile: Evaluated application instances
2017-02-23 13:10:01,162 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_capability_mappings] (0 ms) Compile: Evaluated site capability mappings
2017-02-23 13:10:01,162 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_definitions] (0 ms) Evaluated definitions
2017-02-23 13:10:01,162 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler iterate_on_generators] (0 ms) Iterated (1) on generators
2017-02-23 13:10:01,162 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler evaluate_generators] (0 ms) Compile: Evaluated generators
2017-02-23 13:10:01,162 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler validate_pre_finish] (0 ms) Compile: Validate Catalog pre-finish
2017-02-23 13:10:01,163 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler finish_catalog] (0 ms) Compile: Finished catalog
2017-02-23 13:10:01,163 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler prune_catalog] (0 ms) Compile: Prune
2017-02-23 13:10:01,163 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler validate_final] (0 ms) Compile: Validate Catalog final
2017-02-23 13:10:01,164 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [compiler compile production my-agent] (122 ms) Compiled catalog for my-agent in environment production
2017-02-23 13:10:01,164 INFO [qtp228636330-1172] [puppetserver] Puppet Compiled catalog for my-agent in environment production in 0.12 seconds
2017-02-23 13:10:01,165 INFO [qtp228636330-1172] [puppetserver] Puppet Caching catalog for my-agent
2017-02-23 13:10:01,166 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb catalog convert_to_hash] (1 ms) PuppetDB: Convert catalog to JSON data hash
2017-02-23 13:10:01,166 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb parameters add_missing] (0 ms) PuppetDB: Add parameters if missing (resource count: 4)
2017-02-23 13:10:01,167 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb namevar_aliases add] (1 ms) PuppetDB: Add namevar aliases (resource count: 4)
2017-02-23 13:10:01,167 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb titles stringify] (0 ms) PuppetDB: Stringify titles (resource count: 4)
2017-02-23 13:10:01,167 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb metaparams sort] (0 ms) PuppetDB: Sort unordered metaparams (resource count: 4)
2017-02-23 13:10:01,167 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb edges munge] (0 ms) PuppetDB: Munge edges (edge count: 3)
2017-02-23 13:10:01,167 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb aliases map_to_title] (0 ms) PuppetDB: Map aliases to title (resource count: 4)
2017-02-23 13:10:01,168 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb edges synthesize resource_table build] (0 ms) PuppetDB: Build up resource_table
2017-02-23 13:10:01,168 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb edges synthesize primary_synthesis] (0 ms) PuppetDB: Primary synthesis
2017-02-23 13:10:01,168 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb edges synthesize make_unique] (0 ms) PuppetDB: Make edges unique
2017-02-23 13:10:01,168 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb edges synthesize] (1 ms) PuppetDB: Synthesize edges
2017-02-23 13:10:01,169 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb keys filter_extraneous] (0 ms) PuppetDB: Filter extraneous keys from the catalog
2017-02-23 13:10:01,169 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb catalog munge] (4 ms) PuppetDB: Munge catalog
2017-02-23 13:10:01,169 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb payload format] (0 ms) PuppetDB: Format payload
2017-02-23 13:10:01,271 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing HTTP request to URI: '/pdb/cmd/v1'
2017-02-23 13:10:01,272 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing command with a content-length of 1,185 bytes
2017-02-23 13:10:01,278 DEBUG [qtp1798651069-1125] [p.p.command] [2-1,487,884,201,272] 'replace catalog' command enqueued for my-agent
2017-02-23 13:10:01,279 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb command submit] (109 ms) PuppetDB: Submit command HTTP post
2017-02-23 13:10:01,280 INFO [qtp228636330-1172] [puppetserver] Puppet 'replace_catalog' command for my-agent submitted to PuppetDB with UUID f2ddf218-21db-4e07-8e27-1c201e4b1183
2017-02-23 13:10:01,280 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb command submit replace catalog 9] (111 ms) PuppetDB: Submitted command 'replace catalog' version '9'
2017-02-23 13:10:01,280 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [puppetdb catalog save my-agent] (115 ms) PuppetDB: catalog#save
2017-02-23 13:10:01,281 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [indirector filter catalog my-agent] (0 ms) Filtered result for catalog my-agent
2017-02-23 13:10:01,281 DEBUG [qtp228636330-1172] [puppetserver] Puppet Puppet::Network::Format[msgpack]: feature msgpack is missing
2017-02-23 13:10:01,282 DEBUG [qtp228636330-1172] [puppetserver] Puppet catalog supports formats: pson yaml dot binary
2017-02-23 13:10:01,283 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [http v3_render Puppet::Network::Format[pson]] (1 ms) Rendered result in Puppet::Network::Format[pson]
2017-02-23 13:10:01,283 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [http v3_response] (0 ms) Sent response
2017-02-23 13:10:01,283 DEBUG [qtp228636330-1172] [c.p.p.LoggingPuppetProfiler] [http POST /puppet/v3/catalog/my-agent] (598 ms) Processed request POST /puppet/v3/catalog/my-agent
2017-02-23 13:10:01,294 DEBUG [cmd-proc-thread-1] [c.z.h.p.PoolBase] PDBWritePool - Reset (nothing) on connection org.postgresql.jdbc.PgConnection@78925e78
2017-02-23 13:10:01,295 INFO [cmd-proc-thread-1] [p.p.command] [2-1487884201272] 'replace catalog' command processed for my-agent
2017-02-23 13:10:01,348 DEBUG [qtp228636330-1170] [p.r.core] Processing :put /puppet/v3/report/my-agent
2017-02-23 13:10:01,351 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /^\/puppet\//
2017-02-23 13:10:01,351 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /v3/
2017-02-23 13:10:01,351 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /^\/environments$/
2017-02-23 13:10:01,351 DEBUG [qtp228636330-1170] [puppetserver] Puppet Did not match path ("/report/my-agent")
2017-02-23 13:10:01,351 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /^\/environment\/[^\/]+$/
2017-02-23 13:10:01,352 DEBUG [qtp228636330-1170] [puppetserver] Puppet Did not match path ("/report/my-agent")
2017-02-23 13:10:01,352 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evaluating match for Route /.*/
2017-02-23 13:10:01,352 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:10:01,352 DEBUG [qtp228636330-1170] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:10:01,354 DEBUG [qtp228636330-1170] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:10:01,354 DEBUG [qtp228636330-1170] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:10:01,354 DEBUG [qtp228636330-1170] [puppetserver] Puppet Applying settings catalog for sections reporting
2017-02-23 13:10:01,358 DEBUG [qtp228636330-1170] [puppetserver] Puppet Finishing transaction 34344
2017-02-23 13:10:01,367 DEBUG [qtp228636330-1170] [puppetserver] Puppet Received report to process from my-agent
2017-02-23 13:10:01,367 DEBUG [qtp228636330-1170] [puppetserver] Puppet Evicting cache entry for environment 'production'
2017-02-23 13:10:01,367 DEBUG [qtp228636330-1170] [p.s.j.puppet-environments] Removing environment 'production' from registry
2017-02-23 13:10:01,370 DEBUG [qtp228636330-1170] [p.s.j.puppet-environments] Registering environment 'production'
2017-02-23 13:10:01,370 DEBUG [qtp228636330-1170] [puppetserver] Puppet Caching environment 'production' (ttl = 0 sec)
2017-02-23 13:10:01,372 DEBUG [qtp228636330-1170] [puppetserver] Puppet Processing report from my-agent with processor Puppet::Reports::Puppetdb
2017-02-23 13:10:01,373 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb events_list build] (1 ms) PuppetDB: Build events list (count: 1)
2017-02-23 13:10:01,373 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb events_list build] (0 ms) PuppetDB: Build events list (count: 0)
2017-02-23 13:10:01,373 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb events_list build] (0 ms) PuppetDB: Build events list (count: 0)
2017-02-23 13:10:01,373 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb events_list build] (0 ms) PuppetDB: Build events list (count: 0)
2017-02-23 13:10:01,373 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb events_list build] (0 ms) PuppetDB: Build events list (count: 0)
2017-02-23 13:10:01,374 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb events_list build] (0 ms) PuppetDB: Build events list (count: 0)
2017-02-23 13:10:01,374 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb events_list build] (0 ms) PuppetDB: Build events list (count: 0)
2017-02-23 13:10:01,374 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb events_list build] (0 ms) PuppetDB: Build events list (count: 0)
2017-02-23 13:10:01,374 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb resources_list build] (2 ms) PuppetDB: Build resources list (count: 8)
2017-02-23 13:10:01,375 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb logs_list build] (0 ms) PuppetDB: Build logs list (count: 8)
2017-02-23 13:10:01,376 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb metrics_list build] (0 ms) PuppetDB: Build metrics list (count: 4)
2017-02-23 13:10:01,376 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb report convert_to_wire_format_hash] (4 ms) PuppetDB: Convert report to wire format hash
2017-02-23 13:10:01,377 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb payload format] (1 ms) PuppetDB: Format payload
2017-02-23 13:10:01,473 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing HTTP request to URI: '/pdb/cmd/v1'
2017-02-23 13:10:01,473 DEBUG [qtp1798651069-1125] [p.p.middleware] Processing command with a content-length of 3,528 bytes
2017-02-23 13:10:01,480 DEBUG [qtp1798651069-1125] [p.p.command] [3-1,487,884,201,474] 'store report' command enqueued for my-agent
2017-02-23 13:10:01,481 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb command submit] (103 ms) PuppetDB: Submit command HTTP post
2017-02-23 13:10:01,481 INFO [qtp228636330-1170] [puppetserver] Puppet 'store_report' command for my-agent submitted to PuppetDB with UUID a106f2b4-296b-4de9-8b57-2f1ff03ea6a5
2017-02-23 13:10:01,482 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb command submit store report 8] (106 ms) PuppetDB: Submitted command 'store report' version '8'
2017-02-23 13:10:01,482 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [puppetdb report process] (110 ms) PuppetDB: report#process
2017-02-23 13:10:01,482 DEBUG [qtp228636330-1170] [c.p.p.LoggingPuppetProfiler] [http PUT /puppet/v3/report/my-agent] (131 ms) Processed request PUT /puppet/v3/report/my-agent
2017-02-23 13:10:01,492 DEBUG [cmd-proc-thread-1] [c.z.h.p.PoolBase] PDBWritePool - Reset (nothing) on connection org.postgresql.jdbc.PgConnection@78925e78
2017-02-23 13:10:01,493 INFO [cmd-proc-thread-1] [p.p.command] [3-1487884201474] 'store report' puppet v4.9.2 command processed for my-agent
2017-02-23 13:10:01,521 INFO [nREPL-worker-7] [p.t.internal] Beginning shutdown sequence
2017-02-23 13:10:01,521 DEBUG [async-dispatch-2] [p.t.internal] Received shutdown command, shutting down services
2017-02-23 13:10:01,521 DEBUG [async-dispatch-2] [p.t.internal] Clearing lifecycle worker channels for shutdown.
2017-02-23 13:10:01,522 DEBUG [async-dispatch-2] [p.s.j.jruby-core] Beginning flush of JRuby pools for shutdown
2017-02-23 13:10:01,522 INFO [clojure-agent-send-pool-8] [p.s.j.i.jruby-agents] Flush request received; creating new JRuby pool.
2017-02-23 13:10:01,523 INFO [clojure-agent-send-pool-8] [p.s.j.i.jruby-agents] Replacing old JRuby pool with new instance.
2017-02-23 13:10:01,523 INFO [clojure-agent-send-pool-8] [p.s.j.i.jruby-agents] Swapped JRuby pools, beginning cleanup of old pool.
2017-02-23 13:10:01,525 INFO [clojure-agent-send-pool-8] [p.s.j.i.jruby-internal] Cleaned up old JRubyInstance with id 1.
2017-02-23 13:10:01,525 DEBUG [async-dispatch-2] [p.s.j.jruby-core] Finished flush of JRuby pools for shutdown
2017-02-23 13:10:01,526 INFO [async-dispatch-2] [p.t.s.w.jetty9-service] Shutting down web server(s).
2017-02-23 13:10:01,526 DEBUG [async-dispatch-2] [p.t.s.w.jetty9-core] Cleaning up JMX MBean container
2017-02-23 13:10:01,526 INFO [async-dispatch-2] [p.t.s.w.jetty9-core] Shutting down web server.
2017-02-23 13:10:01,527 INFO [async-dispatch-2] [o.e.j.s.ServerConnector] Stopped ServerConnector@1b3889fc{SSL-HTTP/1.1}{0.0.0.0:8140}
2017-02-23 13:10:01,527 INFO [async-dispatch-2] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler@3282ca8d{/status,null,UNAVAILABLE}
2017-02-23 13:10:01,527 INFO [async-dispatch-2] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler@5726fd59{/,null,UNAVAILABLE}
2017-02-23 13:10:01,528 INFO [async-dispatch-2] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler@1846d3c9{/puppet,null,UNAVAILABLE}
2017-02-23 13:10:01,528 INFO [async-dispatch-2] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler@7943c53{/puppet-admin-api,null,UNAVAILABLE}
2017-02-23 13:10:01,528 INFO [async-dispatch-2] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler@1c046fc2{/puppet-ca,null,UNAVAILABLE}
2017-02-23 13:10:01,528 INFO [async-dispatch-2] [p.t.s.w.jetty9-core] Web server shutdown
2017-02-23 13:10:01,528 DEBUG [async-dispatch-2] [p.t.s.s.scheduler-service] Shutting down Scheduler Service
2017-02-23 13:10:01,528 DEBUG [async-dispatch-2] [p.t.s.s.scheduler-service] Scheduler Service shutdown complete.
2017-02-23 13:10:01,529 DEBUG [async-dispatch-2] [p.t.internal] Service shutdown complete, exiting lifecycle worker loop
2017-02-23 13:10:01,529 INFO [nREPL-worker-7] [p.t.internal] Finished shutdown sequence
2017-02-23 13:10:01,529 INFO [nREPL-worker-7] [p.t.internal] Beginning shutdown sequence
2017-02-23 13:10:01,529 DEBUG [async-dispatch-1] [p.t.internal] Received shutdown command, shutting down services
2017-02-23 13:10:01,529 DEBUG [async-dispatch-1] [p.t.internal] Clearing lifecycle worker channels for shutdown.
2017-02-23 13:10:01,530 INFO [async-dispatch-1] [p.p.c.services] Shutdown request received; puppetdb exiting.
2017-02-23 13:10:01,530 INFO [async-dispatch-1] [c.z.h.p.HikariPool] PDBWritePool - is closing down.
2017-02-23 13:10:01,531 DEBUG [async-dispatch-1] [c.z.h.p.HikariPool] Before closing pool PDBWritePool stats (total=25, active=0, idle=25, waiting=0)
2017-02-23 13:10:01,531 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@435dd69c: (connection evicted by user)
2017-02-23 13:10:01,531 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@78925e78: (connection evicted by user)
2017-02-23 13:10:01,531 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@6159893c: (connection evicted by user)
2017-02-23 13:10:01,531 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@7641ff7f: (connection evicted by user)
2017-02-23 13:10:01,531 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@83778d0: (connection evicted by user)
2017-02-23 13:10:01,531 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@78860fc7: (connection evicted by user)
2017-02-23 13:10:01,531 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@28550fcc: (connection evicted by user)
2017-02-23 13:10:01,531 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@7cb9940f: (connection evicted by user)
2017-02-23 13:10:01,532 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@511d56c: (connection evicted by user)
2017-02-23 13:10:01,533 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@438a6b1: (connection evicted by user)
2017-02-23 13:10:01,534 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@732ee: (connection evicted by user)
2017-02-23 13:10:01,534 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@39bb05fc: (connection evicted by user)
2017-02-23 13:10:01,534 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@4505ac8a: (connection evicted by user)
2017-02-23 13:10:01,535 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@4078afb3: (connection evicted by user)
2017-02-23 13:10:01,535 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@748a0b3a: (connection evicted by user)
2017-02-23 13:10:01,535 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@848784c: (connection evicted by user)
2017-02-23 13:10:01,535 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@252a53c5: (connection evicted by user)
2017-02-23 13:10:01,535 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@38618ca6: (connection evicted by user)
2017-02-23 13:10:01,535 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@56f2b69: (connection evicted by user)
2017-02-23 13:10:01,535 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@aff599e: (connection evicted by user)
2017-02-23 13:10:01,535 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@21640a46: (connection evicted by user)
2017-02-23 13:10:01,537 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@35c62e1d: (connection evicted by user)
2017-02-23 13:10:01,538 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@4da3d707: (connection evicted by user)
2017-02-23 13:10:01,539 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@219827bb: (connection evicted by user)
2017-02-23 13:10:01,539 DEBUG [Hikari connection closer (pool PDBWritePool)] [c.z.h.p.PoolBase] PDBWritePool - Closing connection org.postgresql.jdbc.PgConnection@56a7df2c: (connection evicted by user)
2017-02-23 13:10:01,540 DEBUG [async-dispatch-1] [c.z.h.p.HikariPool] After closing pool PDBWritePool stats (total=0, active=0, idle=0, waiting=0)
2017-02-23 13:10:01,540 INFO [async-dispatch-1] [c.z.h.p.HikariPool] PDBReadPool - is closing down.
2017-02-23 13:10:01,540 DEBUG [async-dispatch-1] [c.z.h.p.HikariPool] Before closing pool PDBReadPool stats (total=25, active=0, idle=25, waiting=0)
2017-02-23 13:10:01,540 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@1beeb0a6: (connection evicted by user)
2017-02-23 13:10:01,540 DEBUG [Hikari connection closer (pool PDBReadPool)] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@439f9eb8: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@1b45e778: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@10f4ebc9: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@5df1c528: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@337425b0: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@73e6a81: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@3023730c: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@38522fc0: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@3695c6ba: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@32aa44b0: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@77a8cc81: (connection evicted by user)
2017-02-23 13:10:01,541 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@5eadc19b: (connection evicted by user)
2017-02-23 13:10:01,542 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@46957ce2: (connection evicted by user)
2017-02-23 13:10:01,542 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@267a2f44: (connection evicted by user)
2017-02-23 13:10:01,542 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@178733ce: (connection evicted by user)
2017-02-23 13:10:01,543 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@271699bc: (connection evicted by user)
2017-02-23 13:10:01,543 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@4a0993d0: (connection evicted by user)
2017-02-23 13:10:01,543 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@3d7e85da: (connection evicted by user)
2017-02-23 13:10:01,544 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@13c9c8f8: (connection evicted by user)
2017-02-23 13:10:01,545 DEBUG [async-dispatch-1] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@16844: (connection evicted by user)
2017-02-23 13:10:01,545 DEBUG [Hikari connection closer (pool PDBReadPool)] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@52b759a6: (connection evicted by user)
2017-02-23 13:10:01,546 DEBUG [Hikari connection closer (pool PDBReadPool)] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@4a34da22: (connection evicted by user)
2017-02-23 13:10:01,548 DEBUG [Hikari connection closer (pool PDBReadPool)] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@44f32d14: (connection evicted by user)
2017-02-23 13:10:01,548 DEBUG [Hikari connection closer (pool PDBReadPool)] [c.z.h.p.PoolBase] PDBReadPool - Closing connection org.postgresql.jdbc.PgConnection@40ccb152: (connection evicted by user)
2017-02-23 13:10:01,548 DEBUG [async-dispatch-1] [c.z.h.p.HikariPool] After closing pool PDBReadPool stats (total=0, active=0, idle=0, waiting=0)
2017-02-23 13:10:01,548 INFO [async-dispatch-1] [p.t.s.w.jetty9-service] Shutting down web server(s).
2017-02-23 13:10:01,549 DEBUG [async-dispatch-1] [p.t.s.w.jetty9-core] Cleaning up JMX MBean container
2017-02-23 13:10:01,549 INFO [async-dispatch-1] [p.t.s.w.jetty9-core] Shutting down web server.
2017-02-23 13:10:01,551 INFO [async-dispatch-1] [o.e.j.s.ServerConnector] Stopped ServerConnector@f8a6274{HTTP/1.1}{0.0.0.0:0}
2017-02-23 13:10:01,551 INFO [async-dispatch-1] [o.e.j.s.ServerConnector] Stopped ServerConnector@615d6638{SSL-HTTP/1.1}{0.0.0.0:33295}
2017-02-23 13:10:01,551 INFO [async-dispatch-1] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler@268257aa{/,null,UNAVAILABLE}
2017-02-23 13:10:01,551 INFO [async-dispatch-1] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler@4a545b57{/status,null,UNAVAILABLE}
2017-02-23 13:10:01,552 INFO [async-dispatch-1] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.ServletContextHandler@331adda7{/metrics/v2,null,UNAVAILABLE}
2017-02-23 13:10:01,552 INFO [async-dispatch-1] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler@6cc3ed3b{/metrics,null,UNAVAILABLE}
2017-02-23 13:10:01,552 INFO [async-dispatch-1] [o.e.j.s.h.ContextHandler] Stopped o.e.j.s.h.ContextHandler@44063950{/pdb,null,UNAVAILABLE}
2017-02-23 13:10:01,552 INFO [async-dispatch-1] [p.t.s.w.jetty9-core] Web server shutdown
2017-02-23 13:10:01,553 DEBUG [async-dispatch-1] [p.t.s.s.scheduler-service] Shutting down Scheduler Service
2017-02-23 13:10:01,553 DEBUG [async-dispatch-1] [p.t.s.s.scheduler-service] Scheduler Service shutdown complete.
2017-02-23 13:10:01,553 DEBUG [async-dispatch-1] [p.t.internal] Service shutdown complete, exiting lifecycle worker loop
2017-02-23 13:10:01,553 INFO [nREPL-worker-7] [p.t.internal] Finished shutdown sequence
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment