[chef] Re: Re: Templates sends notification even when nothing changes

[Arthur Kalmenson < >]

Jesse,

$ chef-client -v

Chef: 0.10.8

Daniel,

It looks like you're right. Sorry, the full logs are below. What do I do about those chmod and chown changes? Those files are there and there's no reason to continue changing the ownership, no?

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing group[hubot] action create (hubot::default line 20)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing user[hubot] action create (hubot::default line 23)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing directory[/usr/local/hubot] action create (hubot::default line 30)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing package[libexpat1] action install (hubot::default line 39)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing package[libexpat1-dev] action install (hubot::default line 39)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing remote_file[/tmp/hubot-2.1.3.tar.gz] action create_if_missing (hubot::default line 45)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing bash[install hubot] action run (hubot::default line 52)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: bash[install hubot] sh("bash"  "/tmp/chef-script20120221-11973-mu2hna-0")

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: bash[install hubot] ran successfully

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing template[/usr/local/hubot/package.json] action create (hubot::default line 66)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/package.json] backed up to /var/lib/chef/backup/usr/local/hubot/package.json.chef-20120221130628

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/package.json] removed backup at /var/lib/chef/backup/./usr/local/hubot/package.json.chef-20120221103932

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/package.json] owner changed to 1001

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/package.json] group changed to 1001

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/package.json] mode changed to 644

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/package.json] updated content

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing template[/usr/local/hubot/hubot-scripts.json] action create (hubot::default line 74)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/hubot-scripts.json] backed up to /var/lib/chef/backup/usr/local/hubot/hubot-scripts.json.chef-20120221130628

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/hubot-scripts.json] removed backup at /var/lib/chef/backup/./usr/local/hubot/hubot-scripts.json.chef-20120221103932

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/hubot-scripts.json] owner changed to 1001

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/hubot-scripts.json] group changed to 1001

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/hubot-scripts.json] mode changed to 644

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/hubot-scripts.json] updated content

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/hubot-scripts.json] not queuing delayed action restart on service[hubot] (delayed), as it's already been queued

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing template[/usr/local/hubot/bin/hubot-wrapper] action create (hubot::default line 82)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing template[/etc/init.d/hubot] action create (hubot::default line 93)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing service[hubot] action enable (hubot::default line 100)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing service[hubot] action start (hubot::default line 100)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing package[redis-server] action install (redis::package line 21)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing service[redis] action start (redis::package line 23)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing template[/etc/redis/redis.conf] action create (redis::package line 30)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: template[/usr/local/hubot/package.json] sending restart action to service[hubot] (delayed)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Processing service[hubot] action restart (hubot::default line 100)

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: service[hubot] restarted

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Chef Run complete in 1.55091 seconds

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Running report handlers

[Tue, 21 Feb 2012 13:06:28 -0500] INFO: Report handlers complete

--
Arthur Kalmenson

On Tue, Feb 21, 2012 at 11:46 AM, Daniel DeLeo < "> > wrote:

On Tuesday, February 21, 2012 at 7:33 AM, Arthur Kalmenson wrote:

> Hello everyone,
>
> I have a template resource that notifies the service to restart. It looks as follows:
>
> # apply configurations
> template "#{install_dir}/package.json" do
> source "package.json.erb"
> owner hubot_user
> group hubot_group
> mode "0644"
> notifies :restart, "service[hubot]"
> end
>
> template "#{install_dir}/hubot-scripts.json" do
> source "hubot-scripts.json.erb"
> owner hubot_user
> group hubot_group
> mode "0644"
> notifies :restart, "service[hubot]"
> end
>
> It's my understanding that the notification will only get called when the template renders differently. However, even when nothing has changed, every time chef-client runs, the restart happens.
>
> ...
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Processing template[/etc/init.d/hubot] action create (hubot::default line 93)
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Processing service[hubot] action enable (hubot::default line 100)
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Processing service[hubot] action start (hubot::default line 100)
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Processing package[redis-server] action install (redis::package line 21)
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Processing service[redis] action start (redis::package line 23)
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Processing template[/etc/redis/redis.conf] action create (redis::package line 30)
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: template[/usr/local/hubot/package.json] sending restart action to service[hubot] (delayed)
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Processing service[hubot] action restart (hubot::default line 100)
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: service[hubot] restarted
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Chef Run complete in 2.929228 seconds
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Running report handlers
> [Tue, 21 Feb 2012 10:25:53 -0500] INFO: Report handlers complete
>
> Does anyone know why this is happening? Thank you in advance.

The log message indicates that `template[/usr/local/hubot/package.json]` is triggering the restart, but you've elided the log messages from that one so we can't see what's going on.

Someone had a similar issue which was caused by a separate process chmod-ing the file.

>
> All the best,
> --
> Arthur Kalmenson

--
Dan DeLeo