Issue #14377 has been updated by Steven Seed.
This is the log file /var/log/puppet/puppetmasterd.log which is written to by the passenger server. It seems to be happening regardless of the system load, but it's hard to be sure. The /var/log/httpd/access log only appears to have on PUT request for each puppet run: PUT /production/report/longhair.fas.fa.disney.com # passenger-config --version 3.0.9 # ruby --version ruby 1.8.7 (2010-06-23 patchlevel 299) [x86_64-linux] ---------------------------------------- Bug #14377: Puppetmaster is logging Compiled catalog lines multiple times for certain agent run https://projects.puppetlabs.com/issues/14377#change-63103 Author: Steven Seed Status: Needs More Information Priority: Normal Assignee: Steven Seed Category: Target version: Affected Puppet version: 2.7.5 Keywords: Branch: I'm trying to track down a high load/concurrency issue with my puppetmasters and I noticed that I've very often seeing multiple messages in the puppetmasterd.log file for the same agent run. Some times there are 10 or more of these messages with the same time stamp and run time scattered through out the log. I'm not sure if this is contributing to the overall load, but it does make parsing the logs difficult. Here is an example of one host parsed out of my puppetmasterd.log file: Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:02:06 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.19 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 04:32:57 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 5.36 seconds Wed May 09 09:55:44 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 1.59 seconds Wed May 09 10:59:16 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 7.38 seconds Wed May 09 11:08:24 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:08:24 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:08:24 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:08:24 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:39:03 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:39:03 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:39:03 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:39:03 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:39:03 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:39:03 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:39:03 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:39:03 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds Wed May 09 11:39:03 -0700 2012 Puppet (notice): Compiled catalog for longhair.fas.fa.disney.com in environment production in 2.08 seconds On the agent itself, there is only one message: Wed May 09 11:08:57 -0700 2012 Puppet (notice): Finished catalog run in 27.07 seconds Wed May 09 11:39:35 -0700 2012 Puppet (notice): Finished catalog run in 27.37 seconds -- You have received this notification because you have either subscribed to it, or are involved in it. To change your notification preferences, please click here: http://projects.puppetlabs.com/my/account -- You received this message because you are subscribed to the Google Groups "Puppet Bugs" group. To post to this group, send email to [email protected]. To unsubscribe from this group, send email to [email protected]. For more options, visit this group at http://groups.google.com/group/puppet-bugs?hl=en.
