Provisioning output

gistfile1.sh

[default] Running provisioner: Vagrant::Provisioners::ChefSolo...
[default] Generating chef JSON and uploading...
[default] Running chef-solo...
stdin: is not a tty
[Wed, 23 May 2012 11:58:42 +0200] INFO: *** Chef 0.10.8 ***
[Wed, 23 May 2012 11:58:42 +0200] INFO: Setting the run_list to ["recipe[vagrant_main]"] from JSON
[Wed, 23 May 2012 11:58:42 +0200] INFO: Run List is [recipe[vagrant_main]]
[Wed, 23 May 2012 11:58:42 +0200] INFO: Run List expands to [vagrant_main]
[Wed, 23 May 2012 11:58:42 +0200] INFO: Starting Chef Run for lucid32
[Wed, 23 May 2012 11:58:42 +0200] INFO: Running start handlers
[Wed, 23 May 2012 11:58:42 +0200] INFO: Start handlers complete.
[Wed, 23 May 2012 11:58:43 +0200] INFO: Processing execute[apt-get update] action run (apt::default line 20)
[Wed, 23 May 2012 11:58:43 +0200] INFO: execute[apt-get update] sh(apt-get update)
[Wed, 23 May 2012 12:08:59 +0200] INFO: execute[apt-get update] ran successfully
[Wed, 23 May 2012 12:08:59 +0200] INFO: Processing package[mysql-devel] action install (mysql::client line 20)
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing package[mysql-client] action install (mysql::client line 43)
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing package[build-essential] action install (mysql::client line 60)
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing gem_package[mysql] action install (mysql::client line 66)
[Wed, 23 May 2012 12:09:00 +0200] WARN: Could not find previously defined grants.sql resource
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing execute[apt-get update] action nothing (apt::default line 20)
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing directory[/var/cache/local] action create (apt::default line 27)
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing directory[/var/cache/local/preseeding] action create (apt::default line 27)
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing package[apache2] action install (apache2::default line 20)
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing service[apache2] action enable (apache2::default line 30)
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing directory[/etc/apache2/ssl] action create (apache2::default line 114)
[Wed, 23 May 2012 12:09:00 +0200] INFO: Processing template[apache2.conf] action create (apache2::default line 121)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[security] action create (apache2::default line 135)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[charset] action create (apache2::default line 145)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/etc/apache2/ports.conf] action create (apache2::default line 155)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/etc/apache2/sites-available/default] action create (apache2::default line 164)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/etc/apache2/mods-available/status.conf] action create (apache2::mod_status line 21)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod status] action run (apache2::mod_status line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/etc/apache2/mods-available/alias.conf] action create (apache2::mod_alias line 21)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod alias] action run (apache2::mod_alias line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod auth_basic] action run (apache2::mod_auth_basic line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod authn_file] action run (apache2::mod_authn_file line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod authz_default] action run (apache2::mod_authz_default line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod authz_groupfile] action run (apache2::mod_authz_groupfile line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod authz_host] action run (apache2::mod_authz_host line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod authz_user] action run (apache2::mod_authz_user line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/etc/apache2/mods-available/autoindex.conf] action create (apache2::mod_autoindex line 21)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod autoindex] action run (apache2::mod_autoindex line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/etc/apache2/mods-available/dir.conf] action create (apache2::mod_dir line 21)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod dir] action run (apache2::mod_dir line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod env] action run (apache2::mod_env line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/etc/apache2/mods-available/mime.conf] action create (apache2::mod_mime line 21)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod mime] action run (apache2::mod_mime line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/etc/apache2/mods-available/negotiation.conf] action create (apache2::mod_negotiation line 21)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod negotiation] action run (apache2::mod_negotiation line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/etc/apache2/mods-available/setenvif.conf] action create (apache2::mod_setenvif line 21)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[a2enmod setenvif] action run (apache2::mod_setenvif line 28)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing service[apache2] action start (apache2::default line 191)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing package[mysql-devel] action nothing (mysql::client line 20)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing package[mysql-client] action nothing (mysql::client line 43)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing package[build-essential] action nothing (mysql::client line 60)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing gem_package[mysql] action nothing (mysql::client line 66)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing directory[/var/cache/local/preseeding] action create (mysql::server line 25)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing execute[preseed mysql-server] action nothing (mysql::server line 32)
[Wed, 23 May 2012 12:09:01 +0200] INFO: Processing template[/var/cache/local/preseeding/mysql-server.seed] action create (mysql::server line 37)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing template[/etc/mysql/debian.cnf] action create (mysql::server line 44)
[Wed, 23 May 2012 12:09:02 +0200] INFO: template[/etc/mysql/debian.cnf] backed up to /var/chef/backup/etc/mysql/debian.cnf.chef-20120523120902
[Wed, 23 May 2012 12:09:02 +0200] INFO: template[/etc/mysql/debian.cnf] updated content
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[mysql-server] action install (mysql::server line 52)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing service[mysql] action nothing (mysql::server line 56)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing template[/etc/mysql/my.cnf] action create (mysql::server line 67)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing template[/etc/mysql/grants.sql] action create (mysql::server line 95)
[Wed, 23 May 2012 12:09:02 +0200] INFO: template[/etc/mysql/grants.sql] backed up to /var/chef/backup/etc/mysql/grants.sql.chef-20120523120902
[Wed, 23 May 2012 12:09:02 +0200] INFO: template[/etc/mysql/grants.sql] updated content
[Wed, 23 May 2012 12:09:02 +0200] INFO: template[/etc/mysql/grants.sql] sending run action to execute[mysql-install-privileges] (immediate)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing execute[mysql-install-privileges] action run (mysql::server line 105)
[Wed, 23 May 2012 12:09:02 +0200] INFO: execute[mysql-install-privileges] sh(/usr/bin/mysql -u root -proot < /etc/mysql/grants.sql)
[Wed, 23 May 2012 12:09:02 +0200] INFO: execute[mysql-install-privileges] ran successfully
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing execute[mysql-install-privileges] action nothing (mysql::server line 105)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[php5-mysql] action upgrade (php::module_mysql line 28)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[php5-ldap] action upgrade (php::module_ldap line 27)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[php5-memcache] action upgrade (php::module_memcache line 28)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[php5-gd] action upgrade (php::module_gd line 28)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[php5-pgsql] action upgrade (php::module_pgsql line 28)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[php-pear] action install (php::pear line 21)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing cookbook_file[/etc/php5/apache2/php.ini] action create (php::php5 line 29)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[php5] action upgrade (php::php5 line 45)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[php5-cli] action upgrade (php::php5 line 45)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[smarty] action upgrade (php::php5 line 45)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[debconf] action install (vagrant_main::default line 8)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[php5-xdebug] action install (vagrant_main::default line 8)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing package[subversion] action install (vagrant_main::default line 8)
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing cookbook_file[/tmp/phpmyadmin.deb.conf] action create (vagrant_main::default line 12)
[Wed, 23 May 2012 12:09:02 +0200] INFO: cookbook_file[/tmp/phpmyadmin.deb.conf] created file /tmp/phpmyadmin.deb.conf
[Wed, 23 May 2012 12:09:02 +0200] INFO: Processing bash[debconf_for_phpmyadmin] action run (vagrant_main::default line 15)
[Wed, 23 May 2012 12:09:02 +0200] INFO: bash[debconf_for_phpmyadmin] sh("bash"  "/tmp/chef-script20120523-1013-1v541g8-0")
[Wed, 23 May 2012 12:09:03 +0200] INFO: bash[debconf_for_phpmyadmin] ran successfully
[Wed, 23 May 2012 12:09:03 +0200] INFO: Processing package[phpmyadmin] action install (vagrant_main::default line 18)
[Wed, 23 May 2012 12:09:03 +0200] INFO: Processing execute[a2enmod rewrite] action run (apache2::mod_rewrite line 28)
[Wed, 23 May 2012 12:09:03 +0200] INFO: Processing template[/etc/apache2/mods-available/deflate.conf] action create (apache2::mod_deflate line 21)
[Wed, 23 May 2012 12:09:03 +0200] INFO: Processing execute[a2enmod deflate] action run (apache2::mod_deflate line 28)
[Wed, 23 May 2012 12:09:03 +0200] INFO: Processing execute[a2enmod headers] action run (apache2::mod_headers line 28)
[Wed, 23 May 2012 12:09:03 +0200] INFO: Processing template[/etc/apache2/sites-available/dev-site.conf] action create (vagrant_main::default line 29)
[Wed, 23 May 2012 12:09:03 +0200] INFO: Processing execute[a2ensite dev-site.conf] action run (vagrant_main::default line 24)
[Wed, 23 May 2012 12:09:03 +0200] INFO: Processing bash[info_in_etc_hosts] action run (vagrant_main::default line 36)
[Wed, 23 May 2012 12:09:03 +0200] INFO: bash[info_in_etc_hosts] sh("bash"  "/tmp/chef-script20120523-1013-1077q3e-0")
[Wed, 23 May 2012 12:09:03 +0200] INFO: bash[info_in_etc_hosts] ran successfully
[Wed, 23 May 2012 12:09:03 +0200] INFO: Processing subversion[Webgrind] action sync (vagrant_main::default line 41)
[Wed, 23 May 2012 12:09:15 +0200] INFO: Processing execute[add-admin-user] action run (vagrant_main::default line 49)
[Wed, 23 May 2012 12:09:15 +0200] INFO: execute[add-admin-user] sh(/usr/bin/mysql -u root -proot -e "CREATE USER 'myadmin'@'localhost' IDENTIFIED BY 'myadmin';G
RANT ALL PRIVILEGES ON *.* TO 'myadmin'@'localhost' WITH GRANT OPTION;CREATE USER 'myadmin'@'%' IDENTIFIED BY 'myadmin';GRANT ALL PRIVILEGES ON *.* TO 'myadmin'
@'%' WITH GRANT OPTION;" mysql)
[Wed, 23 May 2012 12:09:15 +0200] ERROR: execute[add-admin-user] (vagrant_main::default line 49) had an error: Expected process to exit with [0], but received '
1'
---- Begin output of /usr/bin/mysql -u root -proot -e "CREATE USER 'myadmin'@'localhost' IDENTIFIED BY 'myadmin';GRANT ALL PRIVILEGES ON *.* TO 'myadmin'@'local
host' WITH GRANT OPTION;CREATE USER 'myadmin'@'%' IDENTIFIED BY 'myadmin';GRANT ALL PRIVILEGES ON *.* TO 'myadmin'@'%' WITH GRANT OPTION;" mysql ----
STDOUT:
STDERR: ERROR 1396 (HY000) at line 1: Operation CREATE USER failed for 'myadmin'@'localhost'
---- End output of /usr/bin/mysql -u root -proot -e "CREATE USER 'myadmin'@'localhost' IDENTIFIED BY 'myadmin';GRANT ALL PRIVILEGES ON *.* TO 'myadmin'@'localho
st' WITH GRANT OPTION;CREATE USER 'myadmin'@'%' IDENTIFIED BY 'myadmin';GRANT ALL PRIVILEGES ON *.* TO 'myadmin'@'%' WITH GRANT OPTION;" mysql ----
Ran /usr/bin/mysql -u root -proot -e "CREATE USER 'myadmin'@'localhost' IDENTIFIED BY 'myadmin';GRANT ALL PRIVILEGES ON *.* TO 'myadmin'@'localhost' WITH GRANT
OPTION;CREATE USER 'myadmin'@'%' IDENTIFIED BY 'myadmin';GRANT ALL PRIVILEGES ON *.* TO 'myadmin'@'%' WITH GRANT OPTION;" mysql returned 1
[Wed, 23 May 2012 12:09:15 +0200] INFO: Chef Run complete in 632.618582 seconds
[Wed, 23 May 2012 12:09:15 +0200] INFO: Running report handlers
[Wed, 23 May 2012 12:09:15 +0200] INFO: Report handlers complete