ey-local up fails and then succeeds after another run.

ey-local up

➜  testcbs git:(master) ✗ ey-local up
[ey-local] Downloading with Vagrant::Downloaders::HTTP...
[ey-local] Downloading box: https://s3.amazonaws.com/ey-local-images/ey-local-gentoo12.box
[ey-local] Extracting box...
[ey-local] Verifying box...
[ey-local] Cleaning up downloaded box...
A `Vagrantfile` has been placed in this directory. You are now
ready to `ey-local up` your first virtual environment! Please read
the comments in the Vagrantfile as well as documentation on
`vagrantup.com` for more information on using Vagrant.
[ey-local] Using /Users/nilesh/projects/testcbs as the application directory.
[ey-local] There was a problem with the configuration of Vagrant. The error message(s)
are printed below:

vm:
* Base MAC address for eth0/NAT must be set. Contact box maintainer for more information.


➜  testcbs git:(master) ✗ ey-local up
[ey-local] A box named ey-gentoo-rack already exists.
[ey-local] Using /Users/nilesh/projects/testcbs as the application directory.
[ey-local] Importing base box 'ey-gentoo-rack'...
[ey-local] Tagging vm as managed by ey-local ...
[ey-local] The guest additions on this VM do not match the install version of
VirtualBox! This may cause things such as forwarded ports, shared
folders, and more to not work properly. If any of those things fail on
this machine, please update the guest additions and repackage the
box.

Guest Additions Version: 4.1.10
VirtualBox Version: 4.2.4
[ey-local] Matching MAC address for NAT networking...
[ey-local] Clearing any previously set forwarded ports...
[ey-local] Forwarding ports...
[ey-local] -- 22 => 2222 (adapter 1)
[ey-local] -- 80 => 8080 (adapter 1)
[ey-local] Creating shared folders metadata...
[ey-local] Clearing any previously set network interfaces...
[ey-local] Preparing network interfaces based on configuration...
[ey-local] Booting VM...
[ey-local] Waiting for VM to boot. This can take a few minutes.
[ey-local] VM booted and ready for use!
[ey-local] Configuring and enabling network interfaces...
[ey-local] Mounting shared folders...
[ey-local] -- v-root: /vagrant
[ey-local] Creating instance DNA ...
[ey-local] Initiating Chef run ...
[default] [Fri, 16 Nov 2012 02:21:49 +0000] INFO: Starting Chef Solo Run
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: Mounting devices for kernel 2.6.32-amazon-xen-r3
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: 2.6.32+ kernel detected, assuming paravirtual mount style
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: {"SHELL"=>"/bin/bash",
 "TERM"=>"vt100",
 "USER"=>"root",
 "SUDO_USER"=>"vagrant",
 "SUDO_UID"=>"1000",
 "USERNAME"=>"root",
 "MAIL"=>"/var/mail/vagrant",
 "PATH"=>"/usr/local/ey_resin/bin:/sbin:/bin:/usr/sbin:/usr/bin",
 "PWD"=>"/etc/chef",
 "SHLVL"=>"0",
 "SUDO_COMMAND"=>
  "/bin/env PATH=/usr/local/ey_resin/bin:/sbin:/bin:/usr/sbin:/usr/bin chef-solo -j /etc/chef/dna.json -c /etc/chef/solo.rb",
 "HOME"=>"/home/vagrant",
 "LOGNAME"=>"root",
 "SUDO_GID"=>"415"}
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: Release: 2009a.1_pre51
 make.conf updated
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: OpenSSL Version: 0.9.8x
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: --- Dropping db.yml file for db postgresql
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: Making monitrc for: memcached
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: NGINX ACTION: restart
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: NGINX Version: 1.2.3-r1
[default] [Fri, 16 Nov 2012 02:21:50 +0000] INFO: postgres9_1 setup db volume
[default] [Fri, 16 Nov 2012 02:21:51 +0000] INFO: Ran execute[set-time-source-to-tsc] successfully
[default] [Fri, 16 Nov 2012 02:21:51 +0000] INFO: Updating template[/etc/ntp.conf] at /etc/ntp.conf
[default] [Fri, 16 Nov 2012 02:21:51 +0000] INFO: Backing up template[/etc/ntp.conf] to /etc/ntp.conf.chef-20121116022151
[default] [Fri, 16 Nov 2012 02:21:51 +0000] INFO: Updating remote_file[/etc/conf.d/ntpd] at /etc/conf.d/ntpd
[default] [Fri, 16 Nov 2012 02:21:51 +0000] INFO: Backing up remote_file[/etc/conf.d/ntpd] to /etc/conf.d/ntpd.chef-20121116022151
[default] [Fri, 16 Nov 2012 02:21:51 +0000] INFO: service[ntpd]: enabled successfully
[default] [Fri, 16 Nov 2012 02:21:51 +0000] INFO: Started service service[ntpd] successfully
[default] [Fri, 16 Nov 2012 02:21:52 +0000] INFO: Updating template[/etc/make.conf] at /etc/make.conf
[default] [Fri, 16 Nov 2012 02:21:52 +0000] INFO: Backing up template[/etc/make.conf] to /etc/make.conf.chef-20121116022152
[default] [Fri, 16 Nov 2012 02:21:52 +0000] INFO: processing portage update
[default] [Fri, 16 Nov 2012 02:22:57 +0000] INFO: Ran execute[synchronize portage] successfully
[default] [Fri, 16 Nov 2012 02:22:57 +0000] INFO: Ran execute[echo '=dev-libs/openssl-0.9.8x' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:22:57 +0000] INFO: Installing package[dev-libs/openssl] version 0.9.8x
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Updating remote_file[/etc/ssh/sshd_config] at /etc/ssh/sshd_config
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: service[sshd]: restarted successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[start-backup-inittab] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[remove-sm-from-inittab] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[echo 'sm:345:respawn:/bin/ps -Af | awk '{print $3,$8}' | grep -q '^1 /usr/sbin/sshd$' || (/etc/init.d/sshd zap && /etc/init.d/sshd start); sleep 60' >> /etc/inittab] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[telinit-q] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[remove-cm-from-inittab] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[echo 'cm:345:respawn:/engineyard/bin/cron_nanny' >> /etc/inittab] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[telinit-q] successfully
[Fri, 16 Nov 2012 02:23:08 +0000] INFO: Creating file[/etc/engineyard/instance_api.yml] at /etc/engineyard/instance_api.yml
[Fri, 16 Nov 2012 02:23:08 +0000] INFO: Setting owner to 0 for file[/etc/engineyard/instance_api.yml]
[Fri, 16 Nov 2012 02:23:08 +0000] INFO: Setting group to 0 for file[/etc/engineyard/instance_api.yml]
[Fri, 16 Nov 2012 02:23:08 +0000] INFO: Setting mode to 640 for file[/etc/engineyard/instance_api.yml]
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[echo '--- 
token: ""
base_url: https://cloud.engineyard.com/instance_api
instance_id: ""
' > /etc/engineyard/instance_api.yml] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[remove-net.ipv4.tcp_syncookies-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[echo 'net.ipv4.tcp_syncookies = 1' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[remove-fs.file-max-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[echo 'fs.file-max = 1048576' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[remove-net.core.somaxconn-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[echo 'net.core.somaxconn = 65535' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Updating remote_file[/etc/init.d/atd] at /etc/init.d/atd
[default] [Fri, 16 Nov 2012 02:23:08 +0000] INFO: Ran execute[noop] successfully
[Fri, 16 Nov 2012 02:23:08 +0000] INFO: processing unix packages
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: processing users
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[update-username] successfully
[Fri, 16 Nov 2012 02:23:09 +0000] INFO: Creating directory[/data/homedirs/vagrant] at /data/homedirs/vagrant
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Setting owner to 1000 for directory[/data/homedirs/vagrant]
[Fri, 16 Nov 2012 02:23:09 +0000] INFO: Setting group to 1000 for directory[/data/homedirs/vagrant]
[Fri, 16 Nov 2012 02:23:09 +0000] INFO: Setting mode to 755 for directory[/data/homedirs/vagrant]
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[chown homedir to user] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[clearing old crons] successfully
[Fri, 16 Nov 2012 02:23:09 +0000] INFO: Creating file[/tmp/cron_update_header] at /tmp/cron_update_header
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'PATH=/bin:/usr/bin:/usr/local/ey_resin/bin
RAILS_ENV="production"
RACK_ENV="production"
' > /tmp/cron_update_header] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[add environment variables to cron] successfully
[Fri, 16 Nov 2012 02:23:09 +0000] INFO: Backing up file[/tmp/cron_update_header] to /tmp/cron_update_header.chef-20121116022309
[Fri, 16 Nov 2012 02:23:09 +0000] INFO: Deleting file[/tmp/cron_update_header] at /tmp/cron_update_header
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Added cron 'ey-snapshots'
[Fri, 16 Nov 2012 02:23:09 +0000] INFO: Setting group to 409 for directory[/var/spool/cron]
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Added cron 'touch cron-check'
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Creating remote_file[/engineyard/bin/cron_nanny] at /engineyard/bin/cron_nanny
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Setting owner to 0 for remote_file[/engineyard/bin/cron_nanny]
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Setting group to 0 for remote_file[/engineyard/bin/cron_nanny]
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Setting mode to 755 for remote_file[/engineyard/bin/cron_nanny]
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[Ensure that cron_nanny is restarted by init with the latest version] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.core.rmem_max-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.core.rmem_max = 33554432' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.core.wmem_max-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.core.wmem_max = 33554432' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.ipv4.tcp_mem-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.ipv4.tcp_mem = 8388608 8388608 8388608' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.ipv4.tcp_syncookies-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.ipv4.tcp_syncookies = 1' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.ipv4.tcp_max_syn_backlog-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.ipv4.tcp_max_syn_backlog = 262144' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.ipv4.tcp_synack_retries-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.ipv4.tcp_synack_retries = ' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.core.somaxconn-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.core.somaxconn = 655355' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.core.netdev_max_backlog-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.core.netdev_max_backlog = 262144' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.ipv4.tcp_tw_reuse-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.ipv4.tcp_tw_reuse = 1' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.ipv4.ip_local_port_range-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.ipv4.ip_local_port_range = 1024 65023' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.ipv4.tcp_max_tw_buckets-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.ipv4.tcp_max_tw_buckets = 400000' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[remove-net.ipv4.tcp_max_orphans-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[echo 'net.ipv4.tcp_max_orphans = 60000' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:23:09 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Ran bash[make-swap-64-medium] successfully
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 1000 for directory[/data]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 1000 for directory[/data]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 1000 for directory[/data/homedirs]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 1000 for directory[/data/homedirs]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Creating directory[/data/cbs] at /data/cbs
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 1000 for directory[/data/cbs]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 1000 for directory[/data/cbs]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting mode to 755 for directory[/data/cbs]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Creating directory[/var/log/engineyard] at /var/log/engineyard
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 1000 for directory[/var/log/engineyard]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 1000 for directory[/var/log/engineyard]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting mode to 755 for directory[/var/log/engineyard]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Creating directory[/var/cache/engineyard] at /var/cache/engineyard
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 1000 for directory[/var/cache/engineyard]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 1000 for directory[/var/cache/engineyard]
[Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting mode to 755 for directory[/var/cache/engineyard]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Updating remote_file[/etc/security/limits.conf] at /etc/security/limits.conf
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Backing up remote_file[/etc/security/limits.conf] to /etc/security/limits.conf.chef-20121116022314
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Creating remote_file[/etc/env.d/99manpager] at /etc/env.d/99manpager
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 0 for remote_file[/etc/env.d/99manpager]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 0 for remote_file[/etc/env.d/99manpager]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting mode to 644 for remote_file[/etc/env.d/99manpager]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Creating remote_file[/etc/env.d/25editor] at /etc/env.d/25editor
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 0 for remote_file[/etc/env.d/25editor]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 0 for remote_file[/etc/env.d/25editor]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting mode to 644 for remote_file[/etc/env.d/25editor]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Creating remote_file[/etc/env.d/02locale] at /etc/env.d/02locale
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 0 for remote_file[/etc/env.d/02locale]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 0 for remote_file[/etc/env.d/02locale]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting mode to 644 for remote_file[/etc/env.d/02locale]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Creating remote_file[/etc/profile.d/history-helper.sh] at /etc/profile.d/history-helper.sh
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 0 for remote_file[/etc/profile.d/history-helper.sh]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 0 for remote_file[/etc/profile.d/history-helper.sh]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting mode to 755 for remote_file[/etc/profile.d/history-helper.sh]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Creating remote_file[/etc/env.d/26history] at /etc/env.d/26history
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 0 for remote_file[/etc/env.d/26history]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 0 for remote_file[/etc/env.d/26history]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting mode to 644 for remote_file[/etc/env.d/26history]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Creating template[/etc/env.d/05framework_env] at /etc/env.d/05framework_env
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting owner to 0 for template[/etc/env.d/05framework_env]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting group to 0 for template[/etc/env.d/05framework_env]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Setting mode to 644 for template[/etc/env.d/05framework_env]
[default] [Fri, 16 Nov 2012 02:23:14 +0000] INFO: Ran execute[remove framework_env from /etc/profile] successfully
[default] [Fri, 16 Nov 2012 02:23:16 +0000] INFO: Upgrading package[net-misc/wget] version from 1.11.1 to 1.12-r3
[default] [Fri, 16 Nov 2012 02:23:22 +0000] INFO: Upgrading package[app-arch/tar] version from 1.20 to 1.26
[default] [Fri, 16 Nov 2012 02:23:30 +0000] INFO: Upgrading package[net-misc/rsync] version from 3.0.5 to 3.0.9
[default] [Fri, 16 Nov 2012 02:23:34 +0000] INFO: Upgrading package[dev-java/sun-jdk] version from 1.6.0.11 to 1.6.0.30
[default] [Fri, 16 Nov 2012 02:24:04 +0000] INFO: Creating template[/engineyard/bin/env.sh] at /engineyard/bin/env.sh
[default] [Fri, 16 Nov 2012 02:24:04 +0000] INFO: Setting owner to 0 for template[/engineyard/bin/env.sh]
[default] [Fri, 16 Nov 2012 02:24:04 +0000] INFO: Setting group to 0 for template[/engineyard/bin/env.sh]
[default] [Fri, 16 Nov 2012 02:24:04 +0000] INFO: Setting mode to 755 for template[/engineyard/bin/env.sh]
[default] [Fri, 16 Nov 2012 02:24:05 +0000] INFO: Installing package[sys-apps/ey-monit-scripts] version 0.19.21
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: processing backups
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Creating directory[/mnt/backups] at /mnt/backups
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting owner to 1000 for directory[/mnt/backups]
[Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting group to 1000 for directory[/mnt/backups]
[Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting mode to 755 for directory[/mnt/backups]
[Fri, 16 Nov 2012 02:24:10 +0000] INFO: Creating directory[/etc/chef-custom] at /etc/chef-custom
[Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting owner to 1000 for directory[/etc/chef-custom]
[Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting group to 1000 for directory[/etc/chef-custom]
[Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting mode to 755 for directory[/etc/chef-custom]
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Creating remote_file[/etc/chef-custom/solo.rb] at /etc/chef-custom/solo.rb
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting owner to 1000 for remote_file[/etc/chef-custom/solo.rb]
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting group to 1000 for remote_file[/etc/chef-custom/solo.rb]
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting mode to 755 for remote_file[/etc/chef-custom/solo.rb]
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Updating template[/etc/sudoers] at /etc/sudoers
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Backing up template[/etc/sudoers] to /etc/sudoers.chef-20121116022410
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: processing ssh keys
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Setting mode to 755 for directory[/home/vagrant/.ssh]
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: processing ruby_193
[default] [Fri, 16 Nov 2012 02:24:10 +0000] INFO: Installing package[app-admin/eselect-ruby] version 20110201-r1
[default] [Fri, 16 Nov 2012 02:24:13 +0000] INFO: Ran execute[remove-mirror-from-etc-hosts] successfully
[default] [Fri, 16 Nov 2012 02:24:13 +0000] INFO: Creating remote_file[/etc/gemrc] at /etc/gemrc
[default] [Fri, 16 Nov 2012 02:24:14 +0000] INFO: Ran execute[echo '=dev-lang/ruby-1.9.3_p327' >> /etc/portage/package.unmask/ruby] successfully
[default] [Fri, 16 Nov 2012 02:24:14 +0000] INFO: Ran execute[echo '=dev-lang/ruby-1.9.3_p327' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:24:17 +0000] INFO: Installing package[dev-lang/ruby] version 1.9.3_p327
[default] [Fri, 16 Nov 2012 02:24:27 +0000] INFO: Ran execute[eselect-ruby19-for-ruby] successfully
[default] [Fri, 16 Nov 2012 02:24:33 +0000] INFO: Ran execute[install rubygems 1.8.17] successfully
[Fri, 16 Nov 2012 02:24:33 +0000] INFO: installing Rubygems 1.8.17
[default] [Fri, 16 Nov 2012 02:24:35 +0000] INFO: Ran execute[install rubygems 1.8.17] successfully
[default] [Fri, 16 Nov 2012 02:24:39 +0000] INFO: Ran execute[update rubygems to >= 1.8.17] successfully
[default] [Fri, 16 Nov 2012 02:24:39 +0000] INFO: Ran execute[update env] successfully
[Fri, 16 Nov 2012 02:24:39 +0000] INFO: processing custom gems
[default] Successfully installed activesupport-2.3.2
Successfully installed activerecord-2.3.2
Successfully installed actionpack-2.3.2
Successfully installed actionmailer-2.3.2
Successfully installed activeresource-2.3.2
Successfully installed rails-2.3.2
6 gems installed
[default] Building native extensions.  This could take a while...
[default] Successfully installed mysql2-0.3.2
1 gem installed
[default] [Fri, 16 Nov 2012 02:24:54 +0000] INFO: processing monit
[default] [Fri, 16 Nov 2012 02:24:54 +0000] INFO: Ran execute[echo '=app-admin/monit-5.0.3' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Updating template[/etc/monitrc] at /etc/monitrc
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Backing up template[/etc/monitrc] to /etc/monitrc.chef-20121116022455
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Ran bash[migrate-monit.d-dir] successfully
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Creating directory[/data/monit.d] at /data/monit.d
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting owner to 0 for directory[/data/monit.d]
[Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting group to 0 for directory[/data/monit.d]
[Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting mode to 755 for directory[/data/monit.d]
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Creating template[/etc/monit.d/alerts.monitrc] at /etc/monit.d/alerts.monitrc
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting owner to 0 for template[/etc/monit.d/alerts.monitrc]
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting group to 0 for template[/etc/monit.d/alerts.monitrc]
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting mode to 700 for template[/etc/monit.d/alerts.monitrc]
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Creating template[/usr/local/bin/monit] at /usr/local/bin/monit
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting owner to 0 for template[/usr/local/bin/monit]
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting group to 0 for template[/usr/local/bin/monit]
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting mode to 700 for template[/usr/local/bin/monit]
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Ran execute[touch monitrc] successfully
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Ran execute[remove-mo-from-inittab] successfully
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Ran execute[echo 'mo:345:respawn:/usr/local/bin/monit -Ic /etc/monitrc' >> /etc/inittab] successfully
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Ran execute[telinit-q] successfully
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Ran execute[remove-m0-from-inittab] successfully
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Ran execute[echo 'm0:06:wait:/usr/local/bin/monit -Ic /etc/monitrc stop all' >> /etc/inittab] successfully
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Ran execute[telinit-q] successfully
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Creating template[/etc/motd] at /etc/motd
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting owner to 0 for template[/etc/motd]
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting group to 0 for template[/etc/motd]
[default] [Fri, 16 Nov 2012 02:24:55 +0000] INFO: Setting mode to 655 for template[/etc/motd]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Ran execute[fix the permissions] successfully
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Creating directory[/data/cbs/shared] at /data/cbs/shared
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting owner to 1000 for directory[/data/cbs/shared]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting group to 1000 for directory[/data/cbs/shared]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting mode to 755 for directory[/data/cbs/shared]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Creating directory[/data/cbs/shared/bin] at /data/cbs/shared/bin
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting owner to 1000 for directory[/data/cbs/shared/bin]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting group to 1000 for directory[/data/cbs/shared/bin]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting mode to 755 for directory[/data/cbs/shared/bin]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Creating directory[/data/cbs/shared/config] at /data/cbs/shared/config
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting owner to 1000 for directory[/data/cbs/shared/config]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting group to 1000 for directory[/data/cbs/shared/config]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting mode to 755 for directory[/data/cbs/shared/config]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Creating directory[/data/cbs/shared/pids] at /data/cbs/shared/pids
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting owner to 1000 for directory[/data/cbs/shared/pids]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting group to 1000 for directory[/data/cbs/shared/pids]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting mode to 755 for directory[/data/cbs/shared/pids]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Creating directory[/data/cbs/shared/system] at /data/cbs/shared/system
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting owner to 1000 for directory[/data/cbs/shared/system]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting group to 1000 for directory[/data/cbs/shared/system]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting mode to 755 for directory[/data/cbs/shared/system]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Creating directory[/data/cbs/releases] at /data/cbs/releases
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting owner to 1000 for directory[/data/cbs/releases]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting group to 1000 for directory[/data/cbs/releases]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting mode to 755 for directory[/data/cbs/releases]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Creating template[/data/cbs/shared/config/database.yml] at /data/cbs/shared/config/database.yml
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting owner to 1000 for template[/data/cbs/shared/config/database.yml]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting group to 1000 for template[/data/cbs/shared/config/database.yml]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: Setting mode to 655 for template[/data/cbs/shared/config/database.yml]
[default] [Fri, 16 Nov 2012 02:24:56 +0000] INFO: processing memcached
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Creating template[/etc/monit.d/memcached.monitrc] at /etc/monit.d/memcached.monitrc
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Setting owner to 0 for template[/etc/monit.d/memcached.monitrc]
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Setting group to 0 for template[/etc/monit.d/memcached.monitrc]
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Setting mode to 644 for template[/etc/monit.d/memcached.monitrc]
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: template[/etc/monit.d/memcached.monitrc] sending run action to execute[restart-monit] (immediate)
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Ran execute[restart-monit] successfully
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Creating template[/data/cbs/shared/config/memcached.yml] at /data/cbs/shared/config/memcached.yml
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Setting owner to 1000 for template[/data/cbs/shared/config/memcached.yml]
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Setting group to 1000 for template[/data/cbs/shared/config/memcached.yml]
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Setting mode to 644 for template[/data/cbs/shared/config/memcached.yml]
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: processing nginx
[default] [Fri, 16 Nov 2012 02:24:57 +0000] INFO: Ran execute[echo '=www-servers/nginx-1.2.3-r1' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:24:58 +0000] INFO: Installing package[nginx] version 1.2.3-r1
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating directory[/var/log/engineyard/nginx] at /var/log/engineyard/nginx
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting owner to 1000 for directory[/var/log/engineyard/nginx]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting group to 1000 for directory[/var/log/engineyard/nginx]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting mode to 775 for directory[/var/log/engineyard/nginx]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating directory[/data/nginx/servers] at /data/nginx/servers
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting owner to 1000 for directory[/data/nginx/servers]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting group to 1000 for directory[/data/nginx/servers]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting mode to 755 for directory[/data/nginx/servers]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating directory[/data/nginx/common] at /data/nginx/common
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting owner to 1000 for directory[/data/nginx/common]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting group to 1000 for directory[/data/nginx/common]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting mode to 755 for directory[/data/nginx/common]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Deleting directory[/var/log/nginx] recursively at /var/log/nginx
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Ran execute[remove /etc/nginx] successfully
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating a symbolic link from /data/nginx -> /etc/nginx for link[/etc/nginx]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting owner to 1000 for directory[/var/tmp/nginx/client]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting group to 1000 for directory[/var/tmp/nginx/client]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting mode to 775 for directory[/var/tmp/nginx/client]
[Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating a symbolic link from /var/log/engineyard/nginx -> /var/log/nginx for link[/var/log/nginx]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Updating remote_file[/etc/init.d/nginx] at /etc/init.d/nginx
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Backing up remote_file[/etc/init.d/nginx] to /etc/init.d/nginx.chef-20121116022504
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating remote_file[/data/nginx/mime.types] at /data/nginx/mime.types
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting owner to 1000 for remote_file[/data/nginx/mime.types]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting group to 1000 for remote_file[/data/nginx/mime.types]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting mode to 755 for remote_file[/data/nginx/mime.types]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating remote_file[/data/nginx/koi-utf] at /data/nginx/koi-utf
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting owner to 1000 for remote_file[/data/nginx/koi-utf]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting group to 1000 for remote_file[/data/nginx/koi-utf]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting mode to 755 for remote_file[/data/nginx/koi-utf]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating remote_file[/data/nginx/koi-win] at /data/nginx/koi-win
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting owner to 1000 for remote_file[/data/nginx/koi-win]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting group to 1000 for remote_file[/data/nginx/koi-win]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting mode to 755 for remote_file[/data/nginx/koi-win]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Ran execute[rc-update-add-nginx] successfully
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating template[/etc/logrotate.d/nginx] at /etc/logrotate.d/nginx
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting owner to 0 for template[/etc/logrotate.d/nginx]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting group to 0 for template[/etc/logrotate.d/nginx]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting mode to 644 for template[/etc/logrotate.d/nginx]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating template[/etc/conf.d/nginx] at /etc/conf.d/nginx
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Creating file[/data/nginx/stack.conf] at /data/nginx/stack.conf
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting owner to 1000 for file[/data/nginx/stack.conf]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting group to 1000 for file[/data/nginx/stack.conf]
[default] [Fri, 16 Nov 2012 02:25:04 +0000] INFO: Setting mode to 644 for file[/data/nginx/stack.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating template[/data/nginx/nginx.conf] at /data/nginx/nginx.conf
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for template[/data/nginx/nginx.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for template[/data/nginx/nginx.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 644 for template[/data/nginx/nginx.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating file[/data/nginx/http-custom.conf] at /data/nginx/http-custom.conf
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for file[/data/nginx/http-custom.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for file[/data/nginx/http-custom.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 644 for file[/data/nginx/http-custom.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating directory[/data/nginx/ssl] at /data/nginx/ssl
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for directory[/data/nginx/ssl]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for directory[/data/nginx/ssl]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 775 for directory[/data/nginx/ssl]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating template[/data/nginx/common/proxy.conf] at /data/nginx/common/proxy.conf
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for template[/data/nginx/common/proxy.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for template[/data/nginx/common/proxy.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 644 for template[/data/nginx/common/proxy.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating template[/data/nginx/common/servers.conf] at /data/nginx/common/servers.conf
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for template[/data/nginx/common/servers.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for template[/data/nginx/common/servers.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 644 for template[/data/nginx/common/servers.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating template[/data/nginx/common/fcgi.conf] at /data/nginx/common/fcgi.conf
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for template[/data/nginx/common/fcgi.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for template[/data/nginx/common/fcgi.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 644 for template[/data/nginx/common/fcgi.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating file[/data/nginx/servers/default.conf] at /data/nginx/servers/default.conf
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for file[/data/nginx/servers/default.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for file[/data/nginx/servers/default.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 644 for file[/data/nginx/servers/default.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating directory[/data/nginx/servers/cbs] at /data/nginx/servers/cbs
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for directory[/data/nginx/servers/cbs]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for directory[/data/nginx/servers/cbs]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 775 for directory[/data/nginx/servers/cbs]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating directory[/data/nginx/servers/cbs/ssl] at /data/nginx/servers/cbs/ssl
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for directory[/data/nginx/servers/cbs/ssl]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for directory[/data/nginx/servers/cbs/ssl]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 775 for directory[/data/nginx/servers/cbs/ssl]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating file[/data/nginx/servers/cbs/custom.conf] at /data/nginx/servers/cbs/custom.conf
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for file[/data/nginx/servers/cbs/custom.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for file[/data/nginx/servers/cbs/custom.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 644 for file[/data/nginx/servers/cbs/custom.conf]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Creating template[/data/nginx/servers/cbs.users] at /data/nginx/servers/cbs.users
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting owner to 1000 for template[/data/nginx/servers/cbs.users]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting group to 1000 for template[/data/nginx/servers/cbs.users]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Setting mode to 644 for template[/data/nginx/servers/cbs.users]
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Ran execute[ensure-no-old-ssl-vhosts-for-cbs] successfully
[default] [Fri, 16 Nov 2012 02:25:05 +0000] INFO: Started service service[nginx] successfully
[default] [Fri, 16 Nov 2012 02:25:06 +0000] INFO: Installing gem_package[passenger] version 3.0.11
[default] [Fri, 16 Nov 2012 02:25:12 +0000] INFO: Setting owner to 1000 for directory[/data/nginx]
[Fri, 16 Nov 2012 02:25:12 +0000] INFO: Setting group to 1000 for directory[/data/nginx]
[Fri, 16 Nov 2012 02:25:12 +0000] INFO: Setting mode to 775 for directory[/data/nginx]
[default] [Fri, 16 Nov 2012 02:25:12 +0000] INFO: Deleting directory[/var/log/nginx] recursively at /var/log/nginx
[default] [Fri, 16 Nov 2012 02:25:12 +0000] INFO: Creating a symbolic link from /var/log/engineyard/nginx -> /var/log/nginx for link[/var/log/nginx]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Updating template[/data/nginx/stack.conf] at /data/nginx/stack.conf
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Backing up template[/data/nginx/stack.conf] to /data/nginx/stack.conf.chef-20121116022513
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Creating template[/data/nginx/servers/cbs.rewrites] at /data/nginx/servers/cbs.rewrites
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting owner to 1000 for template[/data/nginx/servers/cbs.rewrites]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting group to 1000 for template[/data/nginx/servers/cbs.rewrites]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting mode to 644 for template[/data/nginx/servers/cbs.rewrites]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Creating template[/data/nginx/servers/cbs.conf] at /data/nginx/servers/cbs.conf
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting owner to 1000 for template[/data/nginx/servers/cbs.conf]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting group to 1000 for template[/data/nginx/servers/cbs.conf]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting mode to 644 for template[/data/nginx/servers/cbs.conf]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Added cron 'passenger_monitor_cbs'
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Creating template[/engineyard/bin/app_cbs] at /engineyard/bin/app_cbs
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting owner to 1000 for template[/engineyard/bin/app_cbs]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting group to 1000 for template[/engineyard/bin/app_cbs]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting mode to 755 for template[/engineyard/bin/app_cbs]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: processing deploy keys
[Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting mode to 700 for directory[/home/vagrant/.ssh]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Creating file[/root/.ssh/cbs-deploy-key] at /root/.ssh/cbs-deploy-key
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting mode to 600 for file[/root/.ssh/cbs-deploy-key]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Ran execute[echo '' > /root/.ssh/cbs-deploy-key] successfully
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Creating file[/home/vagrant/.ssh/cbs-deploy-key] at /home/vagrant/.ssh/cbs-deploy-key
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting owner to 1000 for file[/home/vagrant/.ssh/cbs-deploy-key]
[Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting group to 1000 for file[/home/vagrant/.ssh/cbs-deploy-key]
[Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting mode to 600 for file[/home/vagrant/.ssh/cbs-deploy-key]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Ran execute[echo '' > /home/vagrant/.ssh/cbs-deploy-key] successfully
[Fri, 16 Nov 2012 02:25:13 +0000] INFO: processing postgresql 9.1
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Creating template[/root/.pgpass] at /root/.pgpass
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting owner to 0 for template[/root/.pgpass]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting group to 0 for template[/root/.pgpass]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Setting mode to 600 for template[/root/.pgpass]
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Ran execute[touch ~/.bash_login] successfully
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Creating file[/root/.bash_login] at /root/.bash_login
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Ran execute[echo 'export PGUSER='postgres'' >> /root/.bash_login] successfully
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Ran execute[echo '=dev-db/postgresql-base-9.1.3' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:25:13 +0000] INFO: Ran execute[echo '=dev-db/postgresql-server-9.1.3' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:25:14 +0000] INFO: Installing package[dev-db/postgresql-base] version 9.1.3
[default] [Fri, 16 Nov 2012 02:25:22 +0000] INFO: Installing package[dev-db/postgresql-server] version 9.1.3
[default] [Fri, 16 Nov 2012 02:25:29 +0000] INFO: Ran execute[activate_postgres] successfully
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Ran execute[clean up previous version of postgresql-server-9.1 ] successfully
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Ran execute[remove-kernel.shmmax-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Ran execute[echo 'kernel.shmmax = 520011776' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Ran execute[remove-kernel.shmall-in-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Ran execute[echo 'kernel.shmall = 126956' >> /etc/sysctl.conf] successfully
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Ran execute[reload-sysctl] successfully
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting mode to 755 for directory[/var/run/postgresql]
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Updating template[/etc/conf.d/postgresql-9.1] at /etc/conf.d/postgresql-9.1
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Creating directory[/db] at /db
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting owner to 70 for directory[/db]
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting group to 70 for directory[/db]
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting mode to 755 for directory[/db]
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Creating directory[/mnt/postgresql/tmp] at /mnt/postgresql/tmp
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting owner to 70 for directory[/mnt/postgresql/tmp]
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting group to 70 for directory[/mnt/postgresql/tmp]
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting mode to 755 for directory[/mnt/postgresql/tmp]
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: processing postgresql 9.1 configuration
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Creating directory[/db/postgresql/9.1] at /db/postgresql/9.1
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting owner to 70 for directory[/db/postgresql/9.1]
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting group to 70 for directory[/db/postgresql/9.1]
[default] [Fri, 16 Nov 2012 02:25:30 +0000] INFO: Setting mode to 755 for directory[/db/postgresql/9.1]
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Ran execute[init-postgres] successfully
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Updating template[/db/postgresql/9.1/data/postgresql.conf] at /db/postgresql/9.1/data/postgresql.conf
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Creating file[/db/postgresql/9.1/custom.conf] at /db/postgresql/9.1/custom.conf
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Setting owner to 1000 for file[/db/postgresql/9.1/custom.conf]
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Setting group to 1000 for file[/db/postgresql/9.1/custom.conf]
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Setting mode to 644 for file[/db/postgresql/9.1/custom.conf]
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Creating file[/db/postgresql/9.1/custom_pg_hba.conf] at /db/postgresql/9.1/custom_pg_hba.conf
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Setting owner to 1000 for file[/db/postgresql/9.1/custom_pg_hba.conf]
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Setting group to 1000 for file[/db/postgresql/9.1/custom_pg_hba.conf]
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Setting mode to 644 for file[/db/postgresql/9.1/custom_pg_hba.conf]
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Updating template[/db/postgresql/9.1/data/pg_hba.conf] at /db/postgresql/9.1/data/pg_hba.conf
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: Backing up template[/db/postgresql/9.1/data/pg_hba.conf] to /db/postgresql/9.1/data/pg_hba.conf.chef-20121116022531
[default] [Fri, 16 Nov 2012 02:25:31 +0000] INFO: service[postgresql-9.1]: enabled successfully
[default] [Fri, 16 Nov 2012 02:25:32 +0000] INFO: Started service service[postgresql-9.1] successfully
[Fri, 16 Nov 2012 02:25:32 +0000] INFO: --cat /db/postgresql/9.1/custom_pg_hba.conf /db/postgresql/9.1/data/pg_hba.conf  > /tmp/pg_hba.conf; mv /tmp/pg_hba.conf /db/postgresql/9.1/data/pg_hba.conf
[default] [Fri, 16 Nov 2012 02:25:32 +0000] INFO: --/etc/init.d/postgresql-9.1 reload
[default] [Fri, 16 Nov 2012 02:25:32 +0000] INFO: Creating remote_file[/engineyard/bin/load_foreign_postgres_db.sh] at /engineyard/bin/load_foreign_postgres_db.sh
[default] [Fri, 16 Nov 2012 02:25:32 +0000] INFO: Setting mode to 744 for remote_file[/engineyard/bin/load_foreign_postgres_db.sh]
[default] [Fri, 16 Nov 2012 02:25:32 +0000] INFO: Unlocked user[postgres]
[default] [Fri, 16 Nov 2012 02:25:32 +0000] INFO: Ran execute[create-db-uservagrant] successfully
[default] [Fri, 16 Nov 2012 02:25:32 +0000] INFO: Ran execute[alter-db-user-postgres] successfully
[default] [Fri, 16 Nov 2012 02:25:32 +0000] INFO: Ran execute[alter-public-schema-owner-to-vagrant] successfully
[default] [Fri, 16 Nov 2012 02:25:33 +0000] INFO: Ran execute[create database for cbs] successfully
[default] [Fri, 16 Nov 2012 02:25:33 +0000] INFO: Creating template[/etc/.postgresql.backups.yml] at /etc/.postgresql.backups.yml
[default] [Fri, 16 Nov 2012 02:25:33 +0000] INFO: Setting owner to 0 for template[/etc/.postgresql.backups.yml]
[default] [Fri, 16 Nov 2012 02:25:33 +0000] INFO: Setting group to 0 for template[/etc/.postgresql.backups.yml]
[default] [Fri, 16 Nov 2012 02:25:33 +0000] INFO: Setting mode to 600 for template[/etc/.postgresql.backups.yml]
[default] [Fri, 16 Nov 2012 02:25:33 +0000] INFO: processing postgresql 9.1 monitoring
[default] [Fri, 16 Nov 2012 02:25:34 +0000] INFO: Creating remote_file[/mnt/check_postgres.tar.gz] at /mnt/check_postgres.tar.gz
[default] [Fri, 16 Nov 2012 02:25:34 +0000] INFO: Setting mode to 755 for remote_file[/mnt/check_postgres.tar.gz]
[default] [Fri, 16 Nov 2012 02:25:34 +0000] INFO: Ran execute[untar check_postgres] successfully
[default] [Fri, 16 Nov 2012 02:25:34 +0000] INFO: Ran execute[make check_postgres / install] successfully
[default] [Fri, 16 Nov 2012 02:25:34 +0000] INFO: Creating template[/engineyard/bin/check_postgres_wrapper.sh] at /engineyard/bin/check_postgres_wrapper.sh
[default] [Fri, 16 Nov 2012 02:25:34 +0000] INFO: Setting owner to 70 for template[/engineyard/bin/check_postgres_wrapper.sh]
[default] [Fri, 16 Nov 2012 02:25:34 +0000] INFO: Setting group to 70 for template[/engineyard/bin/check_postgres_wrapper.sh]
[default] [Fri, 16 Nov 2012 02:25:34 +0000] INFO: Setting mode to 755 for template[/engineyard/bin/check_postgres_wrapper.sh]
[default] [Fri, 16 Nov 2012 02:25:42 +0000] INFO: Ran execute[re-link-postgresql] successfully
[Fri, 16 Nov 2012 02:25:42 +0000] INFO: execute[re-link-postgresql] sending touch action to file[/etc/engineyard/.postgresql-9.1.ey-resin-pg-re-linked] (immediate)
[Fri, 16 Nov 2012 02:25:42 +0000] INFO: Creating file[/etc/engineyard/.postgresql-9.1.ey-resin-pg-re-linked] at /etc/engineyard/.postgresql-9.1.ey-resin-pg-re-linked
[Fri, 16 Nov 2012 02:25:42 +0000] INFO: Setting owner to 0 for file[/etc/engineyard/.postgresql-9.1.ey-resin-pg-re-linked]
[Fri, 16 Nov 2012 02:25:42 +0000] INFO: Setting group to 0 for file[/etc/engineyard/.postgresql-9.1.ey-resin-pg-re-linked]
[Fri, 16 Nov 2012 02:25:42 +0000] INFO: Setting mode to 644 for file[/etc/engineyard/.postgresql-9.1.ey-resin-pg-re-linked]
[Fri, 16 Nov 2012 02:25:42 +0000] INFO: Updating file[/etc/engineyard/.postgresql-9.1.ey-resin-pg-re-linked] with new atime/mtime of Fri Nov 16 02:25:42 +0000 2012
[default] [Fri, 16 Nov 2012 02:25:42 +0000] INFO: Ran execute[clean up previous version of postgresql-base-9.1 ] successfully
[Fri, 16 Nov 2012 02:25:42 +0000] INFO: processing postgresql 9.1 finished
[default] [Fri, 16 Nov 2012 02:25:42 +0000] INFO: Ran execute[echo '=dev-db/redis-2.4.17' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:25:43 +0000] INFO: Installing package[dev-db/redis] version 2.4.17
[default] [Fri, 16 Nov 2012 02:25:47 +0000] INFO: Creating directory[/db/redis] at /db/redis
[default] [Fri, 16 Nov 2012 02:25:47 +0000] INFO: Setting owner to 104 for directory[/db/redis]
[default] [Fri, 16 Nov 2012 02:25:47 +0000] INFO: Setting group to 413 for directory[/db/redis]
[default] [Fri, 16 Nov 2012 02:25:47 +0000] INFO: Setting mode to 755 for directory[/db/redis]
[default] [Fri, 16 Nov 2012 02:25:47 +0000] INFO: Creating directory[/etc/redis] at /etc/redis
[default] [Fri, 16 Nov 2012 02:25:47 +0000] INFO: Updating template[/etc/conf.d/redis] at /etc/conf.d/redis
[default] [Fri, 16 Nov 2012 02:25:47 +0000] INFO: Backing up template[/etc/conf.d/redis] to /etc/conf.d/redis.chef-20121116022547
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Creating template[/etc/redis/redis.conf] at /etc/redis/redis.conf
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting owner to 0 for template[/etc/redis/redis.conf]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting group to 0 for template[/etc/redis/redis.conf]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting mode to 644 for template[/etc/redis/redis.conf]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Creating a symbolic link from /etc/redis/redis.conf -> /etc/redis.conf for link[/etc/redis.conf]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Creating remote_file[/etc/profile.d/redis-config.sh] at /etc/profile.d/redis-config.sh
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting owner to 0 for remote_file[/etc/profile.d/redis-config.sh]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting group to 0 for remote_file[/etc/profile.d/redis-config.sh]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting mode to 755 for remote_file[/etc/profile.d/redis-config.sh]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Creating template[/data/monit.d/redis.monitrc] at /data/monit.d/redis.monitrc
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting owner to 0 for template[/data/monit.d/redis.monitrc]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting group to 0 for template[/data/monit.d/redis.monitrc]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting mode to 644 for template[/data/monit.d/redis.monitrc]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Creating directory[/var/log/engineyard/apps/cbs] at /var/log/engineyard/apps/cbs
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting owner to 1000 for directory[/var/log/engineyard/apps/cbs]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting group to 1000 for directory[/var/log/engineyard/apps/cbs]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting mode to 755 for directory[/var/log/engineyard/apps/cbs]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Creating a symbolic link from /var/log/engineyard/apps/cbs -> /data/cbs/shared/log for link[/data/cbs/shared/log]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Creating template[/etc/logrotate.d/application-logs] at /etc/logrotate.d/application-logs
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting owner to 0 for template[/etc/logrotate.d/application-logs]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting group to 0 for template[/etc/logrotate.d/application-logs]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Setting mode to 644 for template[/etc/logrotate.d/application-logs]
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: creating deploy user "vagrant"
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Ran execute[touch /etc/portage/package.unmask/pg_top] successfully
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Ran execute[echo '=dev-db/pg_top-3.6.2-r1' >> /etc/portage/package.unmask/pg_top] successfully
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Ran execute[echo '=dev-db/pg_top-3.6.2-r1' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:25:48 +0000] INFO: Installing package[dev-db/pg_top] version 3.6.2-r1
[default] [Fri, 16 Nov 2012 02:25:52 +0000] INFO: processing zsh - install
[default] [Fri, 16 Nov 2012 02:25:53 +0000] INFO: Ran execute[env-update] successfully
[default] [Fri, 16 Nov 2012 02:25:53 +0000] INFO: Ran execute[echo '=net-libs/nodejs-0.6.21' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:25:53 +0000] INFO: Ran execute[echo '=net-libs/nodejs-0.8.7' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:25:53 +0000] INFO: Ran execute[echo '=net-libs/nodejs-0.8.8' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:25:53 +0000] INFO: Ran execute[echo '=net-libs/nodejs-0.8.11' >> /etc/portage/package.keywords/local] successfully
[default] [Fri, 16 Nov 2012 02:25:53 +0000] INFO: Ran execute[touch /etc/portage/package.unmask/nodejs] successfully
[default] [Fri, 16 Nov 2012 02:25:53 +0000] INFO: Ran execute[echo '=net-libs/nodejs-0.8.11' >> /etc/portage/package.unmask/nodejs] successfully
[default] [Fri, 16 Nov 2012 02:25:55 +0000] INFO: Installing package[net-libs/nodejs] version 0.8.11
[default] [Fri, 16 Nov 2012 02:26:03 +0000] INFO: Ran execute[eselect-0.8.11-for-nodejs] successfully
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Ran bash[install ey_node_app_info] successfully
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: Creating a symbolic link from /opt/nodejs/0.8.11/bin/ey_node_app_info -> /usr/local/bin/ey_node_app_info for link[/usr/local/bin/ey_node_app_info]
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Creating directory[/opt/opt/nodejs] at /opt/opt/nodejs
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: Setting owner to 0 for directory[/opt/opt/nodejs]
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: Setting group to 0 for directory[/opt/opt/nodejs]
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: Setting mode to 755 for directory[/opt/opt/nodejs]
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Creating template[/opt/nodejs/nodejs_available_versions.json] at /opt/nodejs/nodejs_available_versions.json
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Setting owner to 0 for template[/opt/nodejs/nodejs_available_versions.json]
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Setting group to 0 for template[/opt/nodejs/nodejs_available_versions.json]
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Setting mode to 644 for template[/opt/nodejs/nodejs_available_versions.json]
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Creating file[/etc/engineyard/recipe-revision.txt] at /etc/engineyard/recipe-revision.txt
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Setting mode to 644 for file[/etc/engineyard/recipe-revision.txt]
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Updating file[/etc/engineyard/recipe-revision.txt] with new atime/mtime of Fri Nov 16 02:26:09 +0000 2012
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Ran bash[add-chef-dracul-revision-sha] successfully
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: execute[start-backup-inittab] sending run action to execute[rotate-backup-inittab] (delayed)
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Ran execute[rotate-backup-inittab] successfully
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: execute[start-backup-inittab] sending run action to execute[rotate-backup-inittab] (delayed)
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Ran execute[rotate-backup-inittab] successfully
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/etc/ntp.conf] sending restart action to service[ntpd] (delayed)
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: service[ntpd]: restarted successfully
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: execute[start-backup-inittab] sending run action to execute[rotate-backup-inittab] (delayed)
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Ran execute[rotate-backup-inittab] successfully
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: execute[start-backup-inittab] sending run action to execute[cleanup-backup-inittab] (delayed)
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: Ran execute[cleanup-backup-inittab] successfully
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/etc/conf.d/nginx] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/nginx.conf] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/common/proxy.conf] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/common/servers.conf] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/common/fcgi.conf] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/servers/cbs.rewrites] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/servers/cbs.conf] sending restart action to service[nginx] (delayed)
[default] [Fri, 16 Nov 2012 02:26:09 +0000] INFO: service[nginx]: restarted successfully
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/etc/conf.d/nginx] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/nginx.conf] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/common/proxy.conf] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/common/servers.conf] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/common/fcgi.conf] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: file[/data/nginx/servers/default.conf] sending restart action to service[nginx] (delayed)
[Fri, 16 Nov 2012 02:26:09 +0000] INFO: template[/data/nginx/servers/cbs.users] sending restart action to service[nginx] (delayed)
[default] [Fri, 16 Nov 2012 02:26:10 +0000] INFO: service[nginx]: restarted successfully
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: execute[start-backup-inittab] sending run action to execute[cleanup-backup-inittab] (delayed)
[default] [Fri, 16 Nov 2012 02:26:10 +0000] INFO: Ran execute[cleanup-backup-inittab] successfully
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: template[/data/monit.d/redis.monitrc] sending run action to execute[restart-monit] (delayed)
[default] [Fri, 16 Nov 2012 02:26:10 +0000] INFO: Ran execute[restart-monit] successfully
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: execute[start-backup-inittab] sending run action to execute[cleanup-backup-inittab] (delayed)
[default] [Fri, 16 Nov 2012 02:26:10 +0000] INFO: Ran execute[cleanup-backup-inittab] successfully
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: execute[noop] sending run action to execute[restart-atd] (delayed)
[default] [Fri, 16 Nov 2012 02:26:10 +0000] INFO: Ran execute[restart-atd] successfully
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: execute[start-backup-inittab] sending run action to execute[cleanup-backup-inittab] (delayed)
[default] [Fri, 16 Nov 2012 02:26:10 +0000] INFO: Ran execute[cleanup-backup-inittab] successfully
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: execute[start-backup-inittab] sending run action to execute[rotate-backup-inittab] (delayed)
[default] [Fri, 16 Nov 2012 02:26:10 +0000] INFO: Ran execute[rotate-backup-inittab] successfully
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: template[/etc/conf.d/postgresql-9.1] sending reload action to service[postgresql-9.1] (delayed)
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: template[/db/postgresql/9.1/data/postgresql.conf] sending reload action to service[postgresql-9.1] (delayed)
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: template[/db/postgresql/9.1/data/pg_hba.conf] sending reload action to service[postgresql-9.1] (delayed)
[default] [Fri, 16 Nov 2012 02:26:10 +0000] INFO: service[postgresql-9.1]: reloaded successfully
[Fri, 16 Nov 2012 02:26:10 +0000] INFO: Chef Run complete in 261.187277 seconds
[default] [Fri, 16 Nov 2012 02:26:11 +0000] INFO: Starting Chef Solo Run
[default] [Fri, 16 Nov 2012 02:26:11 +0000] INFO: Chef Run complete in 0.312088 seconds
[ey-local] Creating bundle if Gemfile|Gemfile.lock is present ...
[default] Fetching gem metadata from https://rubygems.org/
[default] .
[default] .
[default] .
[default] .
[default] .
[default] .
[default] .
[default] .
[default] Error Bundler::HTTPError during request to dependency API
Fetching full source index from https://rubygems.org/
[default] Installing rake (10.0.0)
[default] 
[default] Installing i18n (0.6.1)
[default] 
[default] Installing multi_json (1.3.7)
[default] 
[default] Installing activesupport (3.2.9)
[default] 
[default] Installing builder (3.0.4)
[default] 
[default] Installing activemodel (3.2.9)
[default] 
[default] Installing erubis (2.7.0)
[default] 
[default] Installing journey (1.0.4)
[default] 
[default] Using rack (1.4.1)
[default] 
[default] Installing rack-cache (1.2)
[default] 
[default] Installing rack-test (0.6.2)
[default] 
[default] Installing hike (1.2.1)
[default] 
[default] Installing tilt (1.3.3)
[default] 
[default] Installing sprockets (2.2.1)
[default] 
[default] Installing actionpack (3.2.9)
[default] 
[default] Installing mime-types (1.19)
[default] 
[default] Installing polyglot (0.3.3)
[default] 
[default] Installing treetop (1.4.12)
[default] 
[default] Installing mail (2.4.4)
[default] 
[default] Installing actionmailer (3.2.9)
[default] 
[default] Installing arel (3.0.2)
[default] 
[default] Installing tzinfo (0.3.35)
[default] 
[default] Installing activerecord (3.2.9)
[default] 
[default] Installing activeresource (3.2.9)
[default] 
[default] Installing addressable (2.3.2)
[default] 
[default] Installing bcrypt-ruby (3.0.1)
[default] with native extensions
[default] 
[default] Installing bootstrap-sass (2.1.1.0)
[default] 
[default] Installing cancan (1.6.8)
[default] 
[default] Installing nokogiri (1.5.5)
[default] with native extensions
[default] 
[default] Installing ffi (1.1.5)
[default] with native extensions
[default] 
[default] Installing childprocess (0.3.6)
[default] 
[default] Installing libwebsocket (0.1.5)
[default] 
[default] Installing rubyzip (0.9.9)
[default] 
[default] Installing selenium-webdriver (2.26.0)
[default] 
[default] Installing xpath (0.1.4)
[default] 
[default] Installing capybara (1.1.3)
[default] 
[default] Installing coffee-script-source (1.4.0)
[default] 
[default] Installing execjs (1.4.0)
[default] 
[default] Installing coffee-script (2.2.0)
[default] 
[default] Installing rack-ssl (1.3.2)
[default] 
[default] Installing json (1.7.5)
[default] with native extensions
[default] 
[default] Installing rdoc (3.12)
[default] 
[default] Installing thor (0.16.0)
[default] 
[default] Installing railties (3.2.9)
[default] 
[default] Installing coffee-rails (3.2.2)
[default] 
[default] Installing diff-lcs (1.1.3)
[default] 
[default] Installing gherkin (2.11.5)
[default] with native extensions
[default] 
[default] Installing cucumber (1.2.1)
[default] 
[default] Installing cucumber-rails (1.3.0)
[default] 
[default] Installing database_cleaner (0.9.1)
[default] 
[default] Installing orm_adapter (0.4.0)
[default] 
[default] Installing warden (1.2.1)
[default] 
[default] Installing devise (2.1.2)
[default] 
[default] Installing launchy (2.1.2)
[default] 
[default] Installing email_spec (1.4.0)
[default] 
[default] Installing factory_girl (4.1.0)
[default] 
[default] Installing factory_girl_rails (4.1.0)
[default] 
[default] Installing haml (3.1.7)
[default] 
[default] Installing haml-rails (0.3.5)
[default] 
[default] Installing hpricot (0.8.6)
[default] with native extensions
[default] 
[default] Installing jquery-rails (2.1.3)
[default] 
[default] Installing kgio (2.7.4)
[default] with native extensions
[default] 
[default] Installing quiet_assets (1.0.1)
[default] 
[default] Using bundler (1.1.0)
[default] Installing rails (3.2.9)
[default] 
[default] Installing raindrops (0.10.0)
[default] with native extensions
[default] 
[default] Installing rolify (3.2.0)
[default] 
[default] Installing rspec-core (2.12.0)
[default] 
[default] Installing rspec-expectations (2.12.0)
[default] 
[default] Installing rspec-mocks (2.12.0)
[default] 
[default] Installing rspec-rails (2.12.0)
[default] 
[default] Installing sexp_processor (4.1.2)
[default] 
[default] Installing ruby_parser (3.0.1)
[default] 
[default] Installing sass (3.2.3)
[default] 
[default] Installing sass-rails (3.2.5)
[default] 
[default] Installing simple_form (2.0.4)
[default] 
[default] Installing sqlite3 (1.3.6)
[default] with native extensions
[default] 
[default] Installing uglifier (1.3.0)
[default] 
[default] Installing unicorn (4.4.0)
[default] with native extensions
[default] 
[default] Your bundle is complete! Use `bundle show [gemname]` to see where a bundled gem is installed.
[default] Post-install message from rdoc:
Depending on your version of ruby, you may need to install ruby rdoc/ri data:

<= 1.8.6 : unsupported
 = 1.8.7 : gem install rdoc-data; rdoc-data --install
 = 1.9.1 : gem install rdoc-data; rdoc-data --install
>= 1.9.2 : nothing to do! Yay!

[ey-local] Creating and migrating the db, rake tasks permitting ...
[default] ==  DeviseCreateUsers: migrating ==============================================
-- create_table(:users)
   -> 0.0210s
-- add_index(:users, :email, {:unique=>true})
   -> 0.0007s
-- add_index(:users, :reset_password_token, {:unique=>true})
   -> 0.0003s
==  DeviseCreateUsers: migrated (0.0221s) =====================================

==  AddNameToUsers: migrating =================================================
-- add_column(:users, :name, :string)
   -> 0.0015s
==  AddNameToUsers: migrated (0.0016s) ========================================

==  RolifyCreateRoles: migrating ==============================================
-- create_table(:roles)
   -> 0.0029s
-- create_table(:users_roles, {:id=>false})
   -> 0.0003s
-- add_index(:roles, :name)
   -> 0.0002s
-- add_index(:roles, [:name, :resource_type, :resource_id])
   -> 0.0003s
-- add_index(:users_roles, [:user_id, :role_id])
   -> 0.0004s
==  RolifyCreateRoles: migrated (0.0041s) =====================================
[ey-local] Precompiling assets ...
[default] /usr/bin/ruby19 /usr/bin/rake assets:precompile:all RAILS_ENV=production RAILS_GROUPS=assets
[default] 
[default] * Checking nginx' configuration ...
[default] nginx: [warn] duplicate MIME type "text/html" in /etc/nginx/nginx.conf:34
[default] nginx: the configuration file /etc/nginx/nginx.conf syntax is ok
[default] nginx: configuration file /etc/nginx/nginx.conf test is successful
[default] [ ok ]
 * Stopping nginx ...
[default] [ ok ]
[default] * Checking nginx' configuration ...
[default] nginx: [warn] duplicate MIME type "text/html" in /etc/nginx/nginx.conf:34
[default] nginx: the configuration file /etc/nginx/nginx.conf syntax is ok
[default] nginx: configuration file /etc/nginx/nginx.conf test is successful
[default] [ ok ]
 * Starting nginx ...
[default] nginx: [warn] duplicate MIME type "text/html" in /etc/nginx/nginx.conf:34
[default] [ ok ]
➜  testcbs git:(master) ✗