CHEF-3010 outputs

incorrect permissions, deploy resource debug section

[Thu, 29 Mar 2012 14:46:01 +0000] DEBUG: Processing deploy[/srv/myapp] on localhost
[Thu, 29 Mar 2012 14:46:01 +0000] INFO: Processing deploy[/srv/myapp] action deploy (deployrepro::default line 57)
[Thu, 29 Mar 2012 14:46:01 +0000] INFO: deploy[/srv/myapp] set user to appdeploy
[Thu, 29 Mar 2012 14:46:01 +0000] INFO: deploy[/srv/myapp] set group to apache
[Thu, 29 Mar 2012 14:46:01 +0000] DEBUG: deploy[/srv/myapp] not creating /srv/myapp because it already exists
[Thu, 29 Mar 2012 14:46:01 +0000] DEBUG: deploy[/srv/myapp] not creating /srv/myapp/shared because it already exists
[Thu, 29 Mar 2012 14:46:01 +0000] DEBUG: deploy[/srv/myapp] finding current git revision
[Thu, 29 Mar 2012 14:46:01 +0000] DEBUG: sh(git rev-parse HEAD)
[Thu, 29 Mar 2012 14:46:01 +0000] DEBUG: deploy[/srv/myapp] finding current git revision
[Thu, 29 Mar 2012 14:46:01 +0000] DEBUG: sh(git rev-parse HEAD)
[Thu, 29 Mar 2012 14:46:01 +0000] DEBUG: deploy[/srv/myapp] resolving remote reference
[Thu, 29 Mar 2012 14:46:01 +0000] DEBUG: deploy[/srv/myapp] sh(git ls-remote git@github.com:Mycorp/privaterepo.git HEAD)
[Thu, 29 Mar 2012 14:46:11 +0000] DEBUG: deploy[/srv/myapp] current revision: <long hash> target revision: <long hash>
[Thu, 29 Mar 2012 14:46:11 +0000] DEBUG: Executing cp -RPp /srv/myapp/shared/cached-copy/. /srv/myapp/releases/20120329144601
[Thu, 29 Mar 2012 14:46:11 +0000] DEBUG: ---- Begin output of cp -RPp /srv/myapp/shared/cached-copy/. /srv/myapp/releases/20120329144601 ----
[Thu, 29 Mar 2012 14:46:11 +0000] DEBUG: STDOUT: 
[Thu, 29 Mar 2012 14:46:11 +0000] DEBUG: STDERR: 
[Thu, 29 Mar 2012 14:46:11 +0000] DEBUG: ---- End output of cp -RPp /srv/myapp/shared/cached-copy/. /srv/myapp/releases/20120329144601 ----
[Thu, 29 Mar 2012 14:46:11 +0000] DEBUG: Ran cp -RPp /srv/myapp/shared/cached-copy/. /srv/myapp/releases/20120329144601 returned 0
[Thu, 29 Mar 2012 14:46:11 +0000] INFO: deploy[/srv/myapp] copied the cached checkout to /srv/myapp/releases/20120329144601
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] set user to appdeploy
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] set group to apache
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] made pre-migration symlinks
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] purged directories in checkout log, tmp/pids, public/system
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: deploy[/srv/myapp] created directory /srv/myapp/releases/20120329144601/tmp
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: deploy[/srv/myapp] set user to appdeploy for /srv/myapp/releases/20120329144601/tmp
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: deploy[/srv/myapp] set group to apache for /srv/myapp/releases/20120329144601/tmp
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: deploy[/srv/myapp] not creating /srv/myapp/releases/20120329144601/public because it already exists
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: deploy[/srv/myapp] created directory /srv/myapp/releases/20120329144601/config
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: deploy[/srv/myapp] set user to appdeploy for /srv/myapp/releases/20120329144601/config
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: deploy[/srv/myapp] set group to apache for /srv/myapp/releases/20120329144601/config
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] created directories before symlinking tmp,public,config
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] linked shared paths into current release: log => log, pids => tmp/pids, system => public/system
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] made pre-migration symlinks
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] set user to appdeploy
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] set group to apache
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] linked release /srv/myapp/releases/20120329144601 into production at /srv/myapp/current
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] set user to appdeploy
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] set group to apache
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] updated symlinks
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] restarting app
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: Executing touch tmp/restart.txt
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: ---- Begin output of touch tmp/restart.txt ----
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: STDOUT: 
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: STDERR: 
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: ---- End output of touch tmp/restart.txt ----
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: Ran touch tmp/restart.txt returned 0
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: deploy[/srv/myapp] deployed to /srv/myapp
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: Chef Run complete in 14.26085 seconds
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: Cleaning the checksum cache
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: Removing unused checksum cache file /var/chef/cache/checksums/chef-file--tmp-chef-rendered-template20120329-3591-1wvtzx0-0
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: Removing unused checksum cache file /var/chef/cache/checksums/chef-file--tmp-chef-rendered-template20120329-3804-xbtlx1-0
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: Removing unused checksum cache file /var/chef/cache/checksums/chef-file--tmp-chef-rendered-template20120329-3804-m6u4jm-0
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: Removing unused checksum cache file /var/chef/cache/checksums/chef-file--tmp-chef-rendered-template20120329-3591-f3cuwb-0
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: Running report handlers
[Thu, 29 Mar 2012 14:46:12 +0000] INFO: Report handlers complete
[Thu, 29 Mar 2012 14:46:12 +0000] DEBUG: Exiting

incorrect permissions, deploy_revision resource debug section

...
[Thu, 29 Mar 2012 14:38:34 +0000] DEBUG: Processing deploy_revision[/srv/myapp] on localhost
[Thu, 29 Mar 2012 14:38:34 +0000] INFO: Processing deploy_revision[/srv/myapp] action deploy (deployrepro::default line 57)
[Thu, 29 Mar 2012 14:38:34 +0000] DEBUG: deploy_revision[/srv/myapp] resolving remote reference
[Thu, 29 Mar 2012 14:38:34 +0000] DEBUG: deploy_revision[/srv/myapp] sh(git ls-remote git@github.com:Mycorp/privaterepo.git HEAD)
[Thu, 29 Mar 2012 14:38:39 +0000] ERROR: deploy_revision[/srv/myapp] (deployrepro::default line 57) has had an error
[Thu, 29 Mar 2012 14:38:39 +0000] ERROR: deploy_revision[/srv/myapp] (/tmp/vagrant-chef-1/chef-solo-1/cookbooks/deployrepro/recipes/default.rb:57:in `from_file') had an error:
deploy_revision[/srv/myapp] (deployrepro::default line 57) had an error: Chef::Exceptions::ShellCommandFailed: Expected process to exit with [0], but received '128'
---- Begin output of git ls-remote git@github.com:Mycorp/privaterepo.git HEAD ----
STDOUT: 
STDERR: Permission denied (publickey).
fatal: The remote end hung up unexpectedly
---- End output of git ls-remote git@github.com:Mycorp/privaterepo.git HEAD ----
Ran git ls-remote git@github.com:Mycorp/privaterepo.git HEAD returned 128
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/shell_out.rb:206:in `invalid!'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/shell_out.rb:192:in `error!'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/shell_out.rb:36:in `shell_out!'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/provider/git.rb:189:in `remote_resolve_reference'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/provider/git.rb:178:in `revision_slug'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/provider/deploy/revision.rb:46:in `release_slug'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/provider/deploy.rb:45:in `load_current_resource'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/resource.rb:439:in `run_action'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/runner.rb:45:in `run_action'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/runner.rb:81:in `converge'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/runner.rb:81:in `each'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/runner.rb:81:in `converge'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/resource_collection.rb:94:in `execute_each_resource'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/resource_collection/stepable_iterator.rb:116:in `call'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/resource_collection/stepable_iterator.rb:116:in `call_iterator_block'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/resource_collection/stepable_iterator.rb:85:in `step'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/resource_collection/stepable_iterator.rb:104:in `iterate'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/resource_collection/stepable_iterator.rb:55:in `each_with_index'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/resource_collection.rb:92:in `execute_each_resource'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/runner.rb:76:in `converge'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/client.rb:312:in `converge'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/client.rb:160:in `run'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application/solo.rb:192:in `run_application'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application/solo.rb:183:in `loop'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application/solo.rb:183:in `run_application'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application.rb:67:in `run'
/usr/lib/ruby/gems/1.8/gems/chef-0.10.8/bin/chef-solo:25
/usr/bin/chef-solo:19:in `load'
/usr/bin/chef-solo:19
[Thu, 29 Mar 2012 14:38:39 +0000] ERROR: Running exception handlers
[Thu, 29 Mar 2012 14:38:39 +0000] ERROR: Exception handlers complete
[Thu, 29 Mar 2012 14:38:39 +0000] DEBUG: Re-raising exception: Chef::Exceptions::ShellCommandFailed - deploy_revision[/srv/myapp] (deployrepro::default line 57) had an error: Chef::Exceptions::ShellCommandFailed: Expected process to exit with [0], but received '128'