[Pkg-puppet-devel] Bug#573020: Bug#573020: exec don't work

Thu Mar 11 13:08:39 UTC 2010

El lun, 08-03-2010 a las 08:44 -0800, Nigel Kersten escribió: 
> 2010/3/8 José L. Redrejo <jredrejo at edu.juntaextremadura.net>:
> > Package: puppet
> > Version: 0.25.4-2
> > Severity: important
> >
> > whenever a class file including exec is executed at the client, the
> > puppet client freezes.
> 
> Can you provide a --debug --output --trace log with a simple local
> manifest that only contains one exec please José?

Hello Nigel, sorry for the delay, but I've prefered to do more checks
with a colleague before answering.

I'm attaching you a list of a very simple site.pp used, and the log
outputs when using (and not using) the --debug option in the command
line.

For more fun, the bug doesn't appear everytime you executed puppetd, it
only happens randomly, and it happens much more often if the debug
option is not used.

Anyway, I think you can reproduce it easily using a fresh squeeze
installation at the client and server.

I'm afraid the these symptoms are mostly as those described at
https://bugs.launchpad.net/ubuntu/+source/eglibc/+bug/520715

Regards
José L.
-------------- next part --------------
root at puesto1:~# puppetd -t 
info: Caching catalog for puesto1.unex.es
info: Applying configuration version '1268302012'
Pilar yerr: //Node[default]/Exec[/usr/bin/touch /tmp/prueba2]/returns: 
change from notrun to 0 failed: Command exceeded timeout at 
/etc/puppet/manifests/site.pp:35
warning: Value of 'preferred_serialization_format' (pson) is invalid for 
report, using default (marshal)
notice: Finished catalog run in 300.48 seconds

-------------- next part --------------
class test_class {
        file {"/tmp/fichero.test":
                ensure  => present,
                mode    => 744,
                owner   => root,
                group   => root
        }
}

node default {
      include test_class
      exec { "/usr/bin/touch /tmp/prueba2":
	}

}

-------------- next part --------------
Thu Mar 11 13:42:14 +0100 2010 Puppet (debug): Failed to load library 'selinux' for feature 'selinux'
Thu Mar 11 13:42:14 +0100 2010 Puppet (debug): Puppet::Type::User::ProviderPw: file pw does not exist
Thu Mar 11 13:42:14 +0100 2010 Puppet (debug): Puppet::Type::User::ProviderDirectoryservice: file /usr/bin/dscl does not exist
Thu Mar 11 13:42:14 +0100 2010 Puppet (debug): Puppet::Type::User::ProviderUser_role_add: file roleadd does not exist
Thu Mar 11 13:42:14 +0100 2010 Puppet (debug): Puppet::Type::User::ProviderLdap: true value when expecting false
Thu Mar 11 13:42:15 +0100 2010 Puppet (debug): Failed to load library 'ldap' for feature 'ldap'
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/private] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/certs/ca.pem] (debug): Autorequiring File[/var/lib/puppet/ssl/certs]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/certs] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/facts] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/private_keys] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:42:15 +0100 2010 /File[/etc/puppet/puppet.conf] (debug): Autorequiring File[/etc/puppet]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/state/classes.txt] (debug): Autorequiring File[/var/lib/puppet/state]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/certs/puesto1.unex.es.pem] (debug): Autorequiring File[/var/lib/puppet/ssl/certs]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/lib] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/run/puppet/puppetd.pid] (debug): Autorequiring File[/var/run/puppet]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/clientbucket] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/crl.pem] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/public_keys/puesto1.unex.es.pem] (debug): Autorequiring File[/var/lib/puppet/ssl/public_keys]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/private_keys/puesto1.unex.es.pem] (debug): Autorequiring File[/var/lib/puppet/ssl/private_keys]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/certificate_requests] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/state] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/state/graphs] (debug): Autorequiring File[/var/lib/puppet/state]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/client_yaml] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/ssl/public_keys] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:42:15 +0100 2010 /File[/var/lib/puppet/state/state.yaml] (debug): Autorequiring File[/var/lib/puppet/state]
Thu Mar 11 13:42:15 +0100 2010 Puppet (debug): Finishing transaction -613930118 with 0 changes
Thu Mar 11 13:42:15 +0100 2010 Puppet (debug): Using cached certificate for ca, good until Sun Mar 08 09:42:29 UTC 2015
Thu Mar 11 13:42:15 +0100 2010 Puppet (debug): Using cached certificate for puesto1.unex.es, good until Sun Mar 08 09:46:25 UTC 2015
Thu Mar 11 13:42:15 +0100 2010 Puppet (debug): Loaded state in 0.00 seconds
Thu Mar 11 13:42:15 +0100 2010 Puppet (debug): Using cached certificate for ca, good until Sun Mar 08 09:42:29 UTC 2015
Thu Mar 11 13:42:15 +0100 2010 Puppet (debug): Using cached certificate for puesto1.unex.es, good until Sun Mar 08 09:46:25 UTC 2015
Thu Mar 11 13:42:15 +0100 2010 Puppet (debug): Using cached certificate_revocation_list for ca, good until 
Thu Mar 11 13:42:15 +0100 2010 Puppet (debug): catalog supports formats: b64_zlib_yaml marshal pson raw yaml; using pson
Thu Mar 11 13:42:15 +0100 2010 Puppet (info): Caching catalog for puesto1.unex.es
Thu Mar 11 13:42:16 +0100 2010 Puppet (debug): Creating default schedules
Thu Mar 11 13:42:16 +0100 2010 Puppet (debug): Finishing transaction -613717308 with 0 changes
Thu Mar 11 13:42:16 +0100 2010 Puppet (debug): Loaded state in 0.00 seconds
Thu Mar 11 13:42:16 +0100 2010 Puppet (info): Applying configuration version '1268302012'
Thu Mar 11 13:42:16 +0100 2010 //Node[default]/Exec[/usr/bin/touch /tmp/prueba2] (debug): Changing returns
Thu Mar 11 13:42:16 +0100 2010 //Node[default]/Exec[/usr/bin/touch /tmp/prueba2] (debug): 1 change(s)
Thu Mar 11 13:42:16 +0100 2010 //Node[default]/Exec[/usr/bin/touch /tmp/prueba2] (debug): Executing '/usr/bin/touch /tmp/prueba2'
Thu Mar 11 13:42:16 +0100 2010 Puppet (debug): Executing '/usr/bin/touch /tmp/prueba2'
Thu Mar 11 13:42:16 +0100 2010 //Node[default]/Exec[/usr/bin/touch /tmp/prueba2]/returns (notice): executed successfully
Thu Mar 11 13:42:16 +0100 2010 Puppet (debug): Finishing transaction -613745938 with 1 changes
Thu Mar 11 13:42:16 +0100 2010 Puppet (debug): Storing state
Thu Mar 11 13:42:16 +0100 2010 Puppet (debug): Stored state in 0.04 seconds
Thu Mar 11 13:42:16 +0100 2010 Puppet (warning): Value of 'preferred_serialization_format' (pson) is invalid for report, using default (marshal)
Thu Mar 11 13:42:16 +0100 2010 Puppet (debug): report supports formats: b64_zlib_yaml marshal raw yaml; using marshal
Thu Mar 11 13:42:16 +0100 2010 Puppet (notice): Finished catalog run in 0.48 seconds
Thu Mar 11 13:44:02 +0100 2010 Puppet (debug): Failed to load library 'selinux' for feature 'selinux'
Thu Mar 11 13:44:02 +0100 2010 Puppet (debug): Puppet::Type::User::ProviderPw: file pw does not exist
Thu Mar 11 13:44:02 +0100 2010 Puppet (debug): Puppet::Type::User::ProviderDirectoryservice: file /usr/bin/dscl does not exist
Thu Mar 11 13:44:02 +0100 2010 Puppet (debug): Puppet::Type::User::ProviderUser_role_add: file roleadd does not exist
Thu Mar 11 13:44:02 +0100 2010 Puppet (debug): Puppet::Type::User::ProviderLdap: true value when expecting false
Thu Mar 11 13:44:02 +0100 2010 Puppet (debug): Failed to load library 'ldap' for feature 'ldap'
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/facts] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/public_keys] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/public_keys/puesto1.unex.es.pem] (debug): Autorequiring File[/var/lib/puppet/ssl/public_keys]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/certificate_requests] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/private_keys/puesto1.unex.es.pem] (debug): Autorequiring File[/var/lib/puppet/ssl/private_keys]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/state] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/certs/puesto1.unex.es.pem] (debug): Autorequiring File[/var/lib/puppet/ssl/certs]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/state/state.yaml] (debug): Autorequiring File[/var/lib/puppet/state]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/run/puppet/puppetd.pid] (debug): Autorequiring File[/var/run/puppet]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/private_keys] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/clientbucket] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/state/graphs] (debug): Autorequiring File[/var/lib/puppet/state]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/private] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/certs] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/state/classes.txt] (debug): Autorequiring File[/var/lib/puppet/state]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/crl.pem] (debug): Autorequiring File[/var/lib/puppet/ssl]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl/certs/ca.pem] (debug): Autorequiring File[/var/lib/puppet/ssl/certs]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/lib] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/ssl] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:44:02 +0100 2010 /File[/var/lib/puppet/client_yaml] (debug): Autorequiring File[/var/lib/puppet]
Thu Mar 11 13:44:02 +0100 2010 /File[/etc/puppet/puppet.conf] (debug): Autorequiring File[/etc/puppet]
Thu Mar 11 13:44:03 +0100 2010 Puppet (debug): Finishing transaction -613432538 with 0 changes
Thu Mar 11 13:44:03 +0100 2010 Puppet (debug): Using cached certificate for ca, good until Sun Mar 08 09:42:29 UTC 2015
Thu Mar 11 13:44:03 +0100 2010 Puppet (debug): Using cached certificate for puesto1.unex.es, good until Sun Mar 08 09:46:25 UTC 2015
Thu Mar 11 13:44:03 +0100 2010 Puppet (debug): Loaded state in 0.00 seconds
Thu Mar 11 13:44:03 +0100 2010 Puppet (debug): Using cached certificate for ca, good until Sun Mar 08 09:42:29 UTC 2015
Thu Mar 11 13:44:03 +0100 2010 Puppet (debug): Using cached certificate for puesto1.unex.es, good until Sun Mar 08 09:46:25 UTC 2015
Thu Mar 11 13:44:03 +0100 2010 Puppet (debug): Using cached certificate_revocation_list for ca, good until 
Thu Mar 11 13:44:03 +0100 2010 Puppet (debug): catalog supports formats: b64_zlib_yaml marshal pson raw yaml; using pson
Thu Mar 11 13:44:03 +0100 2010 Puppet (info): Caching catalog for puesto1.unex.es
Thu Mar 11 13:44:03 +0100 2010 Puppet (debug): Creating default schedules
Thu Mar 11 13:44:04 +0100 2010 Puppet (debug): Finishing transaction -613219398 with 0 changes
Thu Mar 11 13:44:04 +0100 2010 Puppet (debug): Loaded state in 0.01 seconds
Thu Mar 11 13:44:04 +0100 2010 Puppet (info): Applying configuration version '1268302012'
Thu Mar 11 13:44:04 +0100 2010 //Node[default]/Exec[/usr/bin/touch /tmp/prueba2] (debug): Changing returns
Thu Mar 11 13:44:04 +0100 2010 //Node[default]/Exec[/usr/bin/touch /tmp/prueba2] (debug): 1 change(s)
Thu Mar 11 13:44:04 +0100 2010 //Node[default]/Exec[/usr/bin/touch /tmp/prueba2] (debug): Executing '/usr/bin/touch /tmp/prueba2'
Thu Mar 11 13:44:04 +0100 2010 Puppet (debug): Executing '/usr/bin/touch /tmp/prueba2'
Thu Mar 11 13:49:04 +0100 2010 //Node[default]/Exec[/usr/bin/touch /tmp/prueba2]/returns (err): change from notrun to 0 failed: Command exceeded timeout at /etc/puppet/manifests/site.pp:35
Thu Mar 11 13:49:04 +0100 2010 Puppet (debug): Finishing transaction -613247958 with 1 changes
Thu Mar 11 13:49:04 +0100 2010 Puppet (debug): Storing state
Thu Mar 11 13:49:04 +0100 2010 Puppet (debug): Stored state in 0.06 seconds
Thu Mar 11 13:49:04 +0100 2010 Puppet (warning): Value of 'preferred_serialization_format' (pson) is invalid for report, using default (marshal)
Thu Mar 11 13:49:04 +0100 2010 Puppet (debug): report supports formats: b64_zlib_yaml marshal raw yaml; using marshal
Thu Mar 11 13:49:04 +0100 2010 Puppet (notice): Finished catalog run in 300.27 seconds
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 197 bytes
Desc: Esta parte del mensaje est? firmada	digitalmente
URL: <http://lists.alioth.debian.org/pipermail/pkg-puppet-devel/attachments/20100311/8c87394f/attachment-0001.pgp>