Started by timer Building in workspace /var/lib/jenkins/jobs/D_aegir3-debian-test-unstable/workspace > git rev-parse --is-inside-work-tree # timeout=10 Fetching changes from the remote Git repository > git config remote.origin.url http://git.drupal.org/project/puppet-aegir.git # timeout=10 Fetching upstream changes from http://git.drupal.org/project/puppet-aegir.git > git --version # timeout=10 > git -c core.askpass=true fetch --tags --progress http://git.drupal.org/project/puppet-aegir.git +refs/heads/*:refs/remotes/origin/* > git rev-parse origin/1.0.x^{commit} # timeout=10 Checking out Revision c460c311fd05e5f658f78b1b77c0a6a45b4ff58e (origin/1.0.x) > git config core.sparsecheckout # timeout=10 > git checkout -f c460c311fd05e5f658f78b1b77c0a6a45b4ff58e > git rev-list c460c311fd05e5f658f78b1b77c0a6a45b4ff58e # timeout=10 > git tag -a -f -m Jenkins Build #917 jenkins-D_aegir3-debian-test-unstable-917 # timeout=10 [workspace] $ /bin/sh -xe /tmp/hudson5132748844218674039.sh + ./tests/functional_tests/aegir3-deb-unstable-no-puppet/setup.sh ==> default: VM not created. Moving on... [workspace] $ /bin/sh -xe /tmp/hudson864699335130783563.sh + ./tests/functional_tests/aegir3-deb-unstable-no-puppet/run-tests.sh Bringing machine 'default' up with 'virtualbox' provider... ==> default: Importing base box 'debian/jessie64'... Progress: 10% Progress: 20% Progress: 40% Progress: 50% Progress: 60% Progress: 70% Progress: 80% Progress: 90% ==> default: Matching MAC address for NAT networking... ==> default: Checking if box 'debian/jessie64' is up to date... ==> default: A newer version of the box 'debian/jessie64' is available! You currently ==> default: have version '8.2.0'. The latest is version '8.7.0'. Run ==> default: `vagrant box update` to update. ==> default: Setting the name of the VM: workspace_default_1487159117559_62331 ==> default: Fixed port collision for 22 => 2222. Now on port 2200. ==> default: Clearing any previously set network interfaces... ==> default: Preparing network interfaces based on configuration... default: Adapter 1: nat ==> default: Forwarding ports... default: 22 => 2200 (adapter 1) ==> default: Booting VM... ==> default: Waiting for machine to boot. This may take a few minutes... default: SSH address: 127.0.0.1:2200 default: SSH username: vagrant default: SSH auth method: private key default: Warning: Connection timeout. Retrying... ==> default: Machine booted and ready! ==> default: Checking for guest additions in VM... ==> default: Setting hostname... ==> default: Mounting shared folders... default: /vagrant => /var/lib/jenkins/jobs/D_aegir3-debian-test-unstable/workspace default: /var/cache/apt/archives => /var/lib/jenkins/jobs/D_aegir3-debian-test-unstable/workspace/tmp/apt/cache ==> default: Running provisioner: shell... default: Running: inline script ==> default: stdin: is not a tty ==> default: deb http://debian.aegirproject.org unstable main ==> default: ==> default: ==> default: % ==> default: ==> default: T ==> default: o ==> default: t ==> default: a ==> default: l ==> default: ==> default: ==> default: ==> default: ==> default: % ==> default: ==> default: R ==> default: e ==> default: ceived % Xferd Average Speed Time Time Time Current ==> default: Dload Upload Total Spent Left Speed ==> default: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 ==> default: 100 3884 100 3884 0 0 30301 0 --:--:-- --:--:-- --:--:-- 30343 ==> default: OK ==> default: Get:1 http://debian.aegirproject.org unstable InRelease [3,142 B] ==> default: Get:2 http://debian.aegirproject.org unstable/main amd64 Packages [4,511 B] ==> default: Ign http://debian.aegirproject.org unstable/main Translation-en_US ==> default: Ign http://debian.aegirproject.org unstable/main Translation-en ==> default: Get:3 http://security.debian.org jessie/updates InRelease [63.1 kB] ==> default: Ign http://httpredir.debian.org jessie InRelease ==> default: Get:4 http://security.debian.org jessie/updates/main Sources [188 kB] ==> default: Get:5 http://httpredir.debian.org jessie Release.gpg [2,373 B] ==> default: Get:6 http://httpredir.debian.org jessie Release [148 kB] ==> default: Get:7 http://security.debian.org jessie/updates/main amd64 Packages [345 kB] ==> default: Get:8 http://httpredir.debian.org jessie/main Sources [7,056 kB] ==> default: Get:9 http://security.debian.org jessie/updates/main Translation-en [183 kB] ==> default: Get:10 http://httpredir.debian.org jessie/main amd64 Packages [6,776 kB] ==> default: Get:11 http://httpredir.debian.org jessie/main Translation-en [4,582 kB] ==> default: Fetched 19.4 MB in 1min 55s (168 kB/s) ==> default: Reading package lists... ==> default: Reading package lists... ==> default: Building dependency tree... ==> default: Reading state information... ==> default: The following extra packages will be installed: ==> default: aegir3-hostmaster aegir3-provision apache2 apache2-bin apache2-data ==> default: apache2-utils git git-core git-man libaio1 libapache2-mod-php5 libapr1 ==> default: libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap libdbd-mysql-perl ==> default: libdbi-perl liberror-perl libgd3 libhtml-template-perl liblua5.1-0 ==> default: libmysqlclient18 libonig2 libqdbm14 libterm-readkey-perl libvpx1 libxpm4 ==> default: mysql-client-5.5 mysql-common mysql-server-5.5 mysql-server-core-5.5 perl ==> default: perl-base perl-modules php5 php5-cli php5-common php5-gd php5-json ==> default: php5-mysql php5-readline rsync ssl-cert ==> default: Suggested packages: ==> default: apache2-doc apache2-suexec-pristine apache2-suexec-custom git-daemon-run ==> default: git-daemon-sysvinit git-doc git-el git-email git-gui gitk gitweb git-arch ==> default: git-cvs git-mediawiki git-svn php-pear libclone-perl libmldbm-perl ==> default: libnet-daemon-perl libsql-statement-perl libgd-tools libipc-sharedcache-perl ==> default: tinyca perl-doc libterm-readline-gnu-perl libterm-readline-perl-perl ==> default: libb-lint-perl libcpanplus-dist-build-perl libcpanplus-perl ==> default: libfile-checktree-perl libobject-accessor-perl php5-user-cache ==> default: openssl-blacklist ==> default: The following NEW packages will be installed: ==> default: aegir3 aegir3-hostmaster aegir3-provision apache2 apache2-bin apache2-data ==> default: apache2-utils git git-core git-man libaio1 libapache2-mod-php5 libapr1 ==> default: libaprutil1 libaprutil1-dbd-sqlite3 libaprutil1-ldap libdbd-mysql-perl ==> default: libdbi-perl liberror-perl libgd3 libhtml-template-perl liblua5.1-0 ==> default: libmysqlclient18 libonig2 libqdbm14 libterm-readkey-perl libvpx1 libxpm4 ==> default: mysql-client-5.5 mysql-common mysql-server mysql-server-5.5 ==> default: mysql-server-core-5.5 php5 php5-cli php5-common php5-gd php5-json php5-mysql ==> default: php5-readline rsync ssl-cert ==> default: The following packages will be upgraded: ==> default: perl perl-base perl-modules ==> default: Reading changelogs... ==> default: Extracting templates from packages: 66% ==> default: Extracting templates from packages: 100% ==> default: Preconfiguring packages ... ==> default: + ==> default: + ==> default: su ==> default: -s ==> default: /bin/sh ==> default: aegir ==> default: grep ==> default: -c ==> default: -q ==> default: drush @hostmaster status ==> default: Drupal bootstrap.*Successful ==> default: + db_input medium aegir/email ==> default: + _db_cmd INPUT medium aegir/email ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT medium aegir/email ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_input high aegir/site ==> default: + _db_cmd INPUT high aegir/site ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT high aegir/site ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_get aegir/email ==> default: + _db_cmd GET aegir/email ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/email ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET= aegir@example.com ==> default: + return 0 ==> default: + [ -z aegir@example.com ] ==> default: + db_get aegir/site ==> default: + _db_cmd GET aegir/site ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/site ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET= aegir.example.com ==> default: + return 0 ==> default: + [ -z aegir.example.com ] ==> default: + db_input low aegir/makefile ==> default: + _db_cmd INPUT low aegir/makefile ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT low aegir/makefile ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_input low aegir/working-copy ==> default: + _db_cmd INPUT low aegir/working-copy ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT low aegir/working-copy ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_input low aegir/db_host ==> default: + _db_cmd INPUT low aegir/db_host ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT low aegir/db_host ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_input low aegir/db_user ==> default: + _db_cmd INPUT low aegir/db_user ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT low aegir/db_user ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_fset aegir/db_password seen false ==> default: + _db_cmd FSET aegir/db_password seen false ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n FSET aegir/db_password seen false ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=false ==> default: + return 0 ==> default: + db_input high aegir/db_password ==> default: + _db_cmd INPUT high aegir/db_password ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT high aegir/db_password ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + ==> default: wc_httpd_installed ==> default: apache2 ==> default: nginx ==> default: + ==> default: local ==> default: httpds ==> default: + ==> default: [ ==> default: apache2 nginx ==> default: ] ==> default: + ==> default: httpds=apache2 nginx ==> default: + ==> default: test ==> default: -x ==> default: /usr/sbin/apache2 ==> default: + ==> default: test ==> default: -x ==> default: /usr/sbin/nginx ==> default: + default_servers= ==> default: + ==> default: sed ==> default: -e ==> default: s/[[:space:]][[:space:]]*/, /g ==> default: + ==> default: echo ==> default: + server_list= ==> default: + db_fget aegir/webserver seen ==> default: + _db_cmd FGET aegir/webserver seen ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n FGET aegir/webserver seen ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=false ==> default: + return 0 ==> default: + [ false = false ] ==> default: + db_set aegir/webserver ==> default: + _db_cmd SET aegir/webserver ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n SET aegir/webserver ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=value set ==> default: + return 0 ==> default: + db_input low aegir/webserver ==> default: + _db_cmd INPUT low aegir/webserver ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT low aegir/webserver ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_go ==> default: + _db_cmd GO ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GO ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=ok ==> default: + return 0 ==> default: 3 upgraded, 42 newly installed, 0 to remove and 141 not upgraded. ==> default: Need to get 0 B/28.5 MB of archives. ==> default: After this operation, 153 MB of additional disk space will be used. ==> default: (Reading database ... ==> default: (Reading database ... 5% ==> default: (Reading database ... 10% ==> default: (Reading database ... 15% ==> default: (Reading database ... 20% ==> default: (Reading database ... 25% ==> default: (Reading database ... 30% ==> default: (Reading database ... 35% ==> default: (Reading database ... 40% ==> default: (Reading database ... 45% ==> default: (Reading database ... 50% ==> default: (Reading database ... 55% ==> default: (Reading database ... 60% ==> default: (Reading database ... 65% ==> default: (Reading database ... 70% ==> default: (Reading database ... 75% ==> default: (Reading database ... 80% ==> default: (Reading database ... 85% ==> default: (Reading database ... 90% ==> default: (Reading database ... 95% ==> default: (Reading database ... 100% ==> default: (Reading database ... ==> default: 35750 files and directories currently installed.) ==> default: Preparing to unpack .../perl_5.20.2-3+deb8u6_amd64.deb ... ==> default: Unpacking perl (5.20.2-3+deb8u6) over (5.20.2-3+deb8u1) ... ==> default: Preparing to unpack .../perl-base_5.20.2-3+deb8u6_amd64.deb ... ==> default: Unpacking perl-base (5.20.2-3+deb8u6) over (5.20.2-3+deb8u1) ... ==> default: Processing triggers for man-db (2.7.0.2-5) ... ==> default: Setting up perl-base (5.20.2-3+deb8u6) ... ==> default: (Reading database ... ==> default: (Reading database ... 5% ==> default: (Reading database ... 10% ==> default: (Reading database ... 15% ==> default: (Reading database ... 20% ==> default: (Reading database ... 25% ==> default: (Reading database ... 30% ==> default: (Reading database ... 35% ==> default: (Reading database ... 40% ==> default: (Reading database ... 45% ==> default: (Reading database ... 50% ==> default: (Reading database ... 55% ==> default: (Reading database ... 60% ==> default: (Reading database ... 65% ==> default: (Reading database ... 70% ==> default: (Reading database ... 75% ==> default: (Reading database ... 80% ==> default: (Reading database ... 85% ==> default: (Reading database ... 90% ==> default: (Reading database ... 95% ==> default: (Reading database ... 100% ==> default: (Reading database ... ==> default: 35751 files and directories currently installed.) ==> default: Preparing to unpack .../perl-modules_5.20.2-3+deb8u6_all.deb ... ==> default: Unpacking perl-modules (5.20.2-3+deb8u6) over (5.20.2-3+deb8u1) ... ==> default: Selecting previously unselected package libapr1:amd64. ==> default: Preparing to unpack .../libapr1_1.5.1-3_amd64.deb ... ==> default: Unpacking libapr1:amd64 (1.5.1-3) ... ==> default: Selecting previously unselected package libaprutil1:amd64. ==> default: Preparing to unpack .../libaprutil1_1.5.4-1_amd64.deb ... ==> default: Unpacking libaprutil1:amd64 (1.5.4-1) ... ==> default: Selecting previously unselected package libaprutil1-dbd-sqlite3:amd64. ==> default: Preparing to unpack .../libaprutil1-dbd-sqlite3_1.5.4-1_amd64.deb ... ==> default: Unpacking libaprutil1-dbd-sqlite3:amd64 (1.5.4-1) ... ==> default: Selecting previously unselected package libaprutil1-ldap:amd64. ==> default: Preparing to unpack .../libaprutil1-ldap_1.5.4-1_amd64.deb ... ==> default: Unpacking libaprutil1-ldap:amd64 (1.5.4-1) ... ==> default: Selecting previously unselected package liblua5.1-0:amd64. ==> default: Preparing to unpack .../liblua5.1-0_5.1.5-7.1_amd64.deb ... ==> default: Unpacking liblua5.1-0:amd64 (5.1.5-7.1) ... ==> default: Selecting previously unselected package apache2-bin. ==> default: Preparing to unpack .../apache2-bin_2.4.10-10+deb8u7_amd64.deb ... ==> default: Unpacking apache2-bin (2.4.10-10+deb8u7) ... ==> default: Selecting previously unselected package apache2-utils. ==> default: Preparing to unpack .../apache2-utils_2.4.10-10+deb8u7_amd64.deb ... ==> default: Unpacking apache2-utils (2.4.10-10+deb8u7) ... ==> default: Selecting previously unselected package apache2-data. ==> default: Preparing to unpack .../apache2-data_2.4.10-10+deb8u7_all.deb ... ==> default: Unpacking apache2-data (2.4.10-10+deb8u7) ... ==> default: Selecting previously unselected package apache2. ==> default: Preparing to unpack .../apache2_2.4.10-10+deb8u7_amd64.deb ... ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper symlink_to_dir in preinst of apache2 ==> default: DEBUG: dpkg-maintscript-helper: SYMLINK=/usr/share/doc/apache2 -> apache2.2-common PACKAGE=apache2:amd64 LASTVERSION=2.4.1-1~ ACTION=install PARAM= ==> default: Unpacking apache2 (2.4.10-10+deb8u7) ... ==> default: Selecting previously unselected package libaio1:amd64. ==> default: Preparing to unpack .../libaio1_0.3.110-1_amd64.deb ... ==> default: Unpacking libaio1:amd64 (0.3.110-1) ... ==> default: Selecting previously unselected package libvpx1:amd64. ==> default: Preparing to unpack .../libvpx1_1.3.0-3_amd64.deb ... ==> default: Unpacking libvpx1:amd64 (1.3.0-3) ... ==> default: Selecting previously unselected package libxpm4:amd64. ==> default: Preparing to unpack .../libxpm4_1%3a3.5.12-0+deb8u1_amd64.deb ... ==> default: Unpacking libxpm4:amd64 (1:3.5.12-0+deb8u1) ... ==> default: Selecting previously unselected package libgd3:amd64. ==> default: Preparing to unpack .../libgd3_2.1.0-5+deb8u9_amd64.deb ... ==> default: Unpacking libgd3:amd64 (2.1.0-5+deb8u9) ... ==> default: Selecting previously unselected package mysql-common. ==> default: Preparing to unpack .../mysql-common_5.5.54-0+deb8u1_all.deb ... ==> default: Unpacking mysql-common (5.5.54-0+deb8u1) ... ==> default: Selecting previously unselected package libmysqlclient18:amd64. ==> default: Preparing to unpack .../libmysqlclient18_5.5.54-0+deb8u1_amd64.deb ... ==> default: Unpacking libmysqlclient18:amd64 (5.5.54-0+deb8u1) ... ==> default: Selecting previously unselected package libonig2:amd64. ==> default: Preparing to unpack .../libonig2_5.9.5-3.2_amd64.deb ... ==> default: Unpacking libonig2:amd64 (5.9.5-3.2) ... ==> default: Selecting previously unselected package libdbi-perl. ==> default: Preparing to unpack .../libdbi-perl_1.631-3+b1_amd64.deb ... ==> default: Unpacking libdbi-perl (1.631-3+b1) ... ==> default: Selecting previously unselected package libdbd-mysql-perl. ==> default: Preparing to unpack .../libdbd-mysql-perl_4.028-2+deb8u2_amd64.deb ... ==> default: Unpacking libdbd-mysql-perl (4.028-2+deb8u2) ... ==> default: Selecting previously unselected package libterm-readkey-perl. ==> default: Preparing to unpack .../libterm-readkey-perl_2.32-1+b1_amd64.deb ... ==> default: Unpacking libterm-readkey-perl (2.32-1+b1) ... ==> default: Selecting previously unselected package mysql-client-5.5. ==> default: Preparing to unpack .../mysql-client-5.5_5.5.54-0+deb8u1_amd64.deb ... ==> default: Unpacking mysql-client-5.5 (5.5.54-0+deb8u1) ... ==> default: Selecting previously unselected package mysql-server-core-5.5. ==> default: Preparing to unpack .../mysql-server-core-5.5_5.5.54-0+deb8u1_amd64.deb ... ==> default: Unpacking mysql-server-core-5.5 (5.5.54-0+deb8u1) ... ==> default: Processing triggers for man-db (2.7.0.2-5) ... ==> default: Processing triggers for systemd (215-17+deb8u2) ... ==> default: Setting up mysql-common (5.5.54-0+deb8u1) ... ==> default: Selecting previously unselected package mysql-server-5.5. ==> default: (Reading database ... ==> default: (Reading database ... 5% ==> default: (Reading database ... 10% ==> default: (Reading database ... 15% ==> default: (Reading database ... 20% ==> default: (Reading database ... 25% ==> default: (Reading database ... 30% ==> default: (Reading database ... 35% ==> default: (Reading database ... 40% ==> default: (Reading database ... 45% ==> default: (Reading database ... 50% ==> default: (Reading database ... 55% ==> default: (Reading database ... 60% ==> default: (Reading database ... 65% ==> default: (Reading database ... 70% ==> default: (Reading database ... 75% ==> default: (Reading database ... 80% ==> default: (Reading database ... 85% ==> default: (Reading database ... 90% ==> default: (Reading database ... 95% ==> default: (Reading database ... 100% ==> default: (Reading database ... ==> default: 36828 files and directories currently installed.) ==> default: Preparing to unpack .../mysql-server-5.5_5.5.54-0+deb8u1_amd64.deb ... ==> default: Unpacking mysql-server-5.5 (5.5.54-0+deb8u1) ... ==> default: Selecting previously unselected package libqdbm14. ==> default: Preparing to unpack .../libqdbm14_1.8.78-5+b1_amd64.deb ... ==> default: Unpacking libqdbm14 (1.8.78-5+b1) ... ==> default: Selecting previously unselected package php5-common. ==> default: Preparing to unpack .../php5-common_5.6.30+dfsg-0+deb8u1_amd64.deb ... ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in preinst of php5-common ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/pdo.ini -> /etc/php5/mods-available/pdo.ini PACKAGE=php5-common:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=install PARAM= ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in preinst of php5-common ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/opcache.ini -> /etc/php5/mods-available/opcache.ini PACKAGE=php5-common:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=install PARAM= ==> default: Unpacking php5-common (5.6.30+dfsg-0+deb8u1) ... ==> default: Selecting previously unselected package php5-json. ==> default: Preparing to unpack .../php5-json_1.3.6-1_amd64.deb ... ==> default: Unpacking php5-json (1.3.6-1) ... ==> default: Selecting previously unselected package php5-cli. ==> default: Preparing to unpack .../php5-cli_5.6.30+dfsg-0+deb8u1_amd64.deb ... ==> default: Unpacking php5-cli (5.6.30+dfsg-0+deb8u1) ... ==> default: Selecting previously unselected package libapache2-mod-php5. ==> default: Preparing to unpack .../libapache2-mod-php5_5.6.30+dfsg-0+deb8u1_amd64.deb ... ==> default: Unpacking libapache2-mod-php5 (5.6.30+dfsg-0+deb8u1) ... ==> default: Selecting previously unselected package php5. ==> default: Preparing to unpack .../php5_5.6.30+dfsg-0+deb8u1_all.deb ... ==> default: Unpacking php5 (5.6.30+dfsg-0+deb8u1) ... ==> default: Selecting previously unselected package php5-mysql. ==> default: Preparing to unpack .../php5-mysql_5.6.30+dfsg-0+deb8u1_amd64.deb ... ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in preinst of php5-mysql ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/mysql.ini -> /etc/php5/mods-available/mysql.ini PACKAGE=php5-mysql:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=install PARAM= ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in preinst of php5-mysql ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/mysqli.ini -> /etc/php5/mods-available/mysqli.ini PACKAGE=php5-mysql:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=install PARAM= ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in preinst of php5-mysql ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/pdo_mysql.ini -> /etc/php5/mods-available/pdo_mysql.ini PACKAGE=php5-mysql:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=install PARAM= ==> default: Unpacking php5-mysql (5.6.30+dfsg-0+deb8u1) ... ==> default: Selecting previously unselected package aegir3-provision. ==> default: Preparing to unpack .../aegir3-provision_3.9+571.c7a38f8_all.deb ... ==> default: Unpacking aegir3-provision (3.9+571.c7a38f8) ... ==> default: Selecting previously unselected package php5-gd. ==> default: Preparing to unpack .../php5-gd_5.6.30+dfsg-0+deb8u1_amd64.deb ... ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in preinst of php5-gd ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/gd.ini -> /etc/php5/mods-available/gd.ini PACKAGE=php5-gd:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=install PARAM= ==> default: Unpacking php5-gd (5.6.30+dfsg-0+deb8u1) ... ==> default: Selecting previously unselected package liberror-perl. ==> default: Preparing to unpack .../liberror-perl_0.17-1.1_all.deb ... ==> default: Unpacking liberror-perl (0.17-1.1) ... ==> default: Selecting previously unselected package git-man. ==> default: Preparing to unpack .../git-man_1%3a2.1.4-2.1+deb8u2_all.deb ... ==> default: Unpacking git-man (1:2.1.4-2.1+deb8u2) ... ==> default: Selecting previously unselected package git. ==> default: Preparing to unpack .../git_1%3a2.1.4-2.1+deb8u2_amd64.deb ... ==> default: Unpacking git (1:2.1.4-2.1+deb8u2) ... ==> default: Selecting previously unselected package git-core. ==> default: Preparing to unpack .../git-core_1%3a2.1.4-2.1+deb8u2_all.deb ... ==> default: Unpacking git-core (1:2.1.4-2.1+deb8u2) ... ==> default: Selecting previously unselected package aegir3-hostmaster. ==> default: Preparing to unpack .../aegir3-hostmaster_3.9+571.c7a38f8_all.deb ... ==> default: Unpacking aegir3-hostmaster (3.9+571.c7a38f8) ... ==> default: Selecting previously unselected package aegir3. ==> default: Preparing to unpack .../aegir3_3.9+571.c7a38f8_all.deb ... ==> default: Unpacking aegir3 (3.9+571.c7a38f8) ... ==> default: Selecting previously unselected package libhtml-template-perl. ==> default: Preparing to unpack .../libhtml-template-perl_2.95-1_all.deb ... ==> default: Unpacking libhtml-template-perl (2.95-1) ... ==> default: Selecting previously unselected package mysql-server. ==> default: Preparing to unpack .../mysql-server_5.5.54-0+deb8u1_all.deb ... ==> default: Unpacking mysql-server (5.5.54-0+deb8u1) ... ==> default: Selecting previously unselected package php5-readline. ==> default: Preparing to unpack .../php5-readline_5.6.30+dfsg-0+deb8u1_amd64.deb ... ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in preinst of php5-readline ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/readline.ini -> /etc/php5/mods-available/readline.ini PACKAGE=php5-readline:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=install PARAM= ==> default: Unpacking php5-readline (5.6.30+dfsg-0+deb8u1) ... ==> default: Selecting previously unselected package rsync. ==> default: Preparing to unpack .../rsync_3.1.1-3_amd64.deb ... ==> default: Unpacking rsync (3.1.1-3) ... ==> default: Selecting previously unselected package ssl-cert. ==> default: Preparing to unpack .../ssl-cert_1.0.35_all.deb ... ==> default: Unpacking ssl-cert (1.0.35) ... ==> default: Processing triggers for systemd (215-17+deb8u2) ... ==> default: Processing triggers for man-db (2.7.0.2-5) ... ==> default: Setting up perl-modules (5.20.2-3+deb8u6) ... ==> default: Setting up perl (5.20.2-3+deb8u6) ... ==> default: Setting up libapr1:amd64 (1.5.1-3) ... ==> default: Setting up libaprutil1:amd64 (1.5.4-1) ... ==> default: Setting up libaprutil1-dbd-sqlite3:amd64 (1.5.4-1) ... ==> default: Setting up libaprutil1-ldap:amd64 (1.5.4-1) ... ==> default: Setting up liblua5.1-0:amd64 (5.1.5-7.1) ... ==> default: Setting up apache2-bin (2.4.10-10+deb8u7) ... ==> default: Setting up apache2-utils (2.4.10-10+deb8u7) ... ==> default: Setting up apache2-data (2.4.10-10+deb8u7) ... ==> default: Setting up apache2 (2.4.10-10+deb8u7) ... ==> default: Enabling module mpm_event. ==> default: Enabling module authz_core. ==> default: Enabling module authz_host. ==> default: Enabling module authn_core. ==> default: Enabling module auth_basic. ==> default: Enabling module access_compat. ==> default: Enabling module authn_file. ==> default: Enabling module authz_user. ==> default: Enabling module alias. ==> default: Enabling module dir. ==> default: Enabling module autoindex. ==> default: Enabling module env. ==> default: Enabling module mime. ==> default: Enabling module negotiation. ==> default: Enabling module setenvif. ==> default: Enabling module filter. ==> default: Enabling module deflate. ==> default: Enabling module status. ==> default: Enabling conf charset. ==> default: Enabling conf localized-error-pages. ==> default: Enabling conf other-vhosts-access-log. ==> default: Enabling conf security. ==> default: Enabling conf serve-cgi-bin. ==> default: Enabling site 000-default. ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper symlink_to_dir in postinst of apache2 ==> default: DEBUG: dpkg-maintscript-helper: SYMLINK=/usr/share/doc/apache2 -> apache2.2-common PACKAGE=apache2:amd64 LASTVERSION=2.4.1-1~ ACTION=configure PARAM= ==> default: Setting up libaio1:amd64 (0.3.110-1) ... ==> default: Setting up libvpx1:amd64 (1.3.0-3) ... ==> default: Setting up libxpm4:amd64 (1:3.5.12-0+deb8u1) ... ==> default: Setting up libgd3:amd64 (2.1.0-5+deb8u9) ... ==> default: Setting up libmysqlclient18:amd64 (5.5.54-0+deb8u1) ... ==> default: Setting up libonig2:amd64 (5.9.5-3.2) ... ==> default: Setting up libdbi-perl (1.631-3+b1) ... ==> default: Setting up libdbd-mysql-perl (4.028-2+deb8u2) ... ==> default: Setting up libterm-readkey-perl (2.32-1+b1) ... ==> default: Setting up mysql-client-5.5 (5.5.54-0+deb8u1) ... ==> default: Setting up mysql-server-core-5.5 (5.5.54-0+deb8u1) ... ==> default: Setting up mysql-server-5.5 (5.5.54-0+deb8u1) ... ==> default: 170215 11:48:07 [Warning] Using unique option prefix key_buffer instead of key_buffer_size is deprecated and will be removed in a future release. Please use the full name instead. ==> default: 170215 11:48:07 [Note] Ignoring --secure-file-priv value as server is running with --bootstrap. ==> default: 170215 11:48:07 [Note] /usr/sbin/mysqld (mysqld 5.5.54-0+deb8u1) starting as process 8578 ... ==> default: Setting up libqdbm14 (1.8.78-5+b1) ... ==> default: Setting up php5-common (5.6.30+dfsg-0+deb8u1) ... ==> default: Creating config file /etc/php5/mods-available/pdo.ini with new version ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in postinst of php5-common ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/pdo.ini -> /etc/php5/mods-available/pdo.ini PACKAGE=php5-common:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=configure PARAM= ==> default: php5_invoke: Enable module pdo for apache2 SAPI ==> default: php5_invoke: Enable module pdo for cli SAPI ==> default: ==> default: Creating config file /etc/php5/mods-available/opcache.ini with new version ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in postinst of php5-common ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/opcache.ini -> /etc/php5/mods-available/opcache.ini PACKAGE=php5-common:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=configure PARAM= ==> default: php5_invoke: Enable module opcache for apache2 SAPI ==> default: php5_invoke: Enable module opcache for cli SAPI ==> default: Setting up php5-json (1.3.6-1) ... ==> default: php5_invoke: Enable module json for apache2 SAPI ==> default: php5_invoke: Enable module json for cli SAPI ==> default: Setting up php5-cli (5.6.30+dfsg-0+deb8u1) ... ==> default: update-alternatives: ==> default: using /usr/bin/php5 to provide /usr/bin/php (php) in auto mode ==> default: update-alternatives: ==> default: using /usr/bin/phar5 to provide /usr/bin/phar (phar) in auto mode ==> default: ==> default: Creating config file /etc/php5/cli/php.ini with new version ==> default: Setting up libapache2-mod-php5 (5.6.30+dfsg-0+deb8u1) ... ==> default: ==> default: Creating config file /etc/php5/apache2/php.ini with new version ==> default: Module mpm_event disabled. ==> default: Enabling module mpm_prefork. ==> default: apache2_switch_mpm Switch to prefork ==> default: apache2_invoke: Enable module php5 ==> default: Setting up php5 (5.6.30+dfsg-0+deb8u1) ... ==> default: Setting up php5-mysql (5.6.30+dfsg-0+deb8u1) ... ==> default: ==> default: Creating config file /etc/php5/mods-available/mysql.ini with new version ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in postinst of php5-mysql ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/mysql.ini -> /etc/php5/mods-available/mysql.ini PACKAGE=php5-mysql:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=configure PARAM= ==> default: php5_invoke: Enable module mysql for apache2 SAPI ==> default: php5_invoke: Enable module mysql for cli SAPI ==> default: Creating config file /etc/php5/mods-available/mysqli.ini with new version ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in postinst of php5-mysql ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/mysqli.ini -> /etc/php5/mods-available/mysqli.ini PACKAGE=php5-mysql:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=configure PARAM= ==> default: php5_invoke: Enable module mysqli for apache2 SAPI ==> default: php5_invoke: Enable module mysqli for cli SAPI ==> default: ==> default: Creating config file /etc/php5/mods-available/pdo_mysql.ini with new version ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in postinst of php5-mysql ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/pdo_mysql.ini -> /etc/php5/mods-available/pdo_mysql.ini PACKAGE=php5-mysql:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=configure PARAM= ==> default: php5_invoke: Enable module pdo_mysql for apache2 SAPI ==> default: php5_invoke: Enable module pdo_mysql for cli SAPI ==> default: Setting up aegir3-provision (3.9+571.c7a38f8) ... ==> default: + ==> default: VARLIB=/var/aegir ==> default: + getent passwd aegir ==> default: + echo adding aegir user... ==> default: adding aegir user... ==> default: + adduser --quiet --system --no-create-home --group --home /var/aegir --shell /bin/bash --gecos Aegir user,,, aegir ==> default: + adduser --quiet aegir www-data ==> default: Adding user aegir to group www-data ==> default: + [ -d /etc/sudoers.d ] ==> default: + ucf --debconf-ok /usr/share/doc/aegir3-provision/examples/example.sudoers /etc/sudoers.d/aegir ==> default: ==> default: Creating config file /etc/sudoers.d/aegir with new version ==> default: + ucfr aegir-provision /etc/sudoers.d/aegir ==> default: + chmod 440 /etc/sudoers.d/aegir ==> default: + chown aegir:aegir /var/aegir ==> default: + db_get aegir/drush_version ==> default: + _db_cmd GET aegir/drush_version ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/drush_version ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=stable ==> default: + return 0 ==> default: + DRUSH_VERSION=stable ==> default: + [ stable != ignore ] ==> default: + ==> default: cut ==> default: -d. ==> default: -f1 ==> default: + ==> default: echo ==> default: stable ==> default: + DRUSH_MAJOR_VERSION=stable ==> default: + [ stable = stable ] ==> default: + DRUSH_PHAR_URL=http://files.drush.org/drush.phar ==> default: + [ -n http://files.drush.org/drush.phar ] ==> default: + curl -SsL http://files.drush.org/drush.phar -o drush ==> default: + chmod +x drush ==> default: + DRUSH_PATH=/usr/local/bin/drush ==> default: + [ -f /usr/local/bin/drush ] ==> default: + mv drush /usr/local/bin/drush ==> default: + exit 0 ==> default: Setting up php5-gd (5.6.30+dfsg-0+deb8u1) ... ==> default: ==> default: Creating config file /etc/php5/mods-available/gd.ini with new version ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in postinst of php5-gd ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/gd.ini -> /etc/php5/mods-available/gd.ini PACKAGE=php5-gd:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=configure PARAM= ==> default: php5_invoke: Enable module gd for apache2 SAPI ==> default: php5_invoke: Enable module gd for cli SAPI ==> default: Setting up liberror-perl (0.17-1.1) ... ==> default: Setting up git-man (1:2.1.4-2.1+deb8u2) ... ==> default: Setting up git (1:2.1.4-2.1+deb8u2) ... ==> default: Setting up git-core (1:2.1.4-2.1+deb8u2) ... ==> default: Setting up aegir3-hostmaster (3.9+571.c7a38f8) ... ==> default: + ==> default: grep ==> default: -q ==> default: Drupal bootstrap.*Successful ==> default: + ==> default: su ==> default: -s ==> default: /bin/sh ==> default: aegir ==> default: -c ==> default: drush @hostmaster status ==> default: + db_input medium aegir/email ==> default: + _db_cmd INPUT medium aegir/email ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT medium aegir/email ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_input high aegir/site ==> default: + _db_cmd INPUT high aegir/site ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT high aegir/site ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_get aegir/email ==> default: + _db_cmd GET aegir/email ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/email ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET= aegir@example.com ==> default: + return 0 ==> default: + [ -z aegir@example.com ] ==> default: + db_get aegir/site ==> default: + _db_cmd GET aegir/site ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/site ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET= aegir.example.com ==> default: + return 0 ==> default: + [ -z aegir.example.com ] ==> default: + db_input low aegir/makefile ==> default: + _db_cmd INPUT low aegir/makefile ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + p ==> default: rintf %s\n INPUT low aegir/makefile ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_input low aegir/working-copy ==> default: + _db_cmd INPUT low aegir/working-copy ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT low aegir/working-copy ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_input low aegir/db_host ==> default: + _db_cmd INPUT low aegir/db_host ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT low aegir/db_host ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_input low aegir/db_user ==> default: + _db_cmd INPUT low aegir/db_user ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT low aegir/db_user ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_fset aegir/db_password seen false ==> default: + _db_cmd FSET aegir/db_password seen false ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n FSET aegir/db_password seen false ==> default: + IFS= ==> default: ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=false ==> default: + return 0 ==> default: + db_input high aegir/db_password ==> default: + _db_cmd INPUT high aegir/db_password ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT high aegir/db_password ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + ==> default: wc_httpd_installed ==> default: apache2 ==> default: nginx ==> default: + ==> default: local ==> default: httpds ==> default: + ==> default: [ ==> default: apache2 nginx ==> default: ] ==> default: + ==> default: httpds=apache2 nginx ==> default: + ==> default: test ==> default: -x ==> default: /usr/sbin/apache2 ==> default: + ==> default: echo ==> default: apache2 ==> default: + ==> default: test ==> default: -x ==> default: /usr/sbin/nginx ==> default: + default_servers=apache2 ==> default: + ==> default: sed ==> default: -e ==> default: s/[[:space:]][[:space:]]*/, /g ==> default: + ==> default: echo ==> default: apache2 ==> default: + server_list=apache2 ==> default: + db_fget aegir/webserver seen ==> default: + _db_cmd FGET aegir/webserver seen ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n FGET aegir/webserver seen ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=false ==> default: + return 0 ==> default: + [ false = false ] ==> default: + db_set aegir/webserver apache2 ==> default: + _db_cmd SET aegir/webserver apache2 ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n SET aegir/webserver apache2 ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=value set ==> default: + return 0 ==> default: + db_input low aegir/webserver ==> default: + _db_cmd INPUT low aegir/webserver ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n INPUT low aegir/webserver ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=30 question skipped ==> default: + return 30 ==> default: + true ==> default: + db_go ==> default: + _db_cmd GO ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GO ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=ok ==> default: + return 0 ==> default: + ==> default: su ==> default: aegir ==> default: -s ==> default: /bin/sh ==> default: -c ==> default: echo $HOME ==> default: + AEGIRHOME=/var/aegir ==> default: + ==> default: sed ==> default: -n ==> default: /^version/{s/^.*= *//;p} ==> default: /usr/share/drush/commands/provision/provision.info ==> default: + VERSION=7.x-3.x ==> default: + FLAGS=--yes ==> default: + [ developer = developer ] ==> default: + FLAGS=--yes --debug ==> default: + db_get aegir/makefile ==> default: + _db_cmd GET aegir/makefile ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/makefile ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET= ==> default: + return 0 ==> default: + [ ! -z ] ==> default: + db_get aegir/webserver ==> default: + _db_cmd GET aegir/webserver ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/webserver ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=apache2 ==> default: + return 0 ==> default: + [ ! -z apache2 ] ==> default: + [ apache2 = apache2 ] ==> default: + RET=apache ==> default: + FLAGS=--yes --debug --http_service_type='apache' ==> default: + WEBSERVER=apache ==> default: + db_get aegir/working-copy ==> default: + _db_cmd GET aegir/working-copy ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/working-copy ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=false ==> default: + return 0 ==> default: + [ ! -z false ] ==> default: + FLAGS=--yes --debug --http_service_type='apache' --working-copy ==> default: + touch /var/aegir/config/apache.conf ==> default: + chown aegir:aegir /var/aegir /var/aegir/config /var/aegir/config/apache.conf ==> default: + su -s /bin/sh aegir -c drush cache-clear drush ==> default: 'drush' cache was cleared. [success] ==> default: + ==> default: su ==> default: -s ==> default: /bin/sh ==> default: aegir ==> default: -c ==> default: drush @hostmaster status --fields="uri" --field-labels=0 2>/dev/null | tr "\n" " " | sed -e "s/^[[:space:]]*//g" -e "s/[[:space:]]*$//g" ==> default: + site_uri= ==> default: + ==> default: su ==> default: -s ==> default: /bin/sh ==> default: aegir ==> default: -c ==> default: drush @hostmaster status --fields="root" --field-labels=0 2>/dev/null | tr "\n" " " | sed -e "s/^[[:space:]]*//g" -e "s/[[:space:]]*$//g" ==> default: + drupal_root= ==> default: + [ -d ] ==> default: + db_get aegir/site ==> default: + _db_cmd GET aegir/site ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/site ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET= aegir.example.com ==> default: + return 0 ==> default: + [ ! -z aegir.example.com ] ==> default: + site_uri= aegir.example.com ==> default: + db_get aegir/db_host ==> default: + _db_cmd GET aegir/db_host ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/db_host ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=localhost ==> default: + return 0 ==> default: + AEGIR_DB_HOST=localhost ==> default: + db_get aegir/db_user ==> default: + _db_cmd GET aegir/db_user ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/db_user ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=root ==> default: + return 0 ==> default: + AEGIR_DB_USER=root ==> default: + db_get aegir/db_password ==> default: + _db_cmd GET aegir/db_password ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/db_password ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=PASSWORD ==> default: + return 0 ==> default: + AEGIR_DB_PASS=PASSWORD ==> default: + [ -z PASSWORD ] ==> default: + db_get aegir/email ==> default: + _db_cmd GET aegir/email ==> default: ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GET aegir/email ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET= aegir@example.com ==> default: + return 0 ==> default: + EMAIL= aegir@example.com ==> default: + db_go ==> default: + _db_cmd GO ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n GO ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=ok ==> default: + return 0 ==> default: + db_reset aegir/db_password ==> default: + _db_cmd RESET aegir/db_password ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n RESET aegir/db_password ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=0 ==> default: + return 0 ==> default: + db_fset aegir/db_password seen true ==> default: + _db_cmd FSET aegir/db_password seen true ==> default: + _db_internal_IFS= ==> default: ==> default: + IFS= ==> default: + printf %s\n FSET aegir/db_password seen true ==> default: + IFS= ==> default: ==> default: + IFS= ==> default: read -r _db_internal_line ==> default: + RET=true ==> default: + return 0 ==> default: + db_stop ==> default: + echo STOP ==> default: + [ -d /var/aegir/.drush/provision ] ==> default: + echo installing the Aegir frontend (Drupal with the hostmaster profile), please wait... ==> default: installing the Aegir frontend (Drupal with the hostmaster profile), please wait... ==> default: + [ developer = ==> default: developer ] ==> default: + DEBUG=--debug ==> default: + su -s /bin/sh aegir -c cd /var/aegir && drush hostmaster-install --yes --debug --http_service_type='apache' --working-copy --backend aegir.example.com 2>&1 | drush backend-parse --debug ==> default: Phar detected. Proceeding to drush_main(). ==> default: Cache HIT cid: 8.1.9-commandfiles-0-a1ab56b0d5baa05a84508f79f69564a5 [debug] ==> default: [0.02 sec, 3.57 MB] ==> default: Bootstrap to phase 0. [0.29 sec, 10.5 MB] [bootstrap] ==> default: Bootstrap to phase 0. [0.3 sec, 10.5 MB] [bootstrap] ==> default: Found command: backend-parse (commandfile=provision) [0.3 sec, 10.5 [bootstrap] ==> default: MB] ==> default: Including /usr/share/drush/commands/provision/parse.backend.inc [0.43[bootstrap] ==> default: sec, 10.56 MB] ==> default: Calling hook drush_provision_backend_parse [0.44 sec, 10.83 MB] [debug] ==> default: Cache HIT cid: 8.1.9-commandfiles-0-a1ab56b0d5baa05a84508f79f69564a5 [debug] ==> default: [87.3 sec, 14.11 MB] ==> default: Bootstrap to phase 0. [87.3 sec, 14.12 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.3 sec, 14.12 MB] [bootstrap] ==> default: Found command: hostmaster-install (commandfile=provision) [87.3 sec, [bootstrap] ==> default: 14.12 MB] ==> default: Loading apache driver for the http service [87.3 sec, 14.13 MB] [notice] ==> default: Loading apache driver for the http service [87.3 sec, 14.13 MB] [notice] ==> default: Including /usr/share/drush/commands/provision/install.hostmaster.inc [bootstrap] ==> default: [87.31 sec, 14.14 MB] ==> default: Calling hook drush_provision_hostmaster_install_pre_validate [87.31 [debug] ==> default: sec, 14.14 MB] ==> default: Returned from hook drush_provision_hostmaster_install_pre_validate [debug] ==> default: [87.31 sec, 14.14 MB] ==> default: Calling hook drush_provision_hostmaster_install_validate [87.31 sec, [debug] ==> default: 14.14 MB] ==> default: Returned from hook drush_provision_hostmaster_install_validate [87.31 [debug] ==> default: sec, 14.14 MB] ==> default: Calling hook drush_provision_hostmaster_install [87.31 sec, 14.14 MB] [debug] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes --root= --uri= provision-save '@server_localhost' ==> default: --remote_host=localhost --context_type=server --db_service_type=mysql ==> default: --master_db='mysql://root:PASSWORD@localhost:3306' --db_port=3306 ==> default: 2>&1 [87.31 sec, 14.14 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes --root= [notice] ==> default: --uri= provision-save '@server_localhost' --remote_host=localhost ==> default: --context_type=server --db_service_type=mysql ==> default: --master_db='mysql://root:PASSWORD@localhost:3306' --db_port=3306 ==> default: 2>&1 [87.31 sec, 14.14 MB] ==> default: Bootstrap to phase 0. [87.31 sec, 14.14 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.31 sec, 14.14 MB] [bootstrap] ==> default: Found command: provision-save (commandfile=provision) [87.31 sec, [bootstrap] ==> default: 14.14 MB] ==> default: Loading mysql driver for the db service [87.31 sec, 14.14 MB] [notice] ==> default: Loading mysql driver for the db service [87.31 sec, 14.15 MB] [notice] ==> default: Calling hook drush_provision_save [87.31 sec, 14.15 MB] [debug] ==> default: Loading mysql driver for the db service [87.31 sec, 14.15 MB] [notice] ==> default: Template loaded from Provision Config class [notice] ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.31 sec, 14.15 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: (/var/aegir/.drush/server_localhost.alias.drushrc.php) [87.31 sec, ==> default: 14.15 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php to 440 [87.31 ==> default: sec, 14.15 MB] ==> default: Returned from hook drush_provision_save [87.31 sec, 14.15 MB] [debug] ==> default: Command dispatch complete [87.31 sec, 14.15 MB] [notice] ==> default: Loaded alias @server_localhost from file [notice] ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php [87.31 sec, ==> default: 14.16 MB] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes @server_localhost provision-verify 2>&1 [87.31 sec, 14.16 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes [notice] ==> default: @server_localhost provision-verify 2>&1 [87.31 sec, 14.16 MB] ==> default: Bootstrap to phase 0. [87.31 sec, 14.16 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.31 sec, 14.16 MB] [bootstrap] ==> default: Found command: provision-verify (commandfile=provision) [87.31 sec, [bootstrap] ==> default: 14.17 MB] ==> default: Loading mysql driver for the db service [87.31 sec, 14.17 MB] [notice] ==> default: Including [bootstrap] ==> default: /usr/share/drush/commands/provision/platform/backupmigrate/verify.provision.inc ==> default: [87.31 sec, 14.17 MB] ==> default: Including [bootstrap] ==> default: /usr/share/drush/commands/provision/platform/verify.provision.inc ==> default: [87.31 sec, 14.17 MB] ==> default: Calling hook drush_provision_drupal_provision_verify_validate [87.31 [debug] ==> default: sec, 14.17 MB] ==> default: Returned from hook drush_provision_drupal_provision_verify_validate [debug] ==> default: [87.31 sec, 14.17 MB] ==> default: Calling hook drush_provision_drupal_pre_provision_verify [87.31 sec, [debug] ==> default: 14.17 MB] ==> default: Provision configuration root path /var/aegir/config exists. [87.31 [success] ==> default: sec, 14.17 MB] ==> default: Provision configuration root ownership of /var/aegir/config has been [success] ==> default: changed to aegir. [87.31 sec, 14.17 MB] ==> default: Provision configuration root permissions of /var/aegir/config have [success] ==> default: been changed to 711. [87.31 sec, 14.17 MB] ==> default: Provision configuration root path /var/aegir/config is writable. [success] ==> default: [87.31 sec, 14.17 MB] ==> default: Provision configuration path /var/aegir/config/server_localhost has [success] ==> default: been created. [87.31 sec, 14.17 MB] ==> default: Provision configuration ownership of [success] ==> default: /var/aegir/config/server_localhost has been changed to aegir. [87.31 ==> default: sec, 14.18 MB] ==> default: Provision configuration permissions of [success] ==> default: /var/aegir/config/server_localhost have been changed to 711. [87.31 ==> default: sec, 14.18 MB] ==> default: Provision configuration path /var/aegir/config/server_localhost is [success] ==> default: writable. [87.31 sec, 14.18 MB] ==> default: Provision client home path /var/aegir/clients has been created. [success] ==> default: [87.31 sec, 14.18 MB] ==> default: Provision client home ownership of /var/aegir/clients has been [success] ==> default: changed to aegir. [87.31 sec, 14.18 MB] ==> default: Provision client home permissions of /var/aegir/clients have been [success] ==> default: changed to 711. [87.31 sec, 14.18 MB] ==> default: Provision client home path /var/aegir/clients is writable. [87.31 [success] ==> default: sec, 14.18 MB] ==> default: Returned from hook drush_provision_drupal_pre_provision_verify [87.31 [debug] ==> default: sec, 14.18 MB] ==> default: Calling hook drush_provision_verify [87.31 sec, 14.18 MB] [debug] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes @server_localhost provision-save 2>&1 [87.31 sec, 14.18 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes [notice] ==> default: @server_localhost provision-save 2>&1 [87.31 sec, 14.18 MB] ==> default: Bootstrap to phase 0. [87.31 sec, 14.18 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.31 sec, 14.19 MB] [bootstrap] ==> default: Found command: provision-save (commandfile=provision) [87.31 sec, [bootstrap] ==> default: 14.19 MB] ==> default: Loading mysql driver for the db service [87.31 sec, 14.19 MB] [notice] ==> default: Calling hook drush_provision_save [87.31 sec, 14.19 MB] [debug] ==> default: Template loaded from Provision Config class [notice] ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.31 sec, 14.19 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php to 640 [87.31 ==> default: sec, 14.19 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: (/var/aegir/.drush/server_localhost.alias.drushrc.php) [87.31 sec, ==> default: 14.19 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php to 440 [87.31 ==> default: sec, 14.19 MB] ==> default: Returned from hook drush_provision_save [87.31 sec, 14.19 MB] [debug] ==> default: Command dispatch complete [87.31 sec, 14.19 MB] [notice] ==> default: Provision can create new databases. [87.31 sec, 14.19 MB] [success] ==> default: Provision can grant privileges on database users. [87.31 sec, 14.2 [success] ==> default: MB] ==> default: Multi-byte UTF-8 for Drupal 7 is not supported on your system. See [warning] ==> default: the <a href="https://www.drupal.org/node/2754539">documentation on ==> default: adding 4 byte UTF-8 support</a> for more information. [87.31 sec, ==> default: 14.2 MB] ==> default: Returned from hook drush_provision_verify [87.31 sec, 14.2 MB] [debug] ==> default: Calling hook drush_provision_drupal_post_provision_verify [87.31 sec, [debug] ==> default: 14.2 MB] ==> default: Returned from hook drush_provision_drupal_post_provision_verify [debug] ==> default: [87.31 sec, 14.2 MB] ==> default: Command dispatch complete [87.31 sec, 14.2 MB] [notice] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes --root= --uri= provision-save '@server_master' ==> default: --context_type=server ==> default: --remote_host=aegir3-deb-unstable-no-puppet.test ==> default: --aegir_root=/var/aegir --script_user=aegir ==> default: --http_service_type=apache --http_port=80 --web_group=www-data ==> default: --master_url=http://aegir.example.com --db_port=3306 2>&1 [87.31 sec, ==> default: 14.2 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes --root= [notice] ==> default: --uri= provision-save '@server_master' --context_type=server ==> default: --remote_host=aegir3-deb-unstable-no-puppet.test ==> default: --aegir_root=/var/aegir --script_user=aegir ==> default: --http_service_type=apache --http_port=80 --web_group=www-data ==> default: --master_url=http://aegir.example.com --db_port=3306 2>&1 [87.31 sec, ==> default: 14.2 MB] ==> default: Bootstrap to phase 0. [87.31 sec, 14.2 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.31 sec, 14.2 MB] [bootstrap] ==> default: Found command: provision-save (commandfile=provision) [87.31 sec, [bootstrap] ==> default: 14.2 MB] ==> default: Loading apache driver for the http service [87.31 sec, 14.21 MB] [notice] ==> default: Loading apache driver for the http service [87.31 sec, 14.21 MB] [notice] ==> default: Calling hook drush_provision_save [87.31 sec, 14.21 MB] [debug] ==> default: Template loaded from Provision Config class [notice] ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.31 sec, 14.21 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: (/var/aegir/.drush/server_master.alias.drushrc.php) [87.31 sec, 14.21 ==> default: MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php to 440 [87.31 sec, ==> default: 14.21 MB] ==> default: Returned from hook drush_provision_save [87.31 sec, 14.21 MB] [debug] ==> default: Command dispatch complete [87.31 sec, 14.21 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.31 sec, 14.22 ==> default: MB] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes @server_master provision-verify 2>&1 [87.31 sec, 14.22 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes [notice] ==> default: @server_master provision-verify 2>&1 [87.31 sec, 14.22 MB] ==> default: Bootstrap to phase 0. [87.31 sec, 14.22 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.31 sec, 14.22 MB] [bootstrap] ==> default: Found command: provision-verify (commandfile=provision) [87.31 sec, [bootstrap] ==> default: 14.22 MB] ==> default: Loading apache driver for the http service [87.31 sec, 14.23 MB] [notice] ==> default: Including [bootstrap] ==> default: /usr/share/drush/commands/provision/platform/backupmigrate/verify.provision.inc ==> default: [87.31 sec, 14.23 MB] ==> default: Including [bootstrap] ==> default: /usr/share/drush/commands/provision/platform/verify.provision.inc ==> default: [87.32 sec, 14.23 MB] ==> default: Calling hook drush_provision_drupal_provision_verify_validate [87.32 [debug] ==> default: sec, 14.23 MB] ==> default: Returned from hook drush_provision_drupal_provision_verify_validate [debug] ==> default: [87.32 sec, 14.23 MB] ==> default: Calling hook drush_provision_drupal_pre_provision_verify [87.32 sec, [debug] ==> default: 14.23 MB] ==> default: Provision configuration root path /var/aegir/config exists. [87.32 [success] ==> default: sec, 14.23 MB] ==> default: Provision configuration root ownership of /var/aegir/config has been [success] ==> default: changed to aegir. [87.32 sec, 14.23 MB] ==> default: Provision configuration root permissions of /var/aegir/config have [success] ==> default: been changed to 711. [87.32 sec, 14.23 MB] ==> default: Provision configuration root path /var/aegir/config is writable. [success] ==> default: [87.32 sec, 14.23 MB] ==> default: Provision configuration path /var/aegir/config/server_master has been [success] ==> default: created. [87.32 sec, 14.23 MB] ==> default: Provision configuration ownership of /var/aegir/config/server_master [success] ==> default: ==> default: has been changed to aegir. [87.32 sec, 14.24 MB] ==> default: Provision configuration permissions of [success] ==> default: ==> default: /var/aegir/config/server_master have been changed to 711. [87.32 sec, ==> default: 14.24 MB] ==> default: Provision configuration path /var/aegir/config/server_master is [success] ==> default: writable. [87.32 sec, 14.24 MB] ==> default: Provision client home path /var/aegir/clients exists. [87.32 sec, [success] ==> default: 14.24 MB] ==> default: Provision client home ownership of /var/aegir/clients has been [success] ==> default: changed to aegir. [87.32 sec, 14.24 MB] ==> default: Provision client home permissions of /var/aegir/clients have been [success] ==> default: changed to 711. [87.32 sec, 14.24 MB] ==> default: Provision client home path /var/aegir/clients is writable. [87.32 [success] ==> default: sec, 14.24 MB] ==> default: Backup path /var/aegir/backups has been created. [87.32 sec, 14.24 [success] ==> default: MB] ==> default: Backup ownership of /var/aegir/backups has been changed to aegir. [success] ==> default: [87.32 sec, 14.24 MB] ==> default: Backup permissions of /var/aegir/backups have been changed to 700. [success] ==> default: ==> default: [87.32 sec, 14.24 MB] ==> default: Backup path /var/aegir/backups is writable. [87.33 sec, 14.24 MB] [success] ==> default: ==> default: Provision PHP configuration path /var/aegir/config/includes has been [success] ==> default: created. [87.33 sec, 14.24 MB] ==> default: Provision PHP configuration ownership of /var/aegir/config/includes [success] ==> default: ==> default: has been changed to aegir. [87.33 sec, 14.25 MB] ==> default: Provision PHP configuration permissions of /var/aegir/config/includes [success] ==> default: have been changed to 711. [87.33 sec, 14.25 MB] ==> default: Provision PHP configuration path /var/aegir/config/includes is [success] ==> default: writable. [87.33 sec, 14.25 MB] ==> default: Template loaded from Provision Config class [notice] ==> default: Provision_Config_Global_Settings: ==> default: /usr/share/drush/commands/provision/Provision/Config/Global/global_settings.tpl.php ==> default: [87.33 sec, 14.25 MB] ==> default: Generated config in write(): Global settings.php file [success] ==> default: ==> default: (/var/aegir/config/includes/global.inc) [87.33 sec, 14.25 MB] ==> default: Returned from hook drush_provision_drupal_pre_provision_verify [87.33 [debug] ==> default: ==> default: sec, 14.25 MB] ==> default: Calling hook drush_provision_verify [87.33 sec, 14.25 MB] [debug] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: ==> default: --yes @server_master provision-save 2>&1 [87.33 sec, 14.25 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes [notice] ==> default: ==> default: @server_master provision-save 2>&1 [87.33 sec, 14.25 MB] ==> default: Bootstrap to phase 0. [87.33 sec, 14.25 MB] [bootstrap] ==> default: ==> default: Bootstrap to phase 0. [87.33 sec, 14.25 MB] [bootstrap] ==> default: ==> default: Found command: provision-save (commandfile=provision) [87.33 sec, [bootstrap] ==> default: 14.25 MB] ==> default: Loading apache driver for the http service [87.33 sec, 14.26 MB] [notice] ==> default: Calling hook drush_provision_save [87.33 sec, 14.26 MB] [debug] ==> default: ==> default: Template loaded from Provision Config class [notice] ==> default: ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.33 sec, 14.26 MB] ==> default: Changed permissions of [success] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php to 640 [87.33 sec, ==> default: 14.26 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: ==> default: (/var/aegir/.drush/server_master.alias.drushrc.php) [87.33 sec, 14.26 ==> default: MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php to 440 [87.33 sec, ==> default: 14.26 MB] ==> default: Returned from hook drush_provision_save [87.33 sec, 14.26 MB] [debug] ==> default: ==> default: Command dispatch complete [87.33 sec, 14.26 MB] [notice] ==> default: ==> default: Webserver custom pre-configuration path [success] ==> default: ==> default: /var/aegir/config/server_master/apache has been created. [87.33 sec, ==> default: 14.26 MB] ==> default: Webserver custom pre-configuration ownership of [success] ==> default: /var/aegir/config/server_master/apache has been changed to aegir. ==> default: [87.33 sec, 14.26 MB] ==> default: Webserver custom pre-configuration permissions of [success] ==> default: /var/aegir/config/server_master/apache have been changed to 711. ==> default: [87.33 sec, 14.26 MB] ==> default: Webserver custom pre-configuration path [success] ==> default: /var/aegir/config/server_master/apache is writable. [87.33 sec, 14.26 ==> default: MB] ==> default: Webserver custom pre-configuration path [success] ==> default: /var/aegir/config/server_master/apache/pre.d has been created. [87.33 ==> default: sec, 14.27 MB] ==> default: Webserver custom pre-configuration ownership of [success] ==> default: /var/aegir/config/server_master/apache/pre.d has been changed to ==> default: aegir. [87.33 sec, 14.27 MB] ==> default: Webserver custom pre-configuration permissions of [success] ==> default: /var/aegir/config/server_master/apache/pre.d have been changed to ==> default: 700. [87.33 sec, 14.27 MB] ==> default: Webserver custom pre-configuration path [success] ==> default: /var/aegir/config/server_master/apache/pre.d is writable. [87.33 sec, ==> default: 14.27 MB] ==> default: Webserver custom post-configuration path [success] ==> default: /var/aegir/config/server_master/apache/post.d has been created. ==> default: [87.33 sec, 14.27 MB] ==> default: Webserver custom post-configuration ownership of [success] ==> default: ==> default: /var/aegir/config/server_master/apache/post.d has been changed to ==> default: aegir. [87.33 sec, 14.27 MB] ==> default: Webserver custom post-configuration permissions of [success] ==> default: /var/aegir/config/server_master/apache/post.d have been changed to ==> default: 700. [87.33 sec, 14.27 MB] ==> default: ==> default: Webserver custom post-configuration path [success] ==> default: /var/aegir/config/server_master/apache/post.d is writable. [87.33 ==> default: sec, 14.27 MB] ==> default: Webserver platform configuration path [success] ==> default: ==> default: /var/aegir/config/server_master/apache/platform.d has been created. ==> default: [87.33 sec, 14.27 MB] ==> default: Webserver platform configuration ownership of [success] ==> default: ==> default: /var/aegir/config/server_master/apache/platform.d has been changed to ==> default: aegir. [87.33 sec, 14.27 MB] ==> default: Webserver platform configuration permissions of [success] ==> default: ==> default: /var/aegir/config/server_master/apache/platform.d have been changed ==> default: to 700. [87.33 sec, 14.27 MB] ==> default: Webserver platform configuration path [success] ==> default: /var/aegir/config/server_master/apache/platform.d is writable. [87.33 ==> default: sec, 14.27 MB] ==> default: Webserver virtual host configuration path [success] ==> default: ==> default: /var/aegir/config/server_master/apache/vhost.d has been created. ==> default: [87.33 sec, 14.28 MB] ==> default: Webserver virtual host configuration ownership of [success] ==> default: ==> default: /var/aegir/config/server_master/apache/vhost.d has been changed to ==> default: aegir. [87.33 sec, 14.28 MB] ==> default: Webserver virtual host configuration permissions of [success] ==> default: ==> default: /var/aegir/config/server_master/apache/vhost.d have been changed to ==> default: 700. [87.33 sec, 14.28 MB] ==> default: Webserver virtual host configuration path [success] ==> default: ==> default: /var/aegir/config/server_master/apache/vhost.d is writable. [87.33 ==> default: sec, 14.28 MB] ==> default: Webserver subdir configuration path [success] ==> default: /var/aegir/config/server_master/apache/subdir.d has been created. ==> default: [87.33 sec, 14.28 MB] ==> default: Webserver subdir configuration ownership of [success] ==> default: /var/aegir/config/server_master/apache/subdir.d has been changed to ==> default: aegir. [87.34 sec, 14.28 MB] ==> default: Webserver subdir configuration permissions of [success] ==> default: /var/aegir/config/server_master/apache/subdir.d have been changed to ==> default: 700. [87.34 sec, 14.28 MB] ==> default: Webserver subdir configuration path [success] ==> default: ==> default: /var/aegir/config/server_master/apache/subdir.d is writable. [87.34 ==> default: sec, 14.28 MB] ==> default: Template loaded from Provision Config class [notice] ==> default: ==> default: Provision_Config_Apache_Server: ==> default: /usr/share/drush/commands/provision/http/Provision/Config/Apache/server.tpl.php ==> default: [87.34 sec, 14.28 MB] ==> default: Generated config in write(): web server configuration file [success] ==> default: ==> default: (/var/aegir/config/server_master/apache.conf) [87.34 sec, 14.28 MB] ==> default: Created symlink for apache.conf on aegir3-deb-unstable-no-puppet.test [notice] ==> default: ==> default: [87.34 sec, 14.28 MB] ==> default: apache on aegir3-deb-unstable-no-puppet.test has been restarted [notice] ==> default: [87.34 sec, 14.28 MB] ==> default: Returned from hook drush_provision_verify [87.34 sec, 14.29 MB] [debug] ==> default: ==> default: Calling hook drush_provision_drupal_post_provision_verify [87.34 sec, [debug] ==> default: ==> default: 14.29 MB] ==> default: Returned from hook drush_provision_drupal_post_provision_verify [debug] ==> default: [87.34 sec, 14.29 MB] ==> default: ==> default: Command dispatch complete [87.34 sec, 14.29 MB] [notice] ==> default: Preparing the hostmaster frontend installation. [87.34 sec, 14.29 MB] [notice] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 [command] ==> default: ==> default: --root=/var/aegir/hostmaster-7.x-3.x --verbose --debug --yes --uri= ==> default: provision-save '@platform_hostmaster' --context_type=platform ==> default: --server='@server_master' --web_server='@server_master' ==> default: --makefile=/usr/share/drush/commands/provision/aegir.make 2>&1 [87.34 ==> default: sec, 14.29 MB] ==> default: /usr/local/bin/drush --backend=2 [notice] ==> default: ==> default: --root=/var/aegir/hostmaster-7.x-3.x --verbose --debug --yes --uri= ==> default: provision-save '@platform_hostmaster' --context_type=platform ==> default: --server='@server_master' --web_server='@server_master' ==> default: --makefile=/usr/share/drush/commands/provision/aegir.make 2>&1 [87.34 ==> default: sec, 14.29 MB] ==> default: Bootstrap to phase 0. [87.34 sec, 14.29 MB] [bootstrap] ==> default: ==> default: Bootstrap to phase 0. [87.34 sec, 14.29 MB] [bootstrap] ==> default: ==> default: Found command: provision-save (commandfile=provision) [87.34 sec, [bootstrap] ==> default: 14.29 MB] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.34 sec, 14.29 ==> default: MB] ==> default: Loading apache driver for the http service [87.34 sec, 14.3 MB] [notice] ==> default: ==> default: Calling hook drush_provision_save [87.34 sec, 14.3 MB] [debug] ==> default: ==> default: Template loaded from Provision Config class [notice] ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.34 sec, 14.3 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: (/var/aegir/.drush/platform_hostmaster.alias.drushrc.php) [87.34 sec, ==> default: 14.3 MB] ==> default: Changed permissions of [success] ==> default: ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php to 440 [87.34 ==> default: sec, 14.3 MB] ==> default: Returned from hook drush_provision_save [87.34 sec, 14.3 MB] [debug] ==> default: ==> default: Command dispatch complete [87.34 sec, 14.3 MB] [notice] ==> default: ==> default: Loaded alias @platform_hostmaster from file [notice] ==> default: ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php [87.34 sec, ==> default: 14.31 MB] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: ==> default: --yes @platform_hostmaster provision-verify --working-copy=1 2>&1 ==> default: [87.34 sec, 14.31 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes [notice] ==> default: ==> default: @platform_hostmaster provision-verify --working-copy=1 2>&1 [87.34 ==> default: sec, 14.31 MB] ==> default: Bootstrap to phase 0. [87.34 sec, 14.31 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.34 sec, 14.31 MB] [bootstrap] ==> default: ==> default: Found command: provision-verify (commandfile=provision) [87.34 sec, [bootstrap] ==> default: ==> default: 14.32 MB] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.34 sec, 14.32 ==> default: MB] ==> default: Loading apache driver for the http service [87.34 sec, 14.32 MB] [notice] ==> default: Including [bootstrap] ==> default: /usr/share/drush/commands/provision/platform/backupmigrate/verify.provision.inc ==> default: [87.34 sec, 14.32 MB] ==> default: Including [bootstrap] ==> default: ==> default: /usr/share/drush/commands/provision/platform/verify.provision.inc ==> default: [87.34 sec, 14.32 MB] ==> default: Calling hook drush_provision_drupal_provision_verify_validate [87.34 [debug] ==> default: sec, 14.32 MB] ==> default: Returned from hook drush_provision_drupal_provision_verify_validate [debug] ==> default: ==> default: [87.34 sec, 14.32 MB] ==> default: Calling hook drush_provision_drupal_pre_provision_verify [87.34 sec, [debug] ==> default: ==> default: 14.32 MB] ==> default: Platform path does not exist, fetching from drush makefile [87.34 [notice] ==> default: sec, 14.32 MB] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: ==> default: --yes --root= --uri= make ==> default: /usr/share/drush/commands/provision/aegir.make ==> default: /var/aegir/hostmaster-7.x-3.x --working-copy 2>&1 [87.34 sec, 14.32 ==> default: MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes --root= [notice] ==> default: ==> default: --uri= make /usr/share/drush/commands/provision/aegir.make ==> default: /var/aegir/hostmaster-7.x-3.x --working-copy 2>&1 [87.34 sec, 14.32 ==> default: MB] ==> default: Bootstrap to phase 0. [87.34 sec, 14.33 MB] [bootstrap] ==> default: Bootstrap to phase -1. [87.34 sec, 14.33 MB] [bootstrap] ==> default: ==> default: Found command: make (commandfile=make) [87.35 sec, 14.33 MB] [bootstrap] ==> default: ==> default: Loading release_info engine. [87.35 sec, 14.33 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.35 sec, 14.33 ==> default: MB] ==> default: Loading apache driver for the http service [87.35 sec, 14.33 MB] [notice] ==> default: ==> default: Calling hook drush_make_validate [87.35 sec, 14.33 MB] [debug] ==> default: Returned from hook drush_make_validate [87.35 sec, 14.33 MB] [debug] ==> default: ==> default: Calling hook drush_make_pre_make [87.35 sec, 14.33 MB] [debug] ==> default: ==> default: Returned from hook drush_make_pre_make [87.35 sec, 14.33 MB] [debug] ==> default: ==> default: Calling hook drush_make [87.35 sec, 14.33 MB] [debug] ==> default: ==> default: Beginning to build /usr/share/drush/commands/provision/aegir.make. [ok] ==> default: [87.35 sec, 14.33 MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/drupal/7.x [87.35 sec, ==> default: 14.34 MB] ==> default: Calling is_readable(/tmp/download_fileGumVoc) [87.35 sec, 14.34 MB] [debug] ==> default: ==> default: Calling is_writable(/var/aegir/.drush/cache/download) [87.35 sec, [debug] ==> default: ==> default: 14.34 MB] ==> default: Calling rename(/tmp/download_fileGumVoc, [debug] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-drupal-7.x) ==> default: [87.35 sec, 14.34 MB] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --verbose [command] ==> default: --debug --root= --uri= pm-download drupal-7.54 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2 ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history --variant=full ==> default: --cache --drupal-project-rename=__build__ 2>&1 [87.35 sec, 14.34 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --verbose --debug --root= [notice] ==> default: ==> default: --uri= pm-download drupal-7.54 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2 ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history --variant=full ==> default: --cache --drupal-project-rename=__build__ 2>&1 [87.35 sec, 14.34 MB] ==> default: Bootstrap to phase 0. [87.35 sec, 14.34 MB] [bootstrap] ==> default: ==> default: Trying to bootstrap as far as we can. [87.35 sec, 14.34 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.35 sec, 14.34 MB] [bootstrap] ==> default: ==> default: Loading version_control engine. [87.35 sec, 14.34 MB] [notice] ==> default: Loading package_handler engine. [87.35 sec, 14.34 MB] [notice] ==> default: ==> default: Loading release_info engine. [87.35 sec, 14.34 MB] [notice] ==> default: ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.35 sec, 14.35 ==> default: MB] ==> default: Loading apache driver for the http service [87.35 sec, 14.35 MB] [notice] ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: ==> default: [87.35 sec, 14.35 MB] ==> default: Calling hook drush_pm_download_validate [87.35 sec, 14.35 MB] [debug] ==> default: Returned from hook drush_pm_download_validate [87.35 sec, 14.35 MB] [debug] ==> default: Calling hook drush_pm_download [87.35 sec, 14.35 MB] [debug] ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/drupal/7.x [87.35 sec, ==> default: 14.35 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-drupal-7.x [notice] ==> default: retrieved from cache. [87.35 sec, 14.35 MB] ==> default: Downloading project drupal ... [87.35 sec, 14.35 MB] [notice] ==> default: Calling is_readable(/tmp/download_fileOPz10P) [87.35 sec, 14.35 MB] [debug] ==> default: Calling is_writable(/var/aegir/.drush/cache/download) [87.35 sec, [debug] ==> default: ==> default: 14.35 MB] ==> default: Calling rename(/tmp/download_fileOPz10P, [debug] ==> default: ==> default: /var/aegir/.drush/cache/download/https---ftp.drupal.org-files-projects-drupal-7.54.tar.gz) ==> default: [87.35 sec, 14.35 MB] ==> default: Downloading drupal-7.54.tar.gz was successful. [87.35 sec, 14.36 MB] [notice] ==> default: ==> default: Calling [debug] ==> default: md5_file(/tmp/drush_tmp_1487159310_58a4400e60442/drupal-7.54.tar.gz) ==> default: [87.35 sec, 14.36 MB] ==> default: Md5 checksum of drupal-7.54.tar.gz verified. [87.35 sec, 14.36 MB] [notice] ==> default: ==> default: Calling chdir(/tmp/drush_tmp_1487159310_58a4400e60442) [87.35 sec, [debug] ==> default: ==> default: 14.36 MB] ==> default: Calling chdir(/var/aegir) [87.35 sec, 14.36 MB] [debug] ==> default: ==> default: Calling chdir(/tmp/drush_tmp_1487159310_58a4400e60442) [87.35 sec, [debug] ==> default: 14.36 MB] ==> default: Calling chdir(/var/aegir) [87.35 sec, 14.36 MB] [debug] ==> default: ==> default: Verifying signature for svn version control engine. [87.35 sec, 14.36 [debug] ==> default: ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.35 sec, 14.36 [debug] ==> default: MB] ==> default: Calling [debug] ==> default: _drush_recursive_copy(/tmp/drush_tmp_1487159310_58a4400e60442/drupal-7.54, ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__) [87.35 sec, 14.36 ==> default: MB] ==> default: Project drupal (7.54) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__. [87.35 sec, 14.36 ==> default: MB] ==> default: Project drupal contains: [success] ==> default: ==> default: - 3 profiles: minimal, standard, testing ==> default: - 4 themes: stark, garland, bartik, seven ==> default: - 47 modules: menu, node, dashboard, forum, field_ui, contact, book, ==> default: profile, color, contextual, update, options, list, field_sql_storage, ==> default: number, text, field, search, overlay, statistics, file, toolbar, ==> default: filter, shortcut, rdf, image, blog, aggregator, openid, translation, ==> default: taxonomy, syslog, help, locale, tracker, comment, simpletest, ==> default: trigger, path, system, block, user, php, poll, dblog, ==> default: drupal_system_listing_compatible_test, ==> default: drupal_system_listing_incompatible_test ==> default: [87.35 sec, 14.36 MB] ==> default: Returned from hook drush_pm_download [87.35 sec, 14.37 MB] [debug] ==> default: Command dispatch complete [87.35 sec, 14.37 MB] [notice] ==> default: drupal-7.54 downloaded. [87.35 sec, 14.37 MB] [ok] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --root= --uri= [command] ==> default: make-process /tmp/make_tmp_1487159310_58a4400e0f8f2 2>&1 [87.36 sec, ==> default: 14.37 MB] ==> default: /usr/local/bin/drush --backend=2 --root= --uri= make-process [notice] ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2 2>&1 [87.36 sec, 14.37 MB] ==> default: Bootstrap to phase 0. [87.36 sec, 14.37 MB] [bootstrap] ==> default: Bootstrap to phase -1. [87.36 sec, 14.37 MB] [bootstrap] ==> default: Found command: make-process (commandfile=make) [87.36 sec, 14.37 MB] [bootstrap] ==> default: Loading release_info engine. [87.36 sec, 14.37 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.36 sec, 14.37 ==> default: MB] ==> default: Loading apache driver for the http service [87.36 sec, 14.37 MB] [notice] ==> default: Calling hook drush_make_process [87.36 sec, 14.38 MB] [debug] ==> default: hostmaster cloned from http://git.drupal.org/project/hostmaster.git. [ok] ==> default: [87.36 sec, 14.38 MB] ==> default: Checked out branch 7.x-3.x. [87.36 sec, 14.38 MB] [ok] ==> default: ==> default: Found makefile: hostmaster.make [87.36 sec, 14.38 MB] [ok] ==> default: ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/admin_menu/7.x [87.36 sec, ==> default: 14.38 MB] ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/betterlogin/7.x [87.36 ==> default: sec, 14.38 MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/ctools/7.x [87.36 sec, ==> default: ==> default: 14.38 MB] ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/entity/7.x [87.36 sec, ==> default: ==> default: 14.38 MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/openidadmin/7.x [87.36 ==> default: sec, 14.38 MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/overlay_paths/7.x [87.36 ==> default: sec, 14.38 MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/r4032login/7.x [87.36 sec, ==> default: 14.38 MB] ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/views/7.x [87.36 sec, ==> default: 14.38 MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/views_bulk_operations/7.x ==> default: [87.36 sec, 14.39 MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/libraries/7.x [87.36 sec, ==> default: 14.39 MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/tfa/7.x [87.36 sec, 14.39 ==> default: MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/tfa_basic/7.x [87.36 sec, ==> default: 14.39 MB] ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/devel/7.x [87.36 sec, ==> default: 14.39 MB] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/devel_debug_log/7.x [87.36 ==> default: sec, 14.39 MB] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --root= --uri= [command] ==> default: ==> default: make-process /tmp/make_tmp_1487159310_58a4400e0f8f2 2>&1 [87.36 sec, ==> default: 14.39 MB] ==> default: /usr/local/bin/drush --backend=2 --root= --uri= make-process [notice] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2 2>&1 [87.36 sec, 14.39 MB] ==> default: Bootstrap to phase 0. [87.36 sec, 14.39 MB] [bootstrap] ==> default: Bootstrap to phase -1. [87.36 sec, 14.39 MB] [bootstrap] ==> default: Found command: make-process (commandfile=make) [87.36 sec, 14.39 MB] [bootstrap] ==> default: ==> default: Loading release_info engine. [87.36 sec, 14.39 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.36 sec, 14.4 ==> default: MB] ==> default: Loading apache driver for the http service [87.36 sec, 14.4 MB] [notice] ==> default: ==> default: Calling hook drush_make_process [87.36 sec, 14.4 MB] [debug] ==> default: eldir cloned from http://git.drupal.org/project/eldir.git. [87.36 [ok] ==> default: ==> default: sec, 14.4 MB] ==> default: Checked out branch 7.x-3.x. [87.36 sec, 14.4 MB] [ok] ==> default: hosting cloned from http://git.drupal.org/project/hosting.git. [87.36 [ok] ==> default: sec, 14.4 MB] ==> default: Checked out branch 7.x-3.x. [87.36 sec, 14.4 MB] [ok] ==> default: ==> default: hosting_git cloned from [ok] ==> default: http://git.drupal.org/project/hosting_git.git. [87.36 sec, 14.4 MB] ==> default: Checked out branch 7.x-3.x. [87.36 sec, 14.4 MB] [ok] ==> default: hosting_remote_import cloned from [ok] ==> default: ==> default: http://git.drupal.org/project/hosting_remote_import.git. [87.36 sec, ==> default: 14.4 MB] ==> default: Checked out branch 7.x-3.x. [87.37 sec, 14.4 MB] [ok] ==> default: hosting_site_backup_manager cloned from [ok] ==> default: ==> default: http://git.drupal.org/project/hosting_site_backup_manager.git. [87.37 ==> default: sec, 14.4 MB] ==> default: Checked out branch 7.x-3.x. [87.37 sec, 14.41 MB] [ok] ==> default: ==> default: hosting_tasks_extra cloned from [ok] ==> default: http://git.drupal.org/project/hosting_tasks_extra.git. [87.37 sec, ==> default: 14.41 MB] ==> default: Checked out branch 7.x-3.x. [87.37 sec, 14.41 MB] [ok] ==> default: hosting_civicrm cloned from [ok] ==> default: ==> default: http://git.drupal.org/project/hosting_civicrm.git. [87.37 sec, 14.41 ==> default: MB] ==> default: Checked out branch 7.x-3.x. [87.37 sec, 14.41 MB] [ok] ==> default: ==> default: hosting_dns cloned from [ok] ==> default: ==> default: http://git.drupal.org/project/hosting_dns.git. [87.37 sec, 14.41 MB] ==> default: Checked out branch 7.x-3.x. [87.37 sec, 14.41 MB] [ok] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: --uri= pm-download admin_menu-7.x-3.0-rc5 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.37 sec, 14.41 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: admin_menu-7.x-3.0-rc5 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.37 sec, 14.41 MB] ==> default: Bootstrap to phase 0. [87.37 sec, 14.41 MB] [bootstrap] ==> default: ==> default: Trying to bootstrap as far as we can. [87.37 sec, 14.41 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.37 sec, 14.41 MB] [bootstrap] ==> default: Loading version_control engine. [87.37 sec, 14.42 MB] [notice] ==> default: ==> default: Loading package_handler engine. [87.37 sec, 14.42 MB] [notice] ==> default: ==> default: Loading release_info engine. [87.37 sec, 14.42 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.37 sec, 14.42 ==> default: MB] ==> default: Loading apache driver for the http service [87.37 sec, 14.42 MB] [notice] ==> default: ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: [87.37 sec, 14.42 MB] ==> default: Calling hook drush_pm_download_validate [87.37 sec, 14.42 MB] [debug] ==> default: ==> default: Returned from hook drush_pm_download_validate [87.37 sec, 14.42 MB] [debug] ==> default: ==> default: Calling hook drush_pm_download [87.37 sec, 14.42 MB] [debug] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/admin_menu/7.x [87.37 sec, ==> default: 14.42 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-admin_menu-7.x [notice] ==> default: ==> default: retrieved from cache. [87.37 sec, 14.42 MB] ==> default: Downloading project admin_menu ... [87.37 sec, 14.42 MB] [notice] ==> default: ==> default: Downloading admin_menu-7.x-3.0-rc5.tar.gz was successful. [87.37 sec, [notice] ==> default: 14.43 MB] ==> default: Md5 checksum of admin_menu-7.x-3.0-rc5.tar.gz verified. [87.37 sec, [notice] ==> default: ==> default: 14.43 MB] ==> default: Verifying signature for svn version control engine. [87.37 sec, 14.43 [debug] ==> default: ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.37 sec, 14.43 [debug] ==> default: MB] ==> default: Project admin_menu (7.x-3.0-rc5) downloaded to [success] ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/admin_menu. ==> default: [87.37 sec, 14.43 MB] ==> default: Returned from hook drush_pm_download [87.37 sec, 14.43 MB] [debug] ==> default: ==> default: Command dispatch complete [87.37 sec, 14.43 MB] [notice] ==> default: admin_menu-7.x-3.0-rc5 downloaded. [87.37 sec, 14.43 MB] [ok] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: ==> default: --uri= pm-download betterlogin-7.x-1.4 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.37 sec, 14.43 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: betterlogin-7.x-1.4 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.37 sec, 14.43 MB] ==> default: Bootstrap to phase 0. [87.37 sec, 14.43 MB] [bootstrap] ==> default: Trying to bootstrap as far as we can. [87.37 sec, 14.44 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.37 sec, 14.44 MB] [bootstrap] ==> default: ==> default: Loading version_control engine. [87.37 sec, 14.44 MB] [notice] ==> default: Loading package_handler engine. [87.37 sec, 14.44 MB] [notice] ==> default: ==> default: Loading release_info engine. [87.37 sec, 14.44 MB] [notice] ==> default: ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.37 sec, 14.44 ==> default: MB] ==> default: Loading apache driver for the http service [87.37 sec, 14.44 MB] [notice] ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: [87.37 sec, 14.44 MB] ==> default: Calling hook drush_pm_download_validate [87.37 sec, 14.44 MB] [debug] ==> default: Returned from hook drush_pm_download_validate [87.37 sec, 14.44 MB] [debug] ==> default: Calling hook drush_pm_download [87.38 sec, 14.44 MB] [debug] ==> default: ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/betterlogin/7.x [87.38 ==> default: sec, 14.44 MB] ==> default: ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-betterlogin-7.x [notice] ==> default: retrieved from cache. [87.38 sec, 14.45 MB] ==> default: Downloading project betterlogin ... [87.38 sec, 14.45 MB] [notice] ==> default: ==> default: Downloading betterlogin-7.x-1.4.tar.gz was successful. [87.38 sec, [notice] ==> default: 14.45 MB] ==> default: ==> default: Md5 checksum of betterlogin-7.x-1.4.tar.gz verified. [87.38 sec, [notice] ==> default: 14.45 MB] ==> default: Verifying signature for svn version control engine. [87.38 sec, 14.45 [debug] ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.38 sec, 14.45 [debug] ==> default: MB] ==> default: Project betterlogin (7.x-1.4) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/betterlogin. ==> default: [87.38 sec, 14.45 MB] ==> default: Returned from hook drush_pm_download [87.38 sec, 14.45 MB] [debug] ==> default: Command dispatch complete [87.38 sec, 14.45 MB] [notice] ==> default: ==> default: betterlogin-7.x-1.4 downloaded. [87.38 sec, 14.45 MB] [ok] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: --uri= pm-download ctools-7.x-1.12 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.38 sec, 14.45 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: ==> default: ctools-7.x-1.12 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.38 sec, 14.45 MB] ==> default: Bootstrap to phase 0. [87.38 sec, 14.46 MB] [bootstrap] ==> default: ==> default: Trying to bootstrap as far as we can. [87.38 sec, 14.46 MB] [debug] ==> default: Found command: pm-download (commandfile=pm) [87.38 sec, 14.46 MB] [bootstrap] ==> default: Loading version_control engine. [87.38 sec, 14.46 MB] [notice] ==> default: Loading package_handler engine. [87.38 sec, 14.46 MB] [notice] ==> default: ==> default: Loading release_info engine. [87.38 sec, 14.46 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.38 sec, 14.46 ==> default: MB] ==> default: Loading apache driver for the http service [87.38 sec, 14.46 MB] [notice] ==> default: ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: [87.38 sec, 14.46 MB] ==> default: Calling hook drush_pm_download_validate [87.38 sec, 14.46 MB] [debug] ==> default: ==> default: Returned from hook drush_pm_download_validate [87.38 sec, 14.46 MB] [debug] ==> default: ==> default: Calling hook drush_pm_download [87.38 sec, 14.46 MB] [debug] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/ctools/7.x [87.38 sec, ==> default: 14.47 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-ctools-7.x [notice] ==> default: ==> default: retrieved from cache. [87.38 sec, 14.47 MB] ==> default: Downloading project ctools ... [87.38 sec, 14.47 MB] [notice] ==> default: Downloading ctools-7.x-1.12.tar.gz was successful. [87.38 sec, 14.47 [notice] ==> default: ==> default: MB] ==> default: Md5 checksum of ctools-7.x-1.12.tar.gz verified. [87.38 sec, 14.47 [notice] ==> default: ==> default: MB] ==> default: Verifying signature for svn version control engine. [87.38 sec, 14.47 [debug] ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.38 sec, 14.47 [debug] ==> default: ==> default: MB] ==> default: Project ctools (7.x-1.12) downloaded to [success] ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/ctools. ==> default: [87.38 sec, 14.47 MB] ==> default: Returned from hook drush_pm_download [87.38 sec, 14.47 MB] [debug] ==> default: Command dispatch complete [87.38 sec, 14.47 MB] [notice] ==> default: ctools-7.x-1.12 downloaded. [87.38 sec, 14.47 MB] [ok] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: --uri= pm-download entity-7.x-1.8 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.38 sec, 14.47 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: entity-7.x-1.8 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.38 sec, 14.48 MB] ==> default: Bootstrap to phase 0. [87.38 sec, 14.48 MB] [bootstrap] ==> default: ==> default: Trying to bootstrap as far as we can. [87.38 sec, 14.48 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.38 sec, 14.48 MB] [bootstrap] ==> default: Loading version_control engine. [87.38 sec, 14.48 MB] [notice] ==> default: ==> default: Loading package_handler engine. [87.38 sec, 14.48 MB] [notice] ==> default: Loading release_info engine. [87.38 sec, 14.48 MB] [notice] ==> default: ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.38 sec, 14.48 ==> default: MB] ==> default: Loading apache driver for the http service [87.38 sec, 14.48 MB] [notice] ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: ==> default: [87.38 sec, 14.48 MB] ==> default: Calling hook drush_pm_download_validate [87.38 sec, 14.48 MB] [debug] ==> default: ==> default: Returned from hook drush_pm_download_validate [87.38 sec, 14.49 MB] [debug] ==> default: Calling hook drush_pm_download [87.39 sec, 14.49 MB] [debug] ==> default: ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/entity/7.x [87.39 sec, ==> default: 14.49 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-entity-7.x [notice] ==> default: ==> default: retrieved from cache. [87.39 sec, 14.49 MB] ==> default: Downloading project entity ... [87.39 sec, 14.49 MB] [notice] ==> default: Downloading entity-7.x-1.8.tar.gz was successful. [87.39 sec, 14.49 [notice] ==> default: ==> default: MB] ==> default: Md5 checksum of entity-7.x-1.8.tar.gz verified. [87.39 sec, 14.49 MB] [notice] ==> default: ==> default: Verifying signature for svn version control engine. [87.39 sec, 14.49 [debug] ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.39 sec, 14.49 [debug] ==> default: MB] ==> default: Project entity (7.x-1.8) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/entity. ==> default: [87.39 sec, 14.49 MB] ==> default: Returned from hook drush_pm_download [87.39 sec, 14.49 MB] [debug] ==> default: Command dispatch complete [87.39 sec, 14.49 MB] [notice] ==> default: ==> default: entity-7.x-1.8 downloaded. [87.39 sec, 14.5 MB] [ok] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: --uri= pm-download openidadmin-7.x-1.0 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.39 sec, 14.5 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: ==> default: openidadmin-7.x-1.0 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.39 sec, 14.5 MB] ==> default: Bootstrap to phase 0. [87.39 sec, 14.5 MB] [bootstrap] ==> default: ==> default: Trying to bootstrap as far as we can. [87.39 sec, 14.5 MB] [debug] ==> default: Found command: pm-download (commandfile=pm) [87.39 sec, 14.5 MB] [bootstrap] ==> default: ==> default: Loading version_control engine. [87.39 sec, 14.5 MB] [notice] ==> default: ==> default: Loading package_handler engine. [87.39 sec, 14.5 MB] [notice] ==> default: Loading release_info engine. [87.39 sec, 14.5 MB] [notice] ==> default: ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.39 sec, 14.5 ==> default: MB] ==> default: Loading apache driver for the http service [87.39 sec, 14.5 MB] [notice] ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: ==> default: [87.39 sec, 14.5 MB] ==> default: Calling hook drush_pm_download_validate [87.39 sec, 14.51 MB] [debug] ==> default: ==> default: Returned from hook drush_pm_download_validate [87.39 sec, 14.51 MB] [debug] ==> default: Calling hook drush_pm_download [87.39 sec, 14.51 MB] [debug] ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/openidadmin/7.x [87.39 ==> default: sec, 14.51 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-openidadmin-7.x [notice] ==> default: ==> default: retrieved from cache. [87.39 sec, 14.51 MB] ==> default: Downloading project openidadmin ... [87.39 sec, 14.51 MB] [notice] ==> default: ==> default: Downloading openidadmin-7.x-1.0.tar.gz was successful. [87.39 sec, [notice] ==> default: 14.51 MB] ==> default: Md5 checksum of openidadmin-7.x-1.0.tar.gz verified. [87.39 sec, [notice] ==> default: ==> default: 14.51 MB] ==> default: Verifying signature for svn version control engine. [87.39 sec, 14.51 [debug] ==> default: ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.39 sec, 14.51 [debug] ==> default: MB] ==> default: Project openidadmin (7.x-1.0) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/openidadmin. ==> default: [87.39 sec, 14.51 MB] ==> default: Returned from hook drush_pm_download [87.39 sec, 14.51 MB] [debug] ==> default: ==> default: Command dispatch complete [87.39 sec, 14.52 MB] [notice] ==> default: openidadmin-7.x-1.0 downloaded. [87.39 sec, 14.52 MB] [ok] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: ==> default: --uri= pm-download overlay_paths-7.x-1.3 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.39 sec, 14.52 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: overlay_paths-7.x-1.3 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.39 sec, 14.52 MB] ==> default: Bootstrap to phase 0. [87.39 sec, 14.52 MB] [bootstrap] ==> default: Trying to bootstrap as far as we can. [87.39 sec, 14.52 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.39 sec, 14.52 MB] [bootstrap] ==> default: Loading version_control engine. [87.39 sec, 14.52 MB] [notice] ==> default: ==> default: Loading package_handler engine. [87.39 sec, 14.52 MB] [notice] ==> default: Loading release_info engine. [87.39 sec, 14.52 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.39 sec, 14.52 ==> default: MB] ==> default: Loading apache driver for the http service [87.39 sec, 14.52 MB] [notice] ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: [87.39 sec, 14.53 MB] ==> default: Calling hook drush_pm_download_validate [87.4 sec, 14.53 MB] [debug] ==> default: Returned from hook drush_pm_download_validate [87.4 sec, 14.53 MB] [debug] ==> default: Calling hook drush_pm_download [87.4 sec, 14.53 MB] [debug] ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/overlay_paths/7.x [87.4 ==> default: sec, 14.53 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-overlay_paths-7.x [notice] ==> default: retrieved from cache. [87.4 sec, 14.53 MB] ==> default: Downloading project overlay_paths ... [87.4 sec, 14.53 MB] [notice] ==> default: ==> default: Downloading overlay_paths-7.x-1.3.tar.gz was successful. [87.4 sec, [notice] ==> default: 14.54 MB] ==> default: Md5 checksum of overlay_paths-7.x-1.3.tar.gz verified. [87.4 sec, [notice] ==> default: ==> default: 14.54 MB] ==> default: Verifying signature for svn version control engine. [87.4 sec, 14.54 [debug] ==> default: ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.4 sec, 14.54 [debug] ==> default: MB] ==> default: Project overlay_paths (7.x-1.3) downloaded to [success] ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/overlay_paths. ==> default: [87.4 sec, 14.54 MB] ==> default: Returned from hook drush_pm_download [87.4 sec, 14.54 MB] [debug] ==> default: ==> default: Command dispatch complete [87.4 sec, 14.54 MB] [notice] ==> default: ==> default: overlay_paths-7.x-1.3 downloaded. [87.4 sec, 14.54 MB] [ok] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: ==> default: --uri= pm-download r4032login-7.x-1.8 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.4 sec, 14.54 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: ==> default: r4032login-7.x-1.8 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.4 sec, 14.54 MB] ==> default: Bootstrap to phase 0. [87.4 sec, 14.54 MB] [bootstrap] ==> default: ==> default: Trying to bootstrap as far as we can. [87.4 sec, 14.54 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.4 sec, 14.55 MB] [bootstrap] ==> default: ==> default: Loading version_control engine. [87.4 sec, 14.55 MB] [notice] ==> default: Loading package_handler engine. [87.4 sec, 14.55 MB] [notice] ==> default: ==> default: Loading release_info engine. [87.4 sec, 14.55 MB] [notice] ==> default: ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.4 sec, 14.55 ==> default: MB] ==> default: Loading apache driver for the http service [87.4 sec, 14.55 MB] [notice] ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: ==> default: [87.4 sec, 14.55 MB] ==> default: Calling hook drush_pm_download_validate [87.4 sec, 14.55 MB] [debug] ==> default: ==> default: Returned from hook drush_pm_download_validate [87.4 sec, 14.55 MB] [debug] ==> default: Calling hook drush_pm_download [87.4 sec, 14.55 MB] [debug] ==> default: ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/r4032login/7.x [87.4 sec, ==> default: 14.55 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-r4032login-7.x [notice] ==> default: retrieved from cache. [87.4 sec, 14.56 MB] ==> default: Downloading project r4032login ... [87.4 sec, 14.56 MB] [notice] ==> default: ==> default: Downloading r4032login-7.x-1.8.tar.gz was successful. [87.4 sec, [notice] ==> default: ==> default: 14.56 MB] ==> default: Md5 checksum of r4032login-7.x-1.8.tar.gz verified. [87.4 sec, 14.56 [notice] ==> default: MB] ==> default: ==> default: Verifying signature for svn version control engine. [87.4 sec, 14.56 [debug] ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.4 sec, 14.56 [debug] ==> default: ==> default: MB] ==> default: Project r4032login (7.x-1.8) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/r4032login. ==> default: [87.4 sec, 14.56 MB] ==> default: Returned from hook drush_pm_download [87.4 sec, 14.56 MB] [debug] ==> default: ==> default: Command dispatch complete [87.4 sec, 14.56 MB] [notice] ==> default: r4032login-7.x-1.8 downloaded. [87.4 sec, 14.56 MB] [ok] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: ==> default: --uri= pm-download views-7.x-3.14 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.4 sec, 14.56 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: ==> default: views-7.x-3.14 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.41 sec, 14.56 MB] ==> default: Bootstrap to phase 0. [87.41 sec, 14.57 MB] [bootstrap] ==> default: ==> default: Trying to bootstrap as far as we can. [87.41 sec, 14.57 MB] [debug] ==> default: Found command: pm-download (commandfile=pm) [87.41 sec, 14.57 MB] [bootstrap] ==> default: ==> default: Loading version_control engine. [87.41 sec, 14.57 MB] [notice] ==> default: ==> default: Loading package_handler engine. [87.41 sec, 14.57 MB] [notice] ==> default: ==> default: Loading release_info engine. [87.41 sec, 14.57 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.41 sec, 14.57 ==> default: MB] ==> default: Loading apache driver for the http service [87.41 sec, 14.57 MB] [notice] ==> default: ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: ==> default: [87.41 sec, 14.57 MB] ==> default: Calling hook drush_pm_download_validate [87.41 sec, 14.57 MB] [debug] ==> default: Returned from hook drush_pm_download_validate [87.41 sec, 14.57 MB] [debug] ==> default: ==> default: Calling hook drush_pm_download [87.41 sec, 14.57 MB] [debug] ==> default: ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/views/7.x [87.41 sec, ==> default: 14.58 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-views-7.x [notice] ==> default: ==> default: retrieved from cache. [87.41 sec, 14.58 MB] ==> default: Downloading project views ... [87.41 sec, 14.58 MB] [notice] ==> default: ==> default: Downloading views-7.x-3.14.tar.gz was successful. [87.41 sec, 14.58 [notice] ==> default: ==> default: MB] ==> default: Md5 checksum of views-7.x-3.14.tar.gz verified. [87.41 sec, 14.58 MB] [notice] ==> default: Verifying signature for svn version control engine. [87.41 sec, 14.58 [debug] ==> default: ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.41 sec, 14.58 [debug] ==> default: ==> default: MB] ==> default: Project views (7.x-3.14) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/views. ==> default: [87.41 sec, 14.58 MB] ==> default: Returned from hook drush_pm_download [87.41 sec, 14.58 MB] [debug] ==> default: Command dispatch complete [87.41 sec, 14.58 MB] [notice] ==> default: ==> default: views-7.x-3.14 downloaded. [87.41 sec, 14.58 MB] [ok] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: ==> default: --uri= pm-download views_bulk_operations-7.x-3.3 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.41 sec, 14.58 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: ==> default: views_bulk_operations-7.x-3.3 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.41 sec, 14.59 MB] ==> default: Bootstrap to phase 0. [87.41 sec, 14.59 MB] [bootstrap] ==> default: ==> default: Trying to bootstrap as far as we can. [87.41 sec, 14.59 MB] [debug] ==> default: Found command: pm-download (commandfile=pm) [87.41 sec, 14.59 MB] [bootstrap] ==> default: ==> default: Loading version_control engine. [87.41 sec, 14.59 MB] [notice] ==> default: Loading package_handler engine. [87.41 sec, 14.59 MB] [notice] ==> default: ==> default: Loading release_info engine. [87.41 sec, 14.59 MB] [notice] ==> default: ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.41 sec, 14.59 ==> default: MB] ==> default: Loading apache driver for the http service [87.41 sec, 14.59 MB] [notice] ==> default: ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: ==> default: [87.41 sec, 14.59 MB] ==> default: Calling hook drush_pm_download_validate [87.41 sec, 14.59 MB] [debug] ==> default: Returned from hook drush_pm_download_validate [87.41 sec, 14.59 MB] [debug] ==> default: Calling hook drush_pm_download [87.41 sec, 14.6 MB] [debug] ==> default: ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/views_bulk_operations/7.x ==> default: [87.41 sec, 14.6 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-views_bulk_operations-7.x [notice] ==> default: retrieved from cache. [87.41 sec, 14.6 MB] ==> default: ==> default: Downloading project views_bulk_operations ... [87.41 sec, 14.6 MB] [notice] ==> default: Downloading views_bulk_operations-7.x-3.3.tar.gz was successful. [notice] ==> default: ==> default: [87.41 sec, 14.6 MB] ==> default: Md5 checksum of views_bulk_operations-7.x-3.3.tar.gz verified. [87.41 [notice] ==> default: sec, 14.6 MB] ==> default: Verifying signature for svn version control engine. [87.41 sec, 14.6 [debug] ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.41 sec, 14.6 [debug] ==> default: MB] ==> default: Project views_bulk_operations (7.x-3.3) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/views_bulk_operations. ==> default: [87.41 sec, 14.6 MB] ==> default: Returned from hook drush_pm_download [87.42 sec, 14.6 MB] [debug] ==> default: Command dispatch complete [87.42 sec, 14.6 MB] [notice] ==> default: ==> default: views_bulk_operations-7.x-3.3 downloaded. [87.42 sec, 14.61 MB] [ok] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: ==> default: --uri= pm-download libraries-7.x-2.3 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.42 sec, 14.61 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: ==> default: libraries-7.x-2.3 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.42 sec, 14.61 MB] ==> default: Bootstrap to phase 0. [87.42 sec, 14.61 MB] [bootstrap] ==> default: Trying to bootstrap as far as we can. [87.42 sec, 14.61 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.42 sec, 14.61 MB] [bootstrap] ==> default: Loading version_control engine. [87.42 sec, 14.61 MB] [notice] ==> default: ==> default: Loading package_handler engine. [87.42 sec, 14.61 MB] [notice] ==> default: ==> default: Loading release_info engine. [87.42 sec, 14.61 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.42 sec, 14.61 ==> default: MB] ==> default: Loading apache driver for the http service [87.42 sec, 14.61 MB] [notice] ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: ==> default: [87.42 sec, 14.61 MB] ==> default: Calling hook drush_pm_download_validate [87.42 sec, 14.62 MB] [debug] ==> default: ==> default: Returned from hook drush_pm_download_validate [87.42 sec, 14.62 MB] [debug] ==> default: ==> default: Calling hook drush_pm_download [87.42 sec, 14.62 MB] [debug] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/libraries/7.x [87.42 sec, ==> default: 14.62 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-libraries-7.x [notice] ==> default: ==> default: retrieved from cache. [87.42 sec, 14.62 MB] ==> default: Downloading project libraries ... [87.42 sec, 14.62 MB] [notice] ==> default: Downloading libraries-7.x-2.3.tar.gz was successful. [87.42 sec, [notice] ==> default: ==> default: 14.62 MB] ==> default: Md5 checksum of libraries-7.x-2.3.tar.gz verified. [87.42 sec, 14.62 [notice] ==> default: ==> default: MB] ==> default: Verifying signature for svn version control engine. [87.42 sec, 14.62 [debug] ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.42 sec, 14.62 [debug] ==> default: ==> default: MB] ==> default: Project libraries (7.x-2.3) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/libraries. ==> default: [87.42 sec, 14.62 MB] ==> default: Returned from hook drush_pm_download [87.42 sec, 14.62 MB] [debug] ==> default: Command dispatch complete [87.42 sec, 14.63 MB] [notice] ==> default: ==> default: libraries-7.x-2.3 downloaded. [87.42 sec, 14.63 MB] [ok] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: ==> default: --uri= pm-download tfa-7.x-2.0 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.42 sec, 14.63 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: ==> default: tfa-7.x-2.0 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.42 sec, 14.63 MB] ==> default: Bootstrap to phase 0. [87.42 sec, 14.63 MB] [bootstrap] ==> default: Trying to bootstrap as far as we can. [87.42 sec, 14.63 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.42 sec, 14.63 MB] [bootstrap] ==> default: ==> default: Loading version_control engine. [87.42 sec, 14.63 MB] [notice] ==> default: Loading package_handler engine. [87.42 sec, 14.63 MB] [notice] ==> default: ==> default: Loading release_info engine. [87.42 sec, 14.63 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.42 sec, 14.63 ==> default: MB] ==> default: Loading apache driver for the http service [87.42 sec, 14.63 MB] [notice] ==> default: ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: [87.42 sec, 14.64 MB] ==> default: Calling hook drush_pm_download_validate [87.42 sec, 14.64 MB] [debug] ==> default: ==> default: Returned from hook drush_pm_download_validate [87.42 sec, 14.64 MB] [debug] ==> default: ==> default: Calling hook drush_pm_download [87.42 sec, 14.64 MB] [debug] ==> default: ==> default: Downloading release history from [notice] ==> default: https://updates.drupal.org/release-history/tfa/7.x [87.42 sec, 14.64 ==> default: MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-tfa-7.x [notice] ==> default: ==> default: retrieved from cache. [87.42 sec, 14.64 MB] ==> default: Downloading project tfa ... [87.42 sec, 14.64 MB] [notice] ==> default: ==> default: Downloading tfa-7.x-2.0.tar.gz was successful. [87.42 sec, 14.64 MB] [notice] ==> default: Md5 checksum of tfa-7.x-2.0.tar.gz verified. [87.43 sec, 14.64 MB] [notice] ==> default: ==> default: Verifying signature for svn version control engine. [87.43 sec, 14.64 [debug] ==> default: MB] ==> default: ==> default: Verifying signature for bzr version control engine. [87.43 sec, 14.64 [debug] ==> default: MB] ==> default: Project tfa (7.x-2.0) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/tfa. ==> default: [87.43 sec, 14.64 MB] ==> default: Returned from hook drush_pm_download [87.43 sec, 14.65 MB] [debug] ==> default: ==> default: Command dispatch complete [87.43 sec, 14.65 MB] [notice] ==> default: tfa-7.x-2.0 downloaded. [87.43 sec, 14.65 MB] [ok] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: --uri= pm-download tfa_basic-7.x-1.0 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.43 sec, 14.65 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: tfa_basic-7.x-1.0 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.43 sec, 14.65 MB] ==> default: Bootstrap to phase 0. [87.43 sec, 14.65 MB] [bootstrap] ==> default: ==> default: Trying to bootstrap as far as we can. [87.43 sec, 14.65 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.43 sec, 14.65 MB] [bootstrap] ==> default: Loading version_control engine. [87.43 sec, 14.65 MB] [notice] ==> default: ==> default: Loading package_handler engine. [87.43 sec, 14.65 MB] [notice] ==> default: Loading release_info engine. [87.43 sec, 14.65 MB] [notice] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.43 sec, 14.65 ==> default: MB] ==> default: Loading apache driver for the http service [87.43 sec, 14.66 MB] [notice] ==> default: ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: [87.43 sec, 14.66 MB] ==> default: Calling hook drush_pm_download_validate [87.43 sec, 14.66 MB] [debug] ==> default: ==> default: Returned from hook drush_pm_download_validate [87.43 sec, 14.66 MB] [debug] ==> default: ==> default: Calling hook drush_pm_download [87.43 sec, 14.66 MB] [debug] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/tfa_basic/7.x [87.43 sec, ==> default: 14.66 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-tfa_basic-7.x [notice] ==> default: ==> default: retrieved from cache. [87.43 sec, 14.66 MB] ==> default: Downloading project tfa_basic ... [87.43 sec, 14.66 MB] [notice] ==> default: Downloading tfa_basic-7.x-1.0.tar.gz was successful. [87.43 sec, [notice] ==> default: ==> default: 14.66 MB] ==> default: Md5 checksum of tfa_basic-7.x-1.0.tar.gz verified. [87.43 sec, 14.66 [notice] ==> default: ==> default: MB] ==> default: Verifying signature for svn version control engine. [87.43 sec, 14.66 [debug] ==> default: MB] ==> default: ==> default: Verifying signature for bzr version control engine. [87.43 sec, 14.67 [debug] ==> default: MB] ==> default: Project tfa_basic (7.x-1.0) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/contrib/tfa_basic. ==> default: [87.43 sec, 14.67 MB] ==> default: Returned from hook drush_pm_download [87.43 sec, 14.67 MB] [debug] ==> default: ==> default: Command dispatch complete [87.43 sec, 14.67 MB] [notice] ==> default: ==> default: tfa_basic-7.x-1.0 downloaded. [87.43 sec, 14.67 MB] [ok] ==> default: Checking patch includes/tfa_totp.inc... [notice] ==> default: ==> default: Checking patch tfa_basic.info... ==> default: Checking patch tfa_basic.module... ==> default: Applied patch includes/tfa_totp.inc cleanly. ==> default: Applied patch tfa_basic.info cleanly. ==> default: Applied patch tfa_basic.module cleanly. [87.43 sec, 14.67 MB] ==> default: tfa_basic patched with use_libraries_module-2807953-8.patch. [87.43 [ok] ==> default: ==> default: sec, 14.67 MB] ==> default: Generated PATCHES.txt file for tfa_basic [87.43 sec, 14.67 MB] [ok] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: ==> default: --uri= pm-download devel-7.x-1.5 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/developer ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.43 sec, 14.67 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: ==> default: devel-7.x-1.5 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/developer ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.43 sec, 14.67 MB] ==> default: Bootstrap to phase 0. [87.43 sec, 14.67 MB] [bootstrap] ==> default: Trying to bootstrap as far as we can. [87.43 sec, 14.67 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.43 sec, 14.68 MB] [bootstrap] ==> default: ==> default: Loading version_control engine. [87.43 sec, 14.68 MB] [notice] ==> default: ==> default: Loading package_handler engine. [87.43 sec, 14.68 MB] [notice] ==> default: Loading release_info engine. [87.43 sec, 14.68 MB] [notice] ==> default: ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.43 sec, 14.68 ==> default: ==> default: MB] ==> default: Loading apache driver for the http service [87.44 sec, 14.68 MB] [notice] ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: ==> default: [87.44 sec, 14.68 MB] ==> default: Calling hook drush_pm_download_validate [87.44 sec, 14.68 MB] [debug] ==> default: ==> default: Returned from hook drush_pm_download_validate [87.44 sec, 14.68 MB] [debug] ==> default: ==> default: Calling hook drush_pm_download [87.44 sec, 14.68 MB] [debug] ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/devel/7.x [87.44 sec, ==> default: 14.68 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-devel-7.x [notice] ==> default: ==> default: retrieved from cache. [87.44 sec, 14.68 MB] ==> default: Downloading project devel ... [87.44 sec, 14.69 MB] [notice] ==> default: ==> default: Downloading devel-7.x-1.5.tar.gz was successful. [87.44 sec, 14.69 [notice] ==> default: MB] ==> default: Md5 checksum of devel-7.x-1.5.tar.gz verified. [87.44 sec, 14.69 MB] [notice] ==> default: ==> default: Verifying signature for svn version control engine. [87.44 sec, 14.69 [debug] ==> default: ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.44 sec, 14.69 [debug] ==> default: ==> default: MB] ==> default: Project devel (7.x-1.5) downloaded to [success] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/developer/devel. ==> default: [87.44 sec, 14.69 MB] ==> default: Returned from hook drush_pm_download [87.44 sec, 14.69 MB] [debug] ==> default: Command dispatch complete [87.44 sec, 14.69 MB] [notice] ==> default: ==> default: devel-7.x-1.5 downloaded. [87.44 sec, 14.69 MB] [ok] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --yes --root= [command] ==> default: --uri= pm-download devel_debug_log-7.x-1.2 ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/developer ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.44 sec, 14.69 MB] ==> default: /usr/local/bin/drush --backend=2 --yes --root= --uri= pm-download [notice] ==> default: devel_debug_log-7.x-1.2 ==> default: ==> default: --destination=/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/developer ==> default: --package-handler=wget ==> default: --source=https://updates.drupal.org/release-history ==> default: --variant=profile-only --cache 2>&1 [87.44 sec, 14.69 MB] ==> default: Bootstrap to phase 0. [87.44 sec, 14.69 MB] [bootstrap] ==> default: Trying to bootstrap as far as we can. [87.44 sec, 14.7 MB] [debug] ==> default: ==> default: Found command: pm-download (commandfile=pm) [87.44 sec, 14.7 MB] [bootstrap] ==> default: ==> default: Loading version_control engine. [87.44 sec, 14.7 MB] [notice] ==> default: ==> default: Loading package_handler engine. [87.44 sec, 14.7 MB] [notice] ==> default: Loading release_info engine. [87.44 sec, 14.7 MB] [notice] ==> default: ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.44 sec, 14.7 ==> default: MB] ==> default: Loading apache driver for the http service [87.44 sec, 14.7 MB] [notice] ==> default: ==> default: Including phar:///usr/local/bin/drush/commands/pm/download.pm.inc [bootstrap] ==> default: [87.44 sec, 14.7 MB] ==> default: ==> default: Calling hook drush_pm_download_validate [87.44 sec, 14.7 MB] [debug] ==> default: Returned from hook drush_pm_download_validate [87.44 sec, 14.7 MB] [debug] ==> default: ==> default: Calling hook drush_pm_download [87.44 sec, 14.7 MB] [debug] ==> default: ==> default: Downloading release history from [notice] ==> default: ==> default: https://updates.drupal.org/release-history/devel_debug_log/7.x [87.44 ==> default: sec, 14.7 MB] ==> default: /var/aegir/.drush/cache/download/https---updates.drupal.org-release-history-devel_debug_log-7.x [notice] ==> default: retrieved from cache. [87.44 sec, 14.71 MB] ==> default: Downloading project devel_debug_log ... [87.44 sec, 14.71 MB] [notice] ==> default: ==> default: Downloading devel_debug_log-7.x-1.2.tar.gz was successful. [87.44 [notice] ==> default: ==> default: sec, 14.71 MB] ==> default: Md5 checksum of devel_debug_log-7.x-1.2.tar.gz verified. [87.44 sec, [notice] ==> default: 14.71 MB] ==> default: Verifying signature for svn version control engine. [87.44 sec, 14.71 [debug] ==> default: ==> default: MB] ==> default: Verifying signature for bzr version control engine. [87.44 sec, 14.71 [debug] ==> default: ==> default: MB] ==> default: Project devel_debug_log (7.x-1.2) downloaded to [success] ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/__build__/profiles/hostmaster/modules/developer/devel_debug_log. ==> default: ==> default: [87.44 sec, 14.71 MB] ==> default: Returned from hook drush_pm_download [87.44 sec, 14.71 MB] [debug] ==> default: Command dispatch complete [87.44 sec, 14.71 MB] [notice] ==> default: ==> default: devel_debug_log-7.x-1.2 downloaded. [87.44 sec, 14.71 MB] [ok] ==> default: ==> default: Returned from hook drush_make_process [87.44 sec, 14.71 MB] [debug] ==> default: ==> default: Command dispatch complete [87.44 sec, 14.72 MB] [notice] ==> default: Undefined property: DrushMakeProject_Library::$type [notice] ==> default: ==> default: make.project.inc:165 [87.44 sec, 14.72 MB] ==> default: qrcodejs cloned from https://github.com/davidshimjs/qrcodejs.git. [ok] ==> default: ==> default: [87.44 sec, 14.72 MB] ==> default: Checked out revision 04f46c6a0708418cb7b96fc563eacae0fbf77674. [87.44 [ok] ==> default: sec, 14.72 MB] ==> default: Returned from hook drush_make_process [87.44 sec, 14.72 MB] [debug] ==> default: ==> default: Command dispatch complete [87.45 sec, 14.72 MB] [notice] ==> default: ==> default: Calling is_readable(/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__) [debug] ==> default: ==> default: [87.45 sec, 14.72 MB] ==> default: Calling is_writable(/tmp/make_tmp_1487159310_58a4400e0f8f2) [87.45 [debug] ==> default: sec, 14.72 MB] ==> default: Calling rename(/tmp/make_tmp_1487159310_58a4400e0f8f2/__build__, [debug] ==> default: ==> default: /tmp/make_tmp_1487159310_58a4400e0f8f2/hostmaster-7.x-3.x) [87.45 ==> default: sec, 14.72 MB] ==> default: Calling [debug] ==> default: ==> default: _drush_recursive_copy(/tmp/make_tmp_1487159310_58a4400e0f8f2/hostmaster-7.x-3.x, ==> default: /var/aegir/hostmaster-7.x-3.x) [87.45 sec, 14.72 MB] ==> default: Returned from hook drush_make [87.45 sec, 14.72 MB] [debug] ==> default: ==> default: Command dispatch complete [87.45 sec, 14.72 MB] [notice] ==> default: ==> default: Drush bootstrap phase : bootstrap_drupal_root() [87.45 sec, 14.73 MB][bootstrap] ==> default: Initialized Drupal 7.54 root directory at [bootstrap] ==> default: ==> default: /var/aegir/hostmaster-7.x-3.x [87.45 sec, 14.73 MB] ==> default: Find command files for phase 1 (max=) [87.45 sec, 14.73 MB] [debug] ==> default: Cache MISS cid: 8.1.9-commandfiles-1-a023a35c126b8cf2bdb3f76a83a2a543 [debug] ==> default: [87.45 sec, 14.73 MB] ==> default: Cache SET cid: 8.1.9-commandfiles-1-a023a35c126b8cf2bdb3f76a83a2a543 [debug] ==> default: [87.45 sec, 14.73 MB] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-annotationfiles-1-d77ecfa976263ac65d4ec71dfaafe693 [87.45 sec, ==> default: 14.73 MB] ==> default: Cache SET cid: [debug] ==> default: 8.1.9-annotationfiles-1-d77ecfa976263ac65d4ec71dfaafe693 [87.45 sec, ==> default: 14.73 MB] ==> default: Drupal sites directory /var/aegir/hostmaster-7.x-3.x/sites is [success] ==> default: writable by the provisioning script [87.45 sec, 14.73 MB] ==> default: This platform is running drupal 7.54 [87.45 sec, 14.73 MB] [notice] ==> default: Found 44 modules in base [87.45 sec, 14.73 MB] [notice] ==> default: Found 4 themes in base [87.45 sec, 14.73 MB] [notice] ==> default: ==> default: Found install profile standard [87.45 [notice] ==> default: ==> default: sec, 14.73 MB] ==> default: Found install profile hostmaster [87.45 [notice] ==> default: sec, 14.74 MB] ==> default: Found install profile minimal [87.45 [notice] ==> default: ==> default: sec, 14.74 MB] ==> default: Found 74 modules in profiles/hostmaster [87.45 sec, 14.74 MB] [notice] ==> default: ==> default: Found 1 themes in profiles/hostmaster [87.45 sec, 14.74 MB] [notice] ==> default: ==> default: Platforms path /var/aegir/platforms has been created. [87.45 sec, [success] ==> default: 14.74 MB] ==> default: Platforms ownership of /var/aegir/platforms has been changed to [success] ==> default: ==> default: aegir. [87.45 sec, 14.74 MB] ==> default: Platforms permissions of /var/aegir/platforms have been changed to [success] ==> default: ==> default: 755. [87.45 sec, 14.74 MB] ==> default: Platforms path /var/aegir/platforms is writable. [87.45 sec, 14.74 [success] ==> default: ==> default: MB] ==> default: Returned from hook drush_provision_drupal_pre_provision_verify [87.45 [debug] ==> default: sec, 14.74 MB] ==> default: Calling hook drush_provision_verify [87.45 sec, 14.74 MB] [debug] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: ==> default: --yes @platform_hostmaster provision-save 2>&1 [87.45 sec, 14.74 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes [notice] ==> default: @platform_hostmaster provision-save 2>&1 [87.45 sec, 14.74 MB] ==> default: Bootstrap to phase 0. [87.45 sec, 14.75 MB] [bootstrap] ==> default: ==> default: Bootstrap to phase 0. [87.45 sec, 14.75 MB] [bootstrap] ==> default: ==> default: Found command: provision-save (commandfile=provision) [87.45 sec, [bootstrap] ==> default: ==> default: 14.75 MB] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.45 sec, 14.75 ==> default: MB] ==> default: Loading apache driver for the http service [87.45 sec, 14.75 MB] [notice] ==> default: Calling hook drush_provision_save [87.45 sec, 14.75 MB] [debug] ==> default: ==> default: Template loaded from Provision Config class [notice] ==> default: ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.45 sec, 14.75 MB] ==> default: Changed permissions of [success] ==> default: ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php to 640 [87.45 ==> default: sec, 14.75 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: (/var/aegir/.drush/platform_hostmaster.alias.drushrc.php) [87.45 sec, ==> default: 14.75 MB] ==> default: Changed permissions of [success] ==> default: ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php to 440 [87.45 ==> default: sec, 14.75 MB] ==> default: Returned from hook drush_provision_save [87.45 sec, 14.75 MB] [debug] ==> default: ==> default: Command dispatch complete [87.45 sec, 14.75 MB] [notice] ==> default: ==> default: Template loaded from Provision Config class default: ==> default: class="placeholder">Provision_Config_Apache_Platform: default: class="placeholder">/usr/share/drush/commands/provision/http/Provision/Config/Apache/platform.tpl.php ==> default: [87.45 sec, 14.76 MB] ==> default: Generated config in write(): platform configuration file [success] ==> default: ==> default: (/var/aegir/config/server_master/apache/platform.d/platform_hostmaster.conf) ==> default: [87.45 sec, 14.76 MB] ==> default: apache on default: ==> default: class="placeholder">aegir3-deb-unstable-no-puppet.test has been ==> default: restarted [87.45 sec, 14.76 MB] ==> default: Returned from hook drush_provision_verify [87.45 sec, 14.76 MB] [debug] ==> default: ==> default: Calling hook drush_provision_drupal_post_provision_verify [87.46 sec, [debug] ==> default: ==> default: 14.76 MB] ==> default: Returned from hook drush_provision_drupal_post_provision_verify [debug] ==> default: ==> default: [87.46 sec, 14.76 MB] ==> default: Created directory /var/aegir/hostmaster-7.x-3.x/sites/all/drush. [success] ==> default: [87.46 sec, 14.76 MB] ==> default: Template loaded from Provision Config class default: ==> default: class="placeholder">Provision_Config_Drushrc: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/provision_drushrc.tpl.php ==> default: [87.46 sec, 14.76 MB] ==> default: Generated config in write(): Platform Drush configuration file [success] ==> default: ==> default: (/var/aegir/hostmaster-7.x-3.x/sites/all/drush/drushrc.php) [87.46 ==> default: sec, 14.76 MB] ==> default: Changed permissions of [success] ==> default: ==> default: /var/aegir/hostmaster-7.x-3.x/sites/all/drush/drushrc.php to 444 ==> default: [87.46 sec, 14.76 MB] ==> default: Platforms path /var/aegir/platforms exists. [87.46 sec, 14.76 MB] [success] ==> default: ==> default: Platforms ownership of /var/aegir/platforms has been changed to [success] ==> default: ==> default: aegir. [87.46 sec, 14.77 MB] ==> default: Platforms permissions of /var/aegir/platforms have been changed to [success] ==> default: 755. [87.46 sec, 14.77 MB] ==> default: Platforms path /var/aegir/platforms is writable. [87.46 sec, 14.77 [success] ==> default: ==> default: MB] ==> default: Command dispatch complete [87.46 sec, 14.77 MB] [notice] ==> default: ==> default: Backend invoke: /usr/local/bin/drush --backend=2 [command] ==> default: ==> default: --uri=aegir.example.com --verbose --debug --yes --root= ==> default: provision-save '@hostmaster' --context_type=site ==> default: --platform='@platform_hostmaster' --db_server='@server_localhost' ==> default: --client_name=admin --client_email=' aegir@example.com' ==> default: --profile=hostmaster --drush_aliases=hm 2>&1 [87.46 sec, 14.77 MB] ==> default: /usr/local/bin/drush --backend=2 --uri=aegir.example.com --verbose [notice] ==> default: ==> default: --debug --yes --root= provision-save '@hostmaster' ==> default: --context_type=site --platform='@platform_hostmaster' ==> default: --db_server='@server_localhost' --client_name=admin --client_email=' ==> default: aegir@example.com' --profile=hostmaster --drush_aliases=hm 2>&1 ==> default: [87.46 sec, 14.77 MB] ==> default: Bootstrap to phase 0. [87.46 sec, 14.77 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.46 sec, 14.77 MB] [bootstrap] ==> default: Found command: provision-save (commandfile=provision) [87.46 sec, [bootstrap] ==> default: ==> default: 14.77 MB] ==> default: Loaded alias @server_localhost from file [notice] ==> default: ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php [87.46 sec, ==> default: 14.77 MB] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.46 sec, 14.77 ==> default: MB] ==> default: Loading apache driver for the http service [87.46 sec, 14.77 MB] [notice] ==> default: Loading mysql driver for the db service [87.46 sec, 14.78 MB] [notice] ==> default: ==> default: Loaded alias @platform_hostmaster from file [notice] ==> default: ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php [87.46 sec, ==> default: 14.78 MB] ==> default: Calling hook drush_provision_save [87.46 sec, 14.78 MB] [debug] ==> default: ==> default: Template loaded from Provision Config class [notice] ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.46 sec, 14.78 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: (/var/aegir/.drush/hostmaster.alias.drushrc.php) [87.46 sec, 14.78 ==> default: MB] ==> default: Changed permissions of /var/aegir/.drush/hostmaster.alias.drushrc.php [success] ==> default: ==> default: to 440 [87.46 sec, 14.78 MB] ==> default: Template loaded from Provision Config class [notice] ==> default: ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.46 sec, 14.78 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: ==> default: (/var/aegir/.drush/hm.alias.drushrc.php) [87.46 sec, 14.78 MB] ==> default: Changed permissions of /var/aegir/.drush/hm.alias.drushrc.php to 440 [success] ==> default: ==> default: [87.46 sec, 14.78 MB] ==> default: Returned from hook drush_provision_save [87.46 sec, 14.78 MB] [debug] ==> default: Command dispatch complete [87.46 sec, 14.78 MB] [notice] ==> default: ==> default: Starting with the hostmaster frontend installation. [87.46 sec, 14.78 [notice] ==> default: ==> default: MB] ==> default: Loaded alias @hostmaster from file [notice] ==> default: /var/aegir/.drush/hostmaster.alias.drushrc.php [87.46 sec, 14.8 MB] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: ==> default: --yes @hostmaster provision-install --client_email=' ==> default: aegir@example.com' 2>&1 [87.46 sec, 14.8 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes @hostmaster [notice] ==> default: provision-install --client_email=' aegir@example.com' 2>&1 [87.46 ==> default: sec, 14.8 MB] ==> default: Bootstrap to phase 0. [87.46 sec, 14.8 MB] [bootstrap] ==> default: ==> default: Bootstrap to phase 1. [87.46 sec, 14.8 MB] [bootstrap] ==> default: ==> default: Drush bootstrap phase : bootstrap_drupal_root() [87.46 sec, 14.8 MB] [bootstrap] ==> default: ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/all/drush/drushrc.php" into ==> default: "drupal" scope. [87.46 sec, 14.8 MB] ==> default: ==> default: Initialized Drupal 7.54 root directory at [bootstrap] ==> default: /var/aegir/hostmaster-7.x-3.x [87.46 sec, 14.8 MB] ==> default: Find command files for phase 1 (max=1) [87.46 sec, 14.8 MB] [debug] ==> default: ==> default: Cache HIT cid: 8.1.9-commandfiles-1-a023a35c126b8cf2bdb3f76a83a2a543 [debug] ==> default: ==> default: [87.46 sec, 14.8 MB] ==> default: Cache HIT cid: [debug] ==> default: ==> default: 8.1.9-annotationfiles-1-d77ecfa976263ac65d4ec71dfaafe693 [87.46 sec, ==> default: 14.81 MB] ==> default: Found command: provision-install (commandfile=provision) [87.47 sec, [bootstrap] ==> default: 14.81 MB] ==> default: Loaded alias @server_localhost from file [notice] ==> default: ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php [87.47 sec, ==> default: 14.81 MB] ==> default: Loaded alias @server_master from file [notice] ==> default: ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.47 sec, 14.81 ==> default: MB] ==> default: Loading apache driver for the http service [87.47 sec, 14.81 MB] [notice] ==> default: ==> default: Loading mysql driver for the db service [87.47 sec, 14.81 MB] [notice] ==> default: Loaded alias @platform_hostmaster from file [notice] ==> default: ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php [87.47 sec, ==> default: 14.81 MB] ==> default: Including [bootstrap] ==> default: /usr/share/drush/commands/provision/db/install.provision.inc [87.47 ==> default: sec, 14.81 MB] ==> default: Including [bootstrap] ==> default: ==> default: /usr/share/drush/commands/provision/http/install.provision.inc [87.47 ==> default: sec, 14.81 MB] ==> default: Including [bootstrap] ==> default: ==> default: /usr/share/drush/commands/provision/platform/install.provision.inc ==> default: [87.47 sec, 14.81 MB] ==> default: Calling hook drush_db_provision_install_validate [87.47 sec, 14.81 [debug] ==> default: MB] ==> default: Returned from hook drush_db_provision_install_validate [87.47 sec, [debug] ==> default: ==> default: 14.82 MB] ==> default: Calling hook drush_provision_drupal_provision_install_validate [87.47 [debug] ==> default: ==> default: sec, 14.82 MB] ==> default: Returned from hook drush_provision_drupal_provision_install_validate [debug] ==> default: [87.47 sec, 14.82 MB] ==> default: Calling hook drush_db_pre_provision_install [87.47 sec, 14.82 MB] [debug] ==> default: Granting privileges to default: ==> default: class="placeholder">aegirexamplecom@ default: class="placeholder">localhost on default: class="placeholder">aegirexamplecom [87.47 sec, 14.82 MB] ==> default: Created aegirexamplecom database [87.47 sec, 14.82 MB] [success] ==> default: ==> default: Returned from hook drush_db_pre_provision_install [87.47 sec, 14.82 [debug] ==> default: ==> default: MB] ==> default: Calling hook drush_provision_drupal_pre_provision_install [87.47 sec, [debug] ==> default: 14.82 MB] ==> default: Created <code>sites/aegir.example.com</code> [87.47 sec, 14.82 MB] [success] ==> default: ==> default: Changed permissions of <code>sites/aegir.example.com</code> to 755 [success] ==> default: ==> default: [87.47 sec, 14.82 MB] ==> default: Created <code>sites/aegir.example.com/themes</code> [87.47 sec, 14.82 [success] ==> default: ==> default: MB] ==> default: Changed permissions of <code>sites/aegir.example.com/themes</code> to [success] ==> default: ==> default: 2775 [87.47 sec, 14.82 MB] ==> default: Created <code>sites/aegir.example.com/modules</code> [87.47 sec, [success] ==> default: 14.83 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/modules</code> [success] ==> default: ==> default: to 2775 [87.47 sec, 14.83 MB] ==> default: Created <code>sites/aegir.example.com/libraries</code> [87.47 sec, [success] ==> default: 14.83 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/libraries</code> [success] ==> default: ==> default: to 2775 [87.47 sec, 14.83 MB] ==> default: Created <code>sites/aegir.example.com/files</code> [87.47 sec, 14.83 [success] ==> default: ==> default: MB] ==> default: Changed permissions of <code>sites/aegir.example.com/files</code> to [success] ==> default: ==> default: 2770 [87.47 sec, 14.83 MB] ==> default: Created <code>sites/aegir.example.com/files/tmp</code> [87.47 sec, [success] ==> default: 14.83 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/files/tmp</code> [success] ==> default: ==> default: to 2770 [87.47 sec, 14.83 MB] ==> default: Created <code>sites/aegir.example.com/files/images</code> [87.47 sec, [success] ==> default: ==> default: 14.83 MB] ==> default: Changed permissions of [success] ==> default: ==> default: <code>sites/aegir.example.com/files/images</code> to 2770 [87.47 sec, ==> default: 14.83 MB] ==> default: Created <code>sites/aegir.example.com/files/pictures</code> [87.47 [success] ==> default: ==> default: sec, 14.83 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/pictures</code> to 2770 [87.47 ==> default: sec, 14.83 MB] ==> default: Created <code>sites/aegir.example.com/files/css</code> [87.47 sec, [success] ==> default: 14.84 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/files/css</code> [success] ==> default: ==> default: to 2770 [87.47 sec, 14.84 MB] ==> default: Created <code>sites/aegir.example.com/files/js</code> [87.47 sec, [success] ==> default: ==> default: 14.84 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/files/js</code> [success] ==> default: ==> default: to 2770 [87.47 sec, 14.84 MB] ==> default: Created <code>sites/aegir.example.com/files/ctools</code> [87.47 sec, [success] ==> default: 14.84 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/ctools</code> to 2770 [87.47 sec, ==> default: 14.84 MB] ==> default: Created <code>sites/aegir.example.com/files/imagecache</code> [87.47 [success] ==> default: sec, 14.84 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/imagecache</code> to 2770 [87.47 ==> default: sec, 14.84 MB] ==> default: Created <code>sites/aegir.example.com/files/locations</code> [87.47 [success] ==> default: sec, 14.84 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/locations</code> to 2770 [87.47 ==> default: sec, 14.84 MB] ==> default: Created <code>sites/aegir.example.com/private</code> [87.47 sec, [success] ==> default: 14.84 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/private</code> [success] ==> default: to 2770 [87.47 sec, 14.84 MB] ==> default: Created <code>sites/aegir.example.com/private/files</code> [87.47 [success] ==> default: sec, 14.85 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/private/files</code> to 2770 [87.48 ==> default: sec, 14.85 MB] ==> default: Created <code>sites/aegir.example.com/private/temp</code> [87.48 sec, [success] ==> default: 14.85 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/private/temp</code> to 2770 [87.48 sec, ==> default: 14.85 MB] ==> default: Created <code>sites/aegir.example.com/files/styles</code> [87.48 sec, [success] ==> default: 14.85 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/styles</code> to 2770 [87.48 sec, ==> default: 14.85 MB] ==> default: Created <code>sites/aegir.example.com/private/config</code> [87.48 [success] ==> default: sec, 14.85 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/private/config</code> to 2770 [87.48 ==> default: sec, 14.85 MB] ==> default: Created <code>sites/aegir.example.com/private/config/sync</code> [success] ==> default: [87.48 sec, 14.85 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/private/config/sync</code> to 2770 ==> default: [87.48 sec, 14.85 MB] ==> default: Changed group ownership of <code>sites/aegir.example.com/files</code> [success] ==> default: to www-data [87.48 sec, 14.85 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/tmp</code> to www-data [87.48 ==> default: sec, 14.85 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/images</code> to www-data [87.48 ==> default: sec, 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/pictures</code> to www-data ==> default: [87.48 sec, 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/css</code> to www-data [87.48 ==> default: sec, 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/js</code> to www-data [87.48 sec, ==> default: 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/ctools</code> to www-data [87.48 ==> default: sec, 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/imagecache</code> to www-data ==> default: [87.48 sec, 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/locations</code> to www-data ==> default: [87.48 sec, 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private</code> to www-data [87.48 sec, ==> default: 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private/files</code> to www-data [87.48 ==> default: sec, 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private/temp</code> to www-data [87.48 ==> default: sec, 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/styles</code> to www-data [87.48 ==> default: sec, 14.86 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private/config</code> to www-data ==> default: [87.48 sec, 14.87 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private/config/sync</code> to www-data ==> default: [87.48 sec, 14.87 MB] ==> default: Returned from hook drush_provision_drupal_pre_provision_install [debug] ==> default: [87.48 sec, 14.87 MB] ==> default: Calling hook drush_http_provision_install [87.48 sec, 14.87 MB] [debug] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Apache_Site: default: class="placeholder">/usr/share/drush/commands/provision/http/Provision/Config/Apache/vhost.tpl.php ==> default: [87.48 sec, 14.87 MB] ==> default: Generated config in write(): virtual host configuration file [success] ==> default: (/var/aegir/config/server_master/apache/vhost.d/aegir.example.com) ==> default: [87.48 sec, 14.87 MB] ==> default: Returned from hook drush_http_provision_install [87.48 sec, 14.87 MB] [debug] ==> default: Calling hook drush_provision_drupal_provision_install [87.48 sec, [debug] ==> default: 14.87 MB] ==> default: Generated blank Drupal local.settings.php file [87.48 sec, 14.87 MB] [success] ==> default: Changed group ownership of [success] ==> default: <code>/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/local.settings.php</code> ==> default: to www-data [87.48 sec, 14.87 MB] ==> default: Changed permissions of [success] ==> default: <code>/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/local.settings.php</code> ==> default: to 440 [87.48 sec, 14.87 MB] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Drupal_Settings: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/Drupal/provision_drupal_settings_7.tpl.php ==> default: [87.48 sec, 14.87 MB] ==> default: Generated config in write(): Drupal settings.php file [success] ==> default: (/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php) ==> default: [87.48 sec, 14.88 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php to ==> default: 440 [87.48 sec, 14.88 MB] ==> default: Change group ownership of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php to ==> default: www-data [87.48 sec, 14.88 MB] ==> default: Platforms path /var/aegir/platforms exists. [87.48 sec, 14.88 MB] [success] ==> default: Platforms ownership of /var/aegir/platforms has been changed to [success] ==> default: aegir. [87.48 sec, 14.88 MB] ==> default: Platforms permissions of /var/aegir/platforms have been changed to [success] ==> default: 755. [87.48 sec, 14.88 MB] ==> default: Platforms path /var/aegir/platforms is writable. [87.48 sec, 14.88 [success] ==> default: MB] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Drushrc_Site: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_site.tpl.php ==> default: [87.48 sec, 14.88 MB] ==> default: Generated config in write(): Site Drush configuration file [success] ==> default: (/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php) ==> default: [87.48 sec, 14.88 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php to ==> default: 440 [87.48 sec, 14.88 MB] ==> default: Platforms path /var/aegir/platforms exists. [87.48 sec, 14.88 MB] [success] ==> default: Platforms ownership of /var/aegir/platforms has been changed to [success] ==> default: aegir. [87.48 sec, 14.88 MB] ==> default: Platforms permissions of /var/aegir/platforms have been changed to [success] ==> default: 755. [87.48 sec, 14.89 MB] ==> default: Platforms path /var/aegir/platforms is writable. [87.48 sec, 14.89 [success] ==> default: MB] ==> default: Drush bootstrap phase : bootstrap_drupal_site() [87.48 sec, 14.89 MB][bootstrap] ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php" ==> default: into "site" scope. [87.48 sec, 14.89 MB] ==> default: Initialized Drupal site aegir.example.com at sites/aegir.example.com [bootstrap] ==> default: [87.48 sec, 14.89 MB] ==> default: Find command files for phase 2 (max=) [87.48 sec, 14.89 MB] [debug] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [87.48 sec, ==> default: 14.89 MB] ==> default: Cache MISS cid: 8.1.9-commandfiles-2-e3f0904542232c4096549f0d06143b37 [debug] ==> default: [87.48 sec, 14.89 MB] ==> default: Cache SET cid: 8.1.9-commandfiles-2-e3f0904542232c4096549f0d06143b37 [debug] ==> default: [87.48 sec, 14.89 MB] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-annotationfiles-2-6d5cf19e66c4340d1e9daa6ee1b7e1f1 [87.48 sec, ==> default: 14.89 MB] ==> default: Cache SET cid: [debug] ==> default: 8.1.9-annotationfiles-2-6d5cf19e66c4340d1e9daa6ee1b7e1f1 [87.48 sec, ==> default: 14.89 MB] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes @hostmaster provision-install-backend --client_email=' ==> default: aegir@example.com' 2>&1 [87.48 sec, 14.89 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes @hostmaster [notice] ==> default: provision-install-backend --client_email=' aegir@example.com' 2>&1 ==> default: [87.48 sec, 14.9 MB] ==> default: Bootstrap to phase 0. [87.48 sec, 14.9 MB] [bootstrap] ==> default: Bootstrap to phase 2. [87.48 sec, 14.9 MB] [bootstrap] ==> default: Drush bootstrap phase : bootstrap_drupal_root() [87.48 sec, 14.9 MB] [bootstrap] ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/all/drush/drushrc.php" into ==> default: "drupal" scope. [87.48 sec, 14.9 MB] ==> default: Initialized Drupal 7.54 root directory at [bootstrap] ==> default: /var/aegir/hostmaster-7.x-3.x [87.48 sec, 14.9 MB] ==> default: Find command files for phase 1 (max=2) [87.48 sec, 14.9 MB] [debug] ==> default: Cache HIT cid: 8.1.9-commandfiles-1-a023a35c126b8cf2bdb3f76a83a2a543 [debug] ==> default: [87.48 sec, 14.9 MB] ==> default: Cache HIT cid: [debug] ==> default: 8.1.9-annotationfiles-1-d77ecfa976263ac65d4ec71dfaafe693 [87.48 sec, ==> default: 14.9 MB] ==> default: Drush bootstrap phase : bootstrap_drupal_site() [87.48 sec, 14.9 MB] [bootstrap] ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php" ==> default: into "site" scope. [87.48 sec, 14.9 MB] ==> default: Initialized Drupal site aegir.example.com at sites/aegir.example.com [bootstrap] ==> default: [87.48 sec, 14.9 MB] ==> default: Find command files for phase 2 (max=2) [87.48 sec, 14.91 MB] [debug] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [87.48 sec, ==> default: 14.91 MB] ==> default: Cache HIT cid: 8.1.9-commandfiles-2-e3f0904542232c4096549f0d06143b37 [debug] ==> default: [87.48 sec, 14.91 MB] ==> default: Cache HIT cid: [debug] ==> default: 8.1.9-annotationfiles-2-6d5cf19e66c4340d1e9daa6ee1b7e1f1 [87.48 sec, ==> default: 14.91 MB] ==> default: Found command: provision-install-backend (commandfile=provision) [bootstrap] ==> default: [87.48 sec, 14.91 MB] ==> default: Loaded alias @server_localhost from file [notice] ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php [87.48 sec, ==> default: 14.91 MB] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.48 sec, 14.91 ==> default: MB] ==> default: Loading apache driver for the http service [87.48 sec, 14.91 MB] [notice] ==> default: Loading mysql driver for the db service [87.48 sec, 14.91 MB] [notice] ==> default: Loaded alias @platform_hostmaster from file [notice] ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php [87.48 sec, ==> default: 14.91 MB] ==> default: Calling hook drush_provision_drupal_provision_install_backend [87.48 [debug] ==> default: sec, 14.91 MB] ==> default: Drush bootstrap phase : bootstrap_drupal_configuration() [87.48 sec, [bootstrap] ==> default: 14.91 MB] ==> default: Find command files for phase 3 (max=) [87.48 sec, 14.92 MB] [debug] ==> default: Error validating client email ` aegir@example.com`. Please check your [warning] ==> default: user account email. To allow the site installation to proceed, the ==> default: email `this.email@is.invalid` has been used instead. Please use the ==> default: `login` link to change the site admin email to a proper valid ==> default: address. [87.49 sec, 14.92 MB] ==> default: WD system: user module installed. [87.49 sec, 14.92 MB] [info] ==> default: WD system: user module enabled. [87.49 sec, 14.92 MB] [info] ==> default: WD system: field_sql_storage module installed. [87.49 sec, 14.92 MB] [info] ==> default: WD system: field_sql_storage module enabled. [87.49 sec, 14.92 MB] [info] ==> default: WD system: field module installed. [87.49 sec, 14.92 MB] [info] ==> default: WD system: field module enabled. [87.49 sec, 14.92 MB] [info] ==> default: WD system: text module installed. [87.49 sec, 14.92 MB] [info] ==> default: WD system: text module enabled. [87.49 sec, 14.92 MB] [info] ==> default: WD system: filter module installed. [87.49 sec, 14.92 MB] [info] ==> default: WD system: filter module enabled. [87.49 sec, 14.93 MB] [info] ==> default: WD system: node module installed. [87.49 sec, 14.93 MB] [info] ==> default: WD system: node module enabled. [87.49 sec, 14.93 MB] [info] ==> default: WD system: actions_permissions module installed. [87.49 sec, 14.93 [info] ==> default: MB] ==> default: WD system: actions_permissions module enabled. [87.49 sec, 14.93 MB] [info] ==> default: WD system: admin_menu module installed. [87.49 sec, 14.93 MB] [info] ==> default: WD system: admin_menu module enabled. [87.49 sec, 14.93 MB] [info] ==> default: WD system: betterlogin module installed. [87.49 sec, 14.93 MB] [info] ==> default: WD system: betterlogin module enabled. [87.49 sec, 14.93 MB] [info] ==> default: WD system: block module installed. [87.49 sec, 14.93 MB] [info] ==> default: WD system: block module enabled. [87.49 sec, 14.93 MB] [info] ==> default: WD system: ctools module installed. [87.49 sec, 14.93 MB] [info] ==> default: WD system: ctools module enabled. [87.49 sec, 14.94 MB] [info] ==> default: WD system: color module installed. [87.49 sec, 14.94 MB] [info] ==> default: WD system: color module enabled. [87.49 sec, 14.94 MB] [info] ==> default: WD system: menu module installed. [87.49 sec, 14.94 MB] [info] ==> default: WD system: menu module enabled. [87.49 sec, 14.94 MB] [info] ==> default: WD system: entity module installed. [87.49 sec, 14.94 MB] [info] ==> default: WD system: entity module enabled. [87.49 sec, 14.94 MB] [info] ==> default: WD system: views module installed. [87.49 sec, 14.94 MB] [info] ==> default: WD system: views module enabled. [87.49 sec, 14.94 MB] [info] ==> default: WD system: views_bulk_operations module installed. [87.49 sec, 14.94 [info] ==> default: MB] ==> default: WD system: views_bulk_operations module enabled. [87.49 sec, 14.94 [info] ==> default: MB] ==> default: WD system: hosting module installed. [87.49 sec, 14.94 MB] [info] ==> default: WD system: hosting module enabled. [87.49 sec, 14.95 MB] [info] ==> default: WD system: hosting_client module installed. [87.49 sec, 14.95 MB] [info] ==> default: WD system: hosting_client module enabled. [87.49 sec, 14.95 MB] [info] ==> default: WD system: hosting_site module installed. [87.49 sec, 14.95 MB] [info] ==> default: WD system: hosting_site module enabled. [87.49 sec, 14.95 MB] [info] ==> default: WD system: help module installed. [87.49 sec, 14.95 MB] [info] ==> default: WD system: help module enabled. [87.49 sec, 14.95 MB] [info] ==> default: WD system: hosting_task module installed. [87.49 sec, 14.95 MB] ==> default: [info] ==> default: WD system: hosting_task module enabled. [87.49 sec, 14.95 MB] [info] ==> default: WD system: hosting_db_server module installed. [87.49 sec, 14.95 MB] [info] ==> default: WD system: hosting_db_server module enabled. [87.49 sec, 14.95 MB] [info] ==> default: WD system: hosting_web_server module installed. [87.49 sec, 14.95 MB] [info] ==> default: WD system: hosting_web_server module enabled. [87.49 sec, 14.96 MB] [info] ==> default: WD system: hosting_package module installed. [87.49 sec, 14.96 MB] [info] ==> default: WD system: hosting_package module enabled. [87.49 sec, 14.96 MB] [info] ==> default: WD system: hosting_platform module installed. [87.49 sec, 14.96 MB] [info] ==> default: WD system: hosting_platform module enabled. [87.49 sec, 14.96 MB] [info] ==> default: WD system: hosting_server module installed. [87.49 sec, 14.96 MB] [info] ==> default: WD system: hosting_server module enabled. [87.49 sec, 14.96 MB] [info] ==> default: WD system: overlay module installed. [87.49 sec, 14.96 MB] [info] ==> default: WD system: overlay module enabled. [87.49 sec, 14.96 MB] [info] ==> default: WD system: overlay_paths module installed. [87.49 sec, 14.96 MB] [info] ==> default: WD system: overlay_paths module enabled. [87.49 sec, 14.96 MB] [info] ==> default: WD system: r4032login module installed. [87.49 sec, 14.96 MB] [info] ==> default: WD system: r4032login module enabled. [87.49 sec, 14.97 MB] [info] ==> default: Trying to get property of non-object hosting.inc:117 [87.49 sec, [notice] ==> default: 14.97 MB] ==> default: Undefined index: server hosting_task.module:851 [87.49 sec, 14.98 MB] [notice] ==> default: Undefined index: server hosting_task.module:851 [87.49 sec, 14.98 MB] [notice] ==> default: Undefined index: platform hosting_task.module:851 [87.49 sec, 14.98 [notice] ==> default: MB] ==> default: WD system: hostmaster module installed. [87.49 sec, 14.98 MB] [info] ==> default: WD system: hostmaster module enabled. [87.49 sec, 14.98 MB] [info] ==> default: WD actions: Action 'Site: Backup' added. [87.49 sec, 14.98 MB] [notice] ==> default: WD actions: Action 'Site: Verify' added. [87.49 sec, 14.98 MB] [notice] ==> default: WD actions: Action 'Site: Disable' added. [87.49 sec, 14.98 MB] [notice] ==> default: WD actions: Action 'Site: Enable' added. [87.49 sec, 14.98 MB] [notice] ==> default: WD actions: Action 'Publish content' added. [87.49 sec, 14.98 MB] [notice] ==> default: WD actions: Action 'Unpublish content' added. [87.49 sec, 14.98 MB] [notice] ==> default: WD actions: Action 'Make content sticky' added. [87.49 sec, 14.98 MB] [notice] ==> default: WD actions: Action 'Make content unsticky' added. [87.49 sec, 14.99 [notice] ==> default: MB] ==> default: WD actions: Action 'Promote content to front page' added. [87.49 sec, [notice] ==> default: 14.99 MB] ==> default: WD actions: Action 'Remove content from front page' added. [87.49 [notice] ==> default: sec, 14.99 MB] ==> default: WD actions: Action 'Save content' added. [87.49 sec, 14.99 MB] [notice] ==> default: WD actions: Action 'Ban IP address of current user' added. [87.49 [notice] ==> default: sec, 14.99 MB] ==> default: WD actions: Action 'Block current user' added. [87.49 sec, 14.99 MB] [notice] ==> default: WD actions: Action 'Create an archive of selected files' added. [notice] ==> default: [87.49 sec, 14.99 MB] ==> default: WD actions: Action 'Pass ids as arguments to a page' added. [87.49 [notice] ==> default: sec, 14.99 MB] ==> default: WD actions: Action 'Delete item' added. [87.49 sec, 14.99 MB] [notice] ==> default: WD actions: Action 'Delete revision' added. [87.49 sec, 14.99 MB] [notice] ==> default: WD actions: Action 'Modify entity values' added. [87.49 sec, 14.99 [notice] ==> default: MB] ==> default: WD system: update module installed. [87.49 sec, 14.99 MB] [info] ==> default: WD system: update module enabled. [87.49 sec, 15 MB] [info] ==> default: WD user: Session opened for admin. [87.49 sec, 15 MB] [notice] ==> default: WD cron: Cron run completed. [87.49 sec, 15 MB] [notice] ==> default: Changed permissions of <code>sites/aegir.example.com</code> to 755 [success] ==> default: [87.49 sec, 15 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/themes</code> to [success] ==> default: 2775 [87.49 sec, 15 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/modules</code> [success] ==> default: to 2775 [87.49 sec, 15 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/libraries</code> [success] ==> default: to 2775 [87.49 sec, 15 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/files</code> to [success] ==> default: 2770 [87.49 sec, 15 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/files/tmp</code> [success] ==> default: to 2770 [87.49 sec, 15 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/images</code> to 2770 [87.49 sec, ==> default: 15 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/pictures</code> to 2770 [87.49 ==> default: sec, 15 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/files/css</code> [success] ==> default: to 2770 [87.49 sec, 15 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/files/js</code> [success] ==> default: to 2770 [87.49 sec, 15.01 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/ctools</code> to 2770 [87.49 sec, ==> default: 15.01 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/imagecache</code> to 2770 [87.49 ==> default: sec, 15.01 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/locations</code> to 2770 [87.49 ==> default: sec, 15.01 MB] ==> default: Changed permissions of <code>sites/aegir.example.com/private</code> [success] ==> default: to 2770 [87.49 sec, 15.01 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/private/files</code> to 2770 [87.49 ==> default: sec, 15.01 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/private/temp</code> to 2770 [87.49 sec, ==> default: 15.01 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/files/styles</code> to 2770 [87.49 sec, ==> default: 15.01 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/private/config</code> to 2770 [87.49 ==> default: sec, 15.01 MB] ==> default: Changed permissions of [success] ==> default: <code>sites/aegir.example.com/private/config/sync</code> to 2770 ==> default: [87.49 sec, 15.01 MB] ==> default: Changed group ownership of <code>sites/aegir.example.com/files</code> [success] ==> default: to www-data [87.49 sec, 15.01 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/tmp</code> to www-data [87.49 ==> default: sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/images</code> to www-data [87.49 ==> default: sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/pictures</code> to www-data ==> default: [87.49 sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/css</code> to www-data [87.49 ==> default: sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/js</code> to www-data [87.49 sec, ==> default: 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/ctools</code> to www-data [87.49 ==> default: sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/imagecache</code> to www-data ==> default: [87.49 sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/locations</code> to www-data ==> default: [87.49 sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private</code> to www-data [87.49 sec, ==> default: 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private/files</code> to www-data [87.49 ==> default: sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private/temp</code> to www-data [87.49 ==> default: sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/files/styles</code> to www-data [87.49 ==> default: sec, 15.02 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private/config</code> to www-data ==> default: [87.49 sec, 15.03 MB] ==> default: Changed group ownership of [success] ==> default: <code>sites/aegir.example.com/private/config/sync</code> to www-data ==> default: [87.49 sec, 15.03 MB] ==> default: Login url: [success] ==> default: http://aegir.example.com/user/reset/1/1487159359/xv_EdcCIGd20VTk-tiDOu3kMXg2sYbgH1QIF5RTl0jQ/login ==> default: [87.49 sec, 15.03 MB] ==> default: Sent welcome mail to this.email@is.invalid [87.49 sec, 15.03 MB] [success] ==> default: Returned from hook drush_provision_drupal_provision_install_backend [debug] ==> default: [87.49 sec, 15.03 MB] ==> default: Content permissions have been rebuilt. [87.49 sec, 15.03 MB] [status] ==> default: Enabling Hosting system feature. [87.49 sec, 15.03 MB] [status] ==> default: The 'aegir client' role was assigned the following permissions: [status] ==> default: 'access content', 'view revisions'. [87.49 sec, 15.03 MB] ==> default: The 'aegir account manager' role was assigned the following [status] ==> default: permissions: 'access content', 'access user profiles'. ==> default: [87.49 sec, 15.03 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permissions: 'access content', 'view revisions'. [87.49 ==> default: sec, 15.03 MB] ==> default: The 'aegir administrator' role was assigned the following [status] ==> default: permissions: 'access administration menu', 'access ==> default: content', 'view revisions', 'access user ==> default: profiles'. [87.49 sec, 15.03 MB] ==> default: Enabling Clients feature. [87.49 sec, 15.03 MB] [status] ==> default: The 'aegir client' role was assigned the following permissions: 'edit [status] ==> default: own client', 'view client'. [87.49 sec, 15.04 MB] ==> default: The 'aegir account manager' role was assigned the following [status] ==> default: permissions: 'administer clients', 'create client', ==> default: 'edit client users', 'view client', 'edit ==> default: client uname'. [87.49 sec, 15.04 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permission: 'administer clients'. [87.49 sec, 15.04 MB] ==> default: Enabling Site administration feature. [87.49 sec, 15.04 MB] [status] ==> default: The 'aegir client' role was assigned the following permissions: [status] ==> default: 'create site', 'delete site', 'view site', ==> default: 'edit site'. [87.49 sec, 15.04 MB] ==> default: The 'aegir account manager' role was assigned the following [status] ==> default: permission: 'view site'. [87.49 sec, 15.04 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permissions: 'administer sites', 'create site', ==> default: 'delete site', 'edit site', 'view site'. ==> default: [87.49 sec, 15.04 MB] ==> default: Enabling Tasks feature. [87.49 sec, 15.04 MB] [status] ==> default: The 'aegir client' role was assigned the following permissions: [status] ==> default: 'create backup task', 'create delete task', ==> default: 'create disable task', 'create enable task', ==> default: 'create restore task', 'create verify task', ==> default: 'access task logs', 'view own tasks', 'view ==> default: task', 'cancel own tasks'. [87.49 sec, 15.04 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permissions: 'administer tasks', 'create lock task', ==> default: 'create unlock task', 'create verify task', ==> default: 'access task logs', 'cancel own tasks', ==> default: 'view own tasks', 'retry failed tasks', ==> default: 'view task'. [87.49 sec, 15.04 MB] ==> default: Enabling Database servers feature. [87.49 sec, 15.04 MB] [status] ==> default: Enabling Web servers feature. [87.49 sec, 15.04 MB] [status] ==> default: Enabling Package management feature. [87.5 sec, 15.05 MB] [status] ==> default: The 'aegir client' role was assigned the following permission: 'view [status] ==> default: package'. [87.5 sec, 15.05 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permission: 'view package'. [87.5 sec, 15.05 MB] ==> default: Enabling Platform administration feature. [87.5 sec, 15.05 MB] [status] ==> default: The 'aegir client' role was assigned the following permission: 'view [status] ==> default: platform'. [87.5 sec, 15.05 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permissions: 'administer platforms', 'create ==> default: platform', 'delete platform', 'edit ==> default: platform', 'view locked platforms', 'view ==> default: platform', 'create sites on locked platforms'. [87.5 sec, ==> default: 15.05 MB] ==> default: Enabling Server administration feature. [87.5 sec, 15.05 MB] [status] ==> default: Creating master server node [87.5 sec, 15.05 MB] [status] ==> default: Creating db server node [87.5 sec, 15.05 MB] [status] ==> default: Enabling Web servers, Database servers, Platform administration, [status] ==> default: Clients, Tasks, Server administration, Package management, Site ==> default: administration, Hosting system features. [87.5 sec, 15.05 MB] ==> default: The 'aegir client' role was assigned the following permission: 'view [status] ==> default: platform'. [87.5 sec, 15.05 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permissions: 'administer platforms', 'create ==> default: platform', 'delete platform', 'edit ==> default: platform', 'view locked platforms', 'view ==> default: platform', 'create sites on locked platforms'. [87.5 sec, ==> default: 15.05 MB] ==> default: The 'aegir client' role was assigned the following permissions: 'edit [status] ==> default: own client', 'view client'. [87.5 sec, 15.06 MB] ==> default: The 'aegir account manager' role was assigned the following [status] ==> default: permissions: 'administer clients', 'create client', ==> default: 'edit client users', 'view client', 'edit ==> default: client uname'. [87.5 sec, 15.06 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permission: 'administer clients'. [87.5 sec, 15.06 MB] ==> default: The 'aegir client' role was assigned the following permissions: [status] ==> default: 'create backup task', 'create delete task', ==> default: 'create disable task', 'create enable task', ==> default: 'create restore task', 'create verify task', ==> default: 'access task logs', 'view own tasks', 'view ==> default: task', 'cancel own tasks'. [87.5 sec, 15.06 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permissions: 'administer tasks', 'create lock task', ==> default: 'create unlock task', 'create verify task', ==> default: 'access task logs', 'cancel own tasks', ==> default: 'view own tasks', 'retry failed tasks', ==> default: 'view task'. [87.5 sec, 15.06 MB] ==> default: The 'aegir client' role was assigned the following permission: 'view [status] ==> default: package'. [87.5 sec, 15.06 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permission: 'view package'. [87.5 sec, 15.06 MB] ==> default: The 'aegir client' role was assigned the following permissions: [status] ==> default: 'create site', 'delete site', 'view site', ==> default: 'edit site'. [87.5 sec, 15.06 MB] ==> default: The 'aegir account manager' role was assigned the following [status] ==> default: permission: 'view site'. [87.5 sec, 15.06 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permissions: 'administer sites', 'create site', ==> default: 'delete site', 'edit site', 'view site'. ==> default: [87.5 sec, 15.06 MB] ==> default: The ' ==> default: aegir client' role was assigned the following permissions: [status] ==> default: 'access content', 'view revisions'. [87.5 sec, 15.06 MB] ==> default: The 'aegir account manager' role was assigned the following [status] ==> default: permissions: 'access content', 'access user profiles'. ==> default: [87.5 sec, 15.07 MB] ==> default: The 'aegir platform manager' role was assigned the following [status] ==> default: permissions: 'access content', 'view revisions'. [87.5 sec, ==> default: 15.07 MB] ==> default: The 'aegir administrator' role was assigned the following [status] ==> default: permissions: 'access administration menu', 'access ==> default: content', 'view revisions', 'access user ==> default: profiles'. [87.5 sec, 15.07 MB] ==> default: Configuring default blocks [87.5 sec, 15.07 MB] [status] ==> default: Content permissions have been rebuilt. [87.5 sec, 15.07 MB] [status] ==> default: No IP addresses provided for server. We guess the IP is 127.0.1.1 [message] ==> default: based on hostname aegir3-deb-unstable-no-puppet. You should set IP ==> default: addreses in the server node unless you are ready to use SNI (Server ==> default: Name Indication) which is incompatible with IE and Safari on Windows ==> default: XP. [87.5 sec, 15.07 MB] ==> default: No IP addresses provided for server. We guess the IP is [message] ==> default: 127.0.0.1,127.0.0.1 based on hostname localhost. You should set IP ==> default: addreses in the server node unless you are ready to use SNI (Server ==> default: Name Indication) which is incompatible with IE and Safari on Windows ==> default: XP. [87.5 sec, 15.07 MB] ==> default: Command dispatch complete [87.5 sec, 15.07 MB] [notice] ==> default: Drush bootstrap phase : bootstrap_drupal_configuration() [87.5 sec, [bootstrap] ==> default: 15.07 MB] ==> default: Find command files for phase 3 (max=) [87.5 sec, 15.07 MB] [debug] ==> default: sql-query: SELECT 1; [87.5 sec, 15.07 MB] [notice] ==> default: sql-query: SHOW TABLES; [87.5 sec, 15.07 MB] [notice] ==> default: Drush bootstrap phase : bootstrap_drupal_database() [87.5 sec, 15.08 [bootstrap] ==> default: MB] ==> default: Successfully connected to the Drupal database. [87.5 sec, 15.08 MB] [bootstrap] ==> default: Find command files for phase 4 (max=) [87.5 sec, 15.08 MB] [debug] ==> default: Drush bootstrap phase : bootstrap_drupal_full() [87.5 sec, 15.08 MB] [bootstrap] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [87.5 sec, ==> default: 15.08 MB] ==> default: Cache SET cid: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [debug] ==> default: [87.5 sec, 15.08 MB] ==> default: Find command files for phase 5 (max=) [87.5 sec, 15.08 MB] [debug] ==> default: Cache HIT cid: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [debug] ==> default: [87.5 sec, 15.08 MB] ==> default: Cache MISS cid: 8.1.9-commandfiles-5-c578520176739d7f859ee351ea965df2 [debug] ==> default: [87.5 sec, 15.08 MB] ==> default: Cache SET cid: 8.1.9-commandfiles-5-c578520176739d7f859ee351ea965df2 [debug] ==> default: [87.5 sec, 15.08 MB] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-annotationfiles-5-f889260044802601cdd1289e980c5455 [87.5 sec, ==> default: 15.08 MB] ==> default: Cache SET cid: [debug] ==> default: 8.1.9-annotationfiles-5-f889260044802601cdd1289e980c5455 [87.5 sec, ==> default: 15.08 MB] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Drupal_Alias_Store: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/Drupal/Alias/provision_drupal_sites.tpl.php ==> default: [87.5 sec, 15.09 MB] ==> default: Generated config in write(): Drupal sites.php file [success] ==> default: (/var/aegir/hostmaster-7.x-3.x/sites/sites.php) [87.5 sec, 15.09 MB] ==> default: Changed permissions of /var/aegir/hostmaster-7.x-3.x/sites/sites.php [success] ==> default: to 644 [87.5 sec, 15.09 MB] ==> default: Returned from hook drush_provision_drupal_provision_install [87.5 [debug] ==> default: sec, 15.09 MB] ==> default: Calling hook drush_http_post_provision_install [87.5 sec, 15.09 MB] [debug] ==> default: apache on default: class="placeholder">aegir3-deb-unstable-no-puppet.test has been ==> default: restarted [87.5 sec, 15.09 MB] ==> default: Returned from hook drush_http_post_provision_install [87.5 sec, 15.09 [debug] ==> default: MB] ==> default: Calling hook drush_provision_civicrm_post_provision_install [87.5 [debug] ==> default: sec, 15.09 MB] ==> default: Returned from hook drush_provision_civicrm_post_provision_install [debug] ==> default: [87.5 sec, 15.09 MB] ==> default: Calling hook drush_provision_drupal_post_provision_install [87.5 sec, [debug] ==> default: 15.09 MB] ==> default: WD actions: Action 'Platform: Verify' added. [87.5 sec, 15.09 MB] [notice] ==> default: WD actions: Action 'Platform: Delete' added. [87.5 sec, 15.09 MB] [notice] ==> default: WD actions: Action 'Platform: Lock' added. [87.5 sec, 15.1 MB] [notice] ==> default: WD actions: Action 'Platform: Unlock' added. [87.5 sec, 15.1 MB] [notice] ==> default: All caches flushed [87.5 sec, 15.1 MB] [notice] ==> default: Found install profile standard [87.5 [notice] ==> default: sec, 15.1 MB] ==> default: Found install profile hostmaster [87.5 [notice] ==> default: sec, 15.1 MB] ==> default: Found install profile minimal [87.5 sec, [notice] ==> default: 15.1 MB] ==> default: Found 118 modules [87.5 sec, 15.1 MB] [notice] ==> default: Found 5 themes [87.5 sec, 15.1 MB] [notice] ==> default: Client home directory for admin path /var/aegir/clients/admin has [success] ==> default: been created. [87.5 sec, 15.1 MB] ==> default: Client home directory for admin ownership of /var/aegir/clients/admin [success] ==> default: has been changed to aegir. [87.5 sec, 15.1 MB] ==> default: Client home directory for admin permissions of [success] ==> default: /var/aegir/clients/admin have been changed to 750. [87.5 sec, 15.1 ==> default: MB] ==> default: Client home directory for admin path /var/aegir/clients/admin is [success] ==> default: writable. [87.51 sec, 15.1 MB] ==> default: couldn't find previous client symlink, iterating through all sites [notice] ==> default: [87.51 sec, 15.11 MB] ==> default: Created symlink /var/aegir/clients/admin/aegir.example.com to [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com [87.51 sec, ==> default: 15.11 MB] ==> default: Returned from hook drush_provision_drupal_post_provision_install [debug] ==> default: [87.51 sec, 15.11 MB] ==> default: Calling hook drush_server_data_post_provision_install [87.51 sec, [debug] ==> default: 15.11 MB] ==> default: server_data received from the frontend (d()->server_data): [87.51 [notice] ==> default: sec, 15.11 MB] ==> default: Returned from hook drush_server_data_post_provision_install [87.51 [debug] ==> default: sec, 15.11 MB] ==> default: Calling hook drush_site_data_post_provision_install [87.51 sec, 15.11 [debug] ==> default: MB] ==> default: site_data received from the frontend (d()->site_data): [87.51 sec, [notice] ==> default: 15.11 MB] ==> default: Returned from hook drush_site_data_post_provision_install [87.51 sec, [debug] ==> default: 15.11 MB] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Drushrc_Site: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_site.tpl.php ==> default: [87.51 sec, 15.11 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php to ==> default: 640 [87.51 sec, 15.11 MB] ==> default: Generated config in write(): Site Drush configuration file [success] ==> default: (/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php) ==> default: [87.51 sec, 15.12 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php to ==> default: 440 [87.51 sec, 15.12 MB] ==> default: Platforms path /var/aegir/platforms exists. [87.51 sec, 15.12 MB] [success] ==> default: Platforms ownership of /var/aegir/platforms has been changed to [success] ==> default: aegir. [87.51 sec, 15.12 MB] ==> default: Platforms permissions of /var/aegir/platforms have been changed to [success] ==> default: 755. [87.51 sec, 15.12 MB] ==> default: Platforms path /var/aegir/platforms is writable. [87.51 sec, 15.12 [success] ==> default: MB] ==> default: Command dispatch complete [87.51 sec, 15.12 MB] [notice] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes @hostmaster provision-verify 2>&1 [87.51 sec, 15.12 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes @hostmaster [notice] ==> default: provision-verify 2>&1 [87.51 sec, 15.12 MB] ==> default: Bootstrap to phase 0. [87.51 sec, 15.12 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.51 sec, 15.12 MB] [bootstrap] ==> default: Found command: provision-verify (commandfile=provision) [87.51 sec, [bootstrap] ==> default: 15.12 MB] ==> default: Loaded alias @server_localhost from file [notice] ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php [87.51 sec, ==> default: 15.13 MB] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.51 sec, 15.13 ==> default: MB] ==> default: Loading apache driver for the http service [87.51 sec, 15.13 MB] [notice] ==> default: Loading mysql driver for the db service [87.51 sec, 15.13 MB] [notice] ==> default: Loaded alias @platform_hostmaster from file [notice] ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php [87.51 sec, ==> default: 15.13 MB] ==> default: Including [bootstrap] ==> default: /usr/share/drush/commands/provision/platform/backupmigrate/verify.provision.inc ==> default: [87.51 sec, 15.13 MB] ==> default: Including [bootstrap] ==> default: /usr/share/drush/commands/provision/platform/verify.provision.inc ==> default: [87.51 sec, 15.13 MB] ==> default: Calling hook drush_provision_drupal_provision_verify_validate [87.51 [debug] ==> default: sec, 15.13 MB] ==> default: Drush bootstrap phase : bootstrap_drupal_root() [87.51 sec, 15.13 MB][bootstrap] ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/all/drush/drushrc.php" into ==> default: "drupal" scope. [87.51 sec, 15.13 MB] ==> default: Initialized Drupal 7.54 root directory at [bootstrap] ==> default: /var/aegir/hostmaster-7.x-3.x [87.51 sec, 15.13 MB] ==> default: Find command files for phase 1 (max=) [87.51 sec, 15.13 MB] [debug] ==> default: Cache HIT cid: 8.1.9-commandfiles-1-a023a35c126b8cf2bdb3f76a83a2a543 [debug] ==> default: [87.51 sec, 15.14 MB] ==> default: Cache HIT cid: [debug] ==> default: 8.1.9-annotationfiles-1-d77ecfa976263ac65d4ec71dfaafe693 [87.51 sec, ==> default: 15.14 MB] ==> default: Drush bootstrap phase : bootstrap_drupal_site() [87.51 sec, 15.14 MB][bootstrap] ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php" ==> default: into "site" scope. [87.51 sec, 15.14 MB] ==> default: Initialized Drupal site aegir.example.com at sites/aegir.example.com [bootstrap] ==> default: [87.51 sec, 15.14 MB] ==> default: Find command files for phase 2 (max=) [87.51 sec, 15.14 MB] [debug] ==> default: Cache HIT cid: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [debug] ==> default: [87.51 sec, 15.14 MB] ==> default: Cache MISS cid: 8.1.9-commandfiles-2-7688a8e753eb5338cfcd03a1d611abb0 [debug] ==> default: [87.51 sec, 15.14 MB] ==> default: Cache SET cid: 8.1.9-commandfiles-2-7688a8e753eb5338cfcd03a1d611abb0 [debug] ==> default: [87.51 sec, 15.14 MB] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-annotationfiles-2-f5b5daa2cb4ed83ce819bbf9326496a6 [87.51 sec, ==> default: 15.14 MB] ==> default: Cache SET cid: [debug] ==> default: 8.1.9-annotationfiles-2-f5b5daa2cb4ed83ce819bbf9326496a6 [87.51 sec, ==> default: 15.14 MB] ==> default: Changed group ownership of [success] ==> default: <code>/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/local.settings.php</code> ==> default: to www-data [87.51 sec, 15.14 MB] ==> default: Changed permissions of [success] ==> default: <code>/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/local.settings.php</code> ==> default: to 440 [87.51 sec, 15.15 MB] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Drupal_Settings: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/Drupal/provision_drupal_settings_7.tpl.php ==> default: [87.51 sec, 15.15 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php to ==> default: 640 [87.51 sec, 15.15 MB] ==> default: Generated config in write(): Drupal settings.php file [success] ==> default: (/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php) ==> default: [87.51 sec, 15.15 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php to ==> default: 440 [87.51 sec, 15.15 MB] ==> default: Change group ownership of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php to ==> default: www-data [87.51 sec, 15.15 MB] ==> default: Platforms path /var/aegir/platforms exists. [87.51 sec, 15.15 MB] [success] ==> default: Platforms ownership of /var/aegir/platforms has been changed to [success] ==> default: aegir. [87.51 sec, 15.15 MB] ==> default: Platforms permissions of /var/aegir/platforms have been changed to [success] ==> default: 755. [87.51 sec, 15.15 MB] ==> default: Platforms path /var/aegir/platforms is writable. [87.51 sec, 15.15 [success] ==> default: MB] ==> default: Drush bootstrap phase : bootstrap_drupal_configuration() [87.51 sec, [bootstrap] ==> default: 15.15 MB] ==> default: Find command files for phase 3 (max=) [87.51 sec, 15.15 MB] [debug] ==> default: sql-query: SELECT 1; [87.51 sec, 15.16 MB] [notice] ==> default: sql-query: SHOW TABLES; [87.51 sec, 15.16 MB] [notice] ==> default: Drush bootstrap phase : bootstrap_drupal_database() [87.51 sec, 15.16[bootstrap] ==> default: MB] ==> default: Successfully connected to the Drupal database. [87.51 sec, 15.16 MB] [bootstrap] ==> default: Find command files for phase 4 (max=) [87.51 sec, 15.16 MB] [debug] ==> default: Drush bootstrap phase : bootstrap_drupal_full() [87.51 sec, 15.16 MB][bootstrap] ==> default: Cache HIT cid: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [debug] ==> default: [87.51 sec, 15.16 MB] ==> default: Find command files for phase 5 (max=) [87.51 sec, 15.16 MB] [debug] ==> default: Cache HIT cid: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [debug] ==> default: [87.51 sec, 15.16 MB] ==> default: Cache HIT cid: 8.1.9-commandfiles-5-c578520176739d7f859ee351ea965df2 [debug] ==> default: [87.51 sec, 15.16 MB] ==> default: Cache HIT cid: [debug] ==> default: 8.1.9-annotationfiles-5-f889260044802601cdd1289e980c5455 [87.51 sec, ==> default: 15.16 MB] ==> default: Returned from hook drush_provision_drupal_provision_verify_validate [debug] ==> default: [87.51 sec, 15.16 MB] ==> default: Calling hook drush_provision_civicrm_pre_provision_verify [87.51 sec, [debug] ==> default: 15.17 MB] ==> default: Returned from hook drush_provision_civicrm_pre_provision_verify [debug] ==> default: [87.52 sec, 15.17 MB] ==> default: Calling hook drush_provision_drupal_pre_provision_verify [87.52 sec, [debug] ==> default: 15.17 MB] ==> default: Found install profile standard [87.52 [notice] ==> default: sec, 15.17 MB] ==> default: Found install profile hostmaster [87.52 [notice] ==> default: sec, 15.17 MB] ==> default: Found install profile minimal [87.52 [notice] ==> default: sec, 15.17 MB] ==> default: Found 118 modules [87.52 sec, 15.17 MB] [notice] ==> default: Found 5 themes [87.52 sec, 15.17 MB] [notice] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Drupal_Alias_Store: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/Drupal/Alias/provision_drupal_sites.tpl.php ==> default: [87.52 sec, 15.17 MB] ==> default: Generated config in write(): Drupal sites.php file [success] ==> default: (/var/aegir/hostmaster-7.x-3.x/sites/sites.php) [87.52 sec, 15.17 MB] ==> default: Changed permissions of /var/aegir/hostmaster-7.x-3.x/sites/sites.php [success] ==> default: to 644 [87.52 sec, 15.17 MB] ==> default: Changed group ownership of [success] ==> default: <code>/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/local.settings.php</code> ==> default: to www-data [87.52 sec, 15.18 MB] ==> default: Changed permissions of [success] ==> default: <code>/var/aegir ==> default: /hostmaster-7.x-3.x/sites/aegir.example.com/local.settings.php</code> ==> default: to 440 [87.52 sec, 15.18 MB] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Drupal_Settings: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/Drupal/provision_drupal_settings_7.tpl.php ==> default: [87.52 sec, 15.18 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php to ==> default: 640 [87.52 sec, 15.18 MB] ==> default: Generated config in write(): Drupal settings.php file [success] ==> default: (/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php) ==> default: [87.52 sec, 15.18 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php to ==> default: 440 [87.52 sec, 15.18 MB] ==> default: Change group ownership of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/settings.php to ==> default: www-data [87.52 sec, 15.18 MB] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Drushrc_Aegir: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_aegir.tpl.php ==> default: [87.52 sec, 15.18 MB] ==> default: Generated config in write(): Aegir Drush configuration file [success] ==> default: (/var/aegir/.drush/drushrc.php) [87.52 sec, 15.18 MB] ==> default: Changed permissions of /var/aegir/.drush/drushrc.php to 440 [87.52 [success] ==> default: sec, 15.18 MB] ==> default: Platforms path /var/aegir/platforms exists. [87.52 sec, 15.18 MB] [success] ==> default: Platforms ownership of /var/aegir/platforms has been changed to [success] ==> default: aegir. [87.52 sec, 15.18 MB] ==> default: Platforms permissions of /var/aegir/platforms have been changed to [success] ==> default: 755. [87.52 sec, 15.19 MB] ==> default: Platforms path /var/aegir/platforms is writable. [87.52 sec, 15.19 [success] ==> default: MB] ==> default: Returned from hook drush_provision_drupal_pre_provision_verify [87.52 [debug] ==> default: sec, 15.19 MB] ==> default: Calling hook drush_provision_verify [87.52 sec, 15.19 MB] [debug] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes @hostmaster provision-save 2>&1 [87.52 sec, 15.19 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes @hostmaster [notice] ==> default: provision-save 2>&1 [87.52 sec, 15.19 MB] ==> default: Bootstrap to phase 0. [87.52 sec, 15.19 MB] [bootstrap] ==> default: Bootstrap to phase 0. [87.52 sec, 15.19 MB] [bootstrap] ==> default: Found command: provision-save (commandfile=provision) [87.52 sec, [bootstrap] ==> default: 15.19 MB] ==> default: Loaded alias @server_localhost from file [notice] ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php [87.52 sec, ==> default: 15.19 MB] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.52 sec, 15.19 ==> default: MB] ==> default: Loading apache driver for the http service [87.52 sec, 15.19 MB] [notice] ==> default: Loading mysql driver for the db service [87.52 sec, 15.2 MB] [notice] ==> default: Loaded alias @platform_hostmaster from file [notice] ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php [87.52 sec, ==> default: 15.2 MB] ==> default: Calling hook drush_provision_save [87.52 sec, 15.2 MB] [debug] ==> default: Template loaded from Provision Config class [notice] ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.52 sec, 15.2 MB] ==> default: Changed permissions of /var/aegir/.drush/hostmaster.alias.drushrc.php [success] ==> default: to 640 [87.52 sec, 15.2 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: (/var/aegir/.drush/hostmaster.alias.drushrc.php) [87.52 sec, 15.2 MB] ==> default: Changed permissions of /var/aegir/.drush/hostmaster.alias.drushrc.php [success] ==> default: to 440 [87.52 sec, 15.2 MB] ==> default: Loaded alias @hm from file /var/aegir/.drush/hm.alias.drushrc.php [notice] ==> default: [87.52 sec, 15.2 MB] ==> default: Template loaded from Provision Config class [notice] ==> default: Provision_Config_Drushrc_Alias: ==> default: /usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_alias.tpl.php ==> default: [87.52 sec, 15.2 MB] ==> default: Changed permissions of /var/aegir/.drush/hm.alias.drushrc.php to 640 [success] ==> default: [87.52 sec, 15.2 MB] ==> default: Generated config in write(): Drush configuration file [success] ==> default: (/var/aegir/.drush/hm.alias.drushrc.php) [87.52 sec, 15.2 MB] ==> default: Changed permissions of /var/aegir/.drush/hm.alias.drushrc.php to 440 [success] ==> default: [87.52 sec, 15.2 MB] ==> default: Returned from hook drush_provision_save [87.52 sec, 15.21 MB] [debug] ==> default: Command dispatch complete [87.52 sec, 15.21 MB] [notice] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Apache_Site: default: class="placeholder">/usr/share/drush/commands/provision/http/Provision/Config/Apache/vhost.tpl.php ==> default: [87.52 sec, 15.21 MB] ==> default: Generated config in write(): virtual host configuration file [success] ==> default: (/var/aegir/config/server_master/apache/vhost.d/aegir.example.com) ==> default: [87.52 sec, 15.21 MB] ==> default: apache on default: class="placeholder">aegir3-deb-unstable-no-puppet.test has been ==> default: restarted [87.52 sec, 15.21 MB] ==> default: Returned from hook drush_provision_verify [87.52 sec, 15.21 MB] [debug] ==> default: Calling hook drush_provision_civicrm_provision_verify [87.52 sec, [debug] ==> default: 15.21 MB] ==> default: Returned from hook drush_provision_civicrm_provision_verify [87.52 [debug] ==> default: sec, 15.21 MB] ==> default: Calling hook drush_provision_civicrm_post_provision_verify [87.52 [debug] ==> default: sec, 15.21 MB] ==> default: Returned from hook drush_provision_civicrm_post_provision_verify [debug] ==> default: [87.52 sec, 15.21 MB] ==> default: Calling hook drush_provision_drupal_post_provision_verify [87.52 sec, [debug] ==> default: 15.21 MB] ==> default: All caches flushed [87.52 sec, 15.21 MB] [notice] ==> default: Client home directory for admin path /var/aegir/clients/admin exists. [success] ==> default: [87.52 sec, 15.22 MB] ==> default: Client home directory for admin ownership of /var/aegir/clients/admin [success] ==> default: has been changed to aegir. [87.52 sec, 15.22 MB] ==> default: Client home directory for admin permissions of [success] ==> default: /var/aegir/clients/admin have been changed to 750. [87.52 sec, 15.22 ==> default: MB] ==> default: Client home directory for admin path /var/aegir/clients/admin is [success] ==> default: writable. [87.52 sec, 15.22 MB] ==> default: Created symlink /var/aegir/clients/admin/aegir.example.com to [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com [87.52 sec, ==> default: 15.22 MB] ==> default: Returned from hook drush_provision_drupal_post_provision_verify [debug] ==> default: [87.52 sec, 15.22 MB] ==> default: Calling hook drush_provision_git_post_provision_verify [87.52 sec, [debug] ==> default: 15.22 MB] ==> default: Returned from hook drush_provision_git_post_provision_verify [87.52 [debug] ==> default: sec, 15.22 MB] ==> default: Calling hook drush_server_data_post_provision_verify [87.52 sec, [debug] ==> default: 15.22 MB] ==> default: server_data received from the frontend (d()->server_data): [87.52 [notice] ==> default: sec, 15.22 MB] ==> default: Returned from hook drush_server_data_post_provision_verify [87.52 [debug] ==> default: sec, 15.22 MB] ==> default: Calling hook drush_site_data_post_provision_verify [87.52 sec, 15.23 [debug] ==> default: MB] ==> default: site_data received from the frontend (d()->site_data): [87.52 sec, [notice] ==> default: 15.23 MB] ==> default: Returned from hook drush_site_data_post_provision_verify [87.52 sec, [debug] ==> default: 15.23 MB] ==> default: Calling hook drush_zz_fix_ownership_post_provision_verify [87.53 sec, [debug] ==> default: 15.23 MB] ==> default: [87.53 sec, 15.23 MB] [warning] ==> default: We trust you have received the usual lecture from the local System [warning] ==> default: [87.53 sec, 15.23 MB] ==> default: Administrator. It usually boils down to these three things: [87.53 [warning] ==> default: sec, 15.23 MB] ==> default: [87.53 sec, 15.23 MB] [warning] ==> default: #1) Respect the privacy of others. [87.53 sec, 15.23 MB] [warning] ==> default: #2) Think before you type. [87.53 sec, 15.23 MB] [warning] ==> default: #3) With great power comes great responsibility. [87.53 sec, [warning] ==> default: 15.23 MB] ==> default: [87.53 sec, 15.23 MB] [warning] ==> default: sudo: no tty present and no askpass program specified [87.53 sec, [warning] ==> default: 15.24 MB] ==> default: Returned from hook drush_zz_fix_ownership_post_provision_verify [debug] ==> default: [87.53 sec, 15.24 MB] ==> default: Calling hook drush_zz_fix_permissions_post_provision_verify [87.53 [debug] ==> default: sec, 15.24 MB] ==> default: [87.53 sec, 15.24 MB] [warning] ==> default: We trust you have received the usual lecture from the local System [warning] ==> default: [87.53 sec, 15.24 MB] ==> default: Administrator. It usually boils down to these three things: [87.53 [warning] ==> default: sec, 15.24 MB] ==> default: [87.53 sec, 15.24 MB] [warning] ==> default: #1) Respect the privacy of others. [87.53 sec, 15.24 MB] [warning] ==> default: #2) Think before you type. [87.53 sec, 15.24 MB] [warning] ==> default: #3) With great power comes great responsibility. [87.53 sec, [warning] ==> default: 15.24 MB] ==> default: [87.53 sec, 15.24 MB] [warning] ==> default: sudo: no tty present and no askpass program specified [87.53 sec, [warning] ==> default: 15.24 MB] ==> default: Returned from hook drush_zz_fix_permissions_post_provision_verify [debug] ==> default: [87.53 sec, 15.25 MB] ==> default: Template loaded from Provision Config class default: class="placeholder">Provision_Config_Drushrc_Site: default: class="placeholder">/usr/share/drush/commands/provision/Provision/Config/Drushrc/provision_drushrc_site.tpl.php ==> default: [87.53 sec, 15.25 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php to ==> default: 640 [87.53 sec, 15.25 MB] ==> default: Generated config in write(): Site Drush configuration file [success] ==> default: (/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php) ==> default: [87.53 sec, 15.25 MB] ==> default: Changed permissions of [success] ==> default: /var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php to ==> default: 440 [87.53 sec, 15.25 MB] ==> default: Platforms path /var/aegir/platforms exists. [87.53 sec, 15.25 MB] [success] ==> default: Platforms ownership of /var/aegir/platforms has been changed to [success] ==> default: aegir. [87.53 sec, 15.25 MB] ==> default: Platforms permissions of /var/aegir/platforms have been changed to [success] ==> default: 755. [87.53 sec, 15.25 MB] ==> default: Platforms path /var/aegir/platforms is writable. [87.53 sec, 15.25 [success] ==> default: MB] ==> default: Command dispatch complete [87.53 sec, 15.25 MB] [notice] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes --root= --uri= cache-clear drush 2>&1 [87.53 sec, 15.25 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes --root= [notice] ==> default: --uri= cache-clear drush 2>&1 [87.53 sec, 15.25 MB] ==> default: Bootstrap to phase 0. [87.53 sec, 15.26 MB] [bootstrap] ==> default: Trying to bootstrap as far as we can. [87.53 sec, 15.26 MB] [debug] ==> default: Found command: cache-clear (commandfile=cache) [87.53 sec, 15.26 MB] [bootstrap] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.53 sec, 15.26 ==> default: MB] ==> default: Loading apache driver for the http service [87.53 sec, 15.26 MB] [notice] ==> default: Calling hook drush_cache_clear_pre_validate [87.53 sec, 15.26 MB] [debug] ==> default: Returned from hook drush_cache_clear_pre_validate [87.53 sec, 15.26 [debug] ==> default: MB] ==> default: Calling hook drush_cache_command_clear [87.53 sec, 15.26 MB] [debug] ==> default: Calling drush_cache_clear_drush() [87.53 sec, 15.26 MB] [debug] ==> default: 'drush' cache was cleared. [87.53 sec, 15.26 MB] [success] ==> default: Returned from hook drush_cache_command_clear [87.53 sec, 15.26 MB] [debug] ==> default: Command dispatch complete [87.53 sec, 15.26 MB] [notice] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes @hostmaster hosting-setup 2>&1 [87.53 sec, 15.27 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes @hostmaster [notice] ==> default: hosting-setup 2>&1 [87.53 sec, 15.27 MB] ==> default: Bootstrap to phase 0. [87.53 sec, 15.27 MB] [bootstrap] ==> default: Bootstrap to phase 1. [87.53 sec, 15.27 MB] [bootstrap] ==> default: Drush bootstrap phase : bootstrap_drupal_root() [87.53 sec, 15.27 MB][bootstrap] ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/all/drush/drushrc.php" into ==> default: "drupal" scope. [87.53 sec, 15.27 MB] ==> default: Initialized Drupal 7.54 root directory at [bootstrap] ==> default: /var/aegir/hostmaster-7.x-3.x [87.53 sec, 15.27 MB] ==> default: Find command files for phase 1 (max=1) [87.53 sec, 15.27 MB] [debug] ==> default: Cache MISS cid: 8.1.9-commandfiles-1-a023a35c126b8cf2bdb3f76a83a2a543 [debug] ==> default: [87.53 sec, 15.27 MB] ==> default: Cache SET cid: 8.1.9-commandfiles-1-a023a35c126b8cf2bdb3f76a83a2a543 [debug] ==> default: [87.53 sec, 15.27 MB] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-annotationfiles-1-d77ecfa976263ac65d4ec71dfaafe693 [87.53 sec, ==> default: 15.27 MB] ==> default: Cache SET cid: [debug] ==> default: 8.1.9-annotationfiles-1-d77ecfa976263ac65d4ec71dfaafe693 [87.53 sec, ==> default: 15.28 MB] ==> default: Bootstrap to phase 5. [87.53 sec, 15.28 MB] [bootstrap] ==> default: Drush bootstrap phase : bootstrap_drupal_site() [87.53 sec, 15.28 MB][bootstrap] ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php" ==> default: into "site" scope. [87.53 sec, 15.28 MB] ==> default: Initialized Drupal site aegir.example.com at sites/aegir.example.com [bootstrap] ==> default: [87.53 sec, 15.28 MB] ==> default: Find command files for phase 2 (max=5) [87.53 sec, 15.28 MB] [debug] ==> default: Drush bootstrap phase : bootstrap_drupal_configuration() [87.53 sec, [bootstrap] ==> default: 15.28 MB] ==> default: Find command files for phase 3 (max=5) [87.53 sec, 15.28 MB] [debug] ==> default: sql-query: SELECT 1; [87.53 sec, 15.28 MB] [notice] ==> default: sql-query: SHOW TABLES; [87.53 sec, 15.28 MB] [notice] ==> default: Drush bootstrap phase : bootstrap_drupal_database() [87.53 sec, 15.28[bootstrap] ==> default: MB] ==> default: Successfully connected to the Drupal database. [87.53 sec, 15.28 MB] [bootstrap] ==> default: Find command files for phase 4 (max=5) [87.53 sec, 15.29 MB] [debug] ==> default: Drush bootstrap phase : bootstrap_drupal_full() [87.53 sec, 15.29 MB][bootstrap] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [87.53 sec, ==> default: 15.29 MB] ==> default: Cache SET cid: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [debug] ==> default: [87.53 sec, 15.29 MB] ==> default: Find command files for phase 5 (max=5) [87.53 sec, 15.29 MB] [debug] ==> default: Cache HIT cid: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [debug] ==> default: [87.53 sec, 15.29 MB] ==> default: Cache MISS cid: 8.1.9-commandfiles-5-c578520176739d7f859ee351ea965df2 [debug] ==> default: [87.53 sec, 15.29 MB] ==> default: Cache SET cid: 8.1.9-commandfiles-5-c578520176739d7f859ee351ea965df2 [debug] ==> default: [87.53 sec, 15.29 MB] ==> default: Cache MISS cid: [debug] ==> default: 8.1.9-annotationfiles-5-f889260044802601cdd1289e980c5455 [87.53 sec, ==> default: 15.29 MB] ==> default: Cache SET cid: [debug] ==> default: 8.1.9-annotationfiles-5-f889260044802601cdd1289e980c5455 [87.53 sec, ==> default: 15.29 MB] ==> default: Bootstrap to phase 6. [87.53 sec, 15.29 MB] [bootstrap] ==> default: Drush bootstrap phase : bootstrap_drupal_login() [87.53 sec, 15.29 [bootstrap] ==> default: MB] ==> default: Find command files for phase 6 (max=6) [87.53 sec, 15.3 MB] [debug] ==> default: Found command: hosting-setup (commandfile=hosting) [87.53 sec, 15.3 [bootstrap] ==> default: MB] ==> default: Loaded alias @server_localhost from f ==> default: ile [notice] ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php [87.53 sec, 15.3 ==> default: MB] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.53 sec, 15.3 ==> default: MB] ==> default: Loading apache driver for the http service [87.54 sec, 15.3 MB] [notice] ==> default: Loading mysql driver for the db service [87.54 sec, 15.3 MB] [notice] ==> default: Loaded alias @platform_hostmaster from file [notice] ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php [87.54 sec, ==> default: 15.3 MB] ==> default: Calling hook drush_hosting_setup [87.54 sec, 15.3 MB] [debug] ==> default: Dispatch command was run successfully [87.54 sec, 15.3 MB] [success] ==> default: No existing crontab was found [87.54 sec, 15.3 MB] [message] ==> default: Adding hosting-dispatch cron entry to run every minute [87.54 sec, [ok] ==> default: 15.3 MB] ==> default: Installed a new crontab entry. [87.54 sec, 15.3 MB] [success] ==> default: Returned from hook drush_hosting_setup [87.54 sec, 15.31 MB] [debug] ==> default: Command dispatch complete [87.54 sec, 15.31 MB] [notice] ==> default: Returned from hook drush_provision_hostmaster_install [87.54 sec, [debug] ==> default: 15.31 MB] ==> default: Calling hook drush_provision_post_hostmaster_install [87.54 sec, [debug] ==> default: 15.31 MB] ==> default: Backend invoke: /usr/local/bin/drush --backend=2 --verbose --debug [command] ==> default: --yes --uri=aegir.example.com --root=/var/aegir/hostmaster-7.x-3.x ==> default: cache-clear drush 2>&1 [87.54 sec, 15.31 MB] ==> default: /usr/local/bin/drush --backend=2 --verbose --debug --yes [notice] ==> default: --uri=aegir.example.com --root=/var/aegir/hostmaster-7.x-3.x ==> default: cache-clear drush 2>&1 [87.54 sec, 15.31 MB] ==> default: Bootstrap to phase 0. [87.54 sec, 15.31 MB] [bootstrap] ==> default: Trying to bootstrap as far as we can. [87.54 sec, 15.31 MB] [debug] ==> default: Drush bootstrap phase : bootstrap_drupal_root() [87.54 sec, 15.31 MB][bootstrap] ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/all/drush/drushrc.php" into ==> default: "drupal" scope. [87.54 sec, 15.31 MB] ==> default: Initialized Drupal 7.54 root directory at [bootstrap] ==> default: /var/aegir/hostmaster-7.x-3.x [87.54 sec, 15.31 MB] ==> default: Find command files for phase 1 (max=7) [87.54 sec, 15.31 MB] [debug] ==> default: Cache HIT cid: 8.1.9-commandfiles-1-a023a35c126b8cf2bdb3f76a83a2a543 [debug] ==> default: [87.54 sec, 15.32 MB] ==> default: Cache HIT cid: [debug] ==> default: 8.1.9-annotationfiles-1-d77ecfa976263ac65d4ec71dfaafe693 [87.54 sec, ==> default: 15.32 MB] ==> default: Drush bootstrap phase : bootstrap_drupal_site() [87.54 sec, 15.32 MB][bootstrap] ==> default: Loading drushrc [bootstrap] ==> default: "/var/aegir/hostmaster-7.x-3.x/sites/aegir.example.com/drushrc.php" ==> default: into "site" scope. [87.54 sec, 15.32 MB] ==> default: Initialized Drupal site aegir.example.com at sites/aegir.example.com [bootstrap] ==> default: [87.54 sec, 15.32 MB] ==> default: Find command files for phase 2 (max=7) [87.54 sec, 15.32 MB] [debug] ==> default: Drush bootstrap phase : bootstrap_drupal_configuration() [87.54 sec, [bootstrap] ==> default: 15.32 MB] ==> default: Find command files for phase 3 (max=7) [87.54 sec, 15.32 MB] [debug] ==> default: sql-query: SELECT 1; [87.54 sec, 15.32 MB] [notice] ==> default: sql-query: SHOW TABLES; [87.54 sec, 15.32 MB] [notice] ==> default: Drush bootstrap phase : bootstrap_drupal_database() [87.54 sec, 15.32[bootstrap] ==> default: MB] ==> default: Successfully connected to the Drupal database. [87.54 sec, 15.33 MB] [bootstrap] ==> default: Find command files for phase 4 (max=7) [87.54 sec, 15.33 MB] [debug] ==> default: Drush bootstrap phase : bootstrap_drupal_full() [87.54 sec, 15.33 MB][bootstrap] ==> default: Cache HIT cid: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [debug] ==> default: [87.54 sec, 15.33 MB] ==> default: Find command files for phase 5 (max=7) [87.54 sec, 15.33 MB] [debug] ==> default: Cache HIT cid: 8.1.9-install_profile-c6cdcf4422b121f3d1c20f2de96f0ebe [debug] ==> default: [87.54 sec, 15.33 MB] ==> default: Cache HIT cid: 8.1.9-commandfiles-5-c578520176739d7f859ee351ea965df2 [debug] ==> default: [87.54 sec, 15.33 MB] ==> default: Cache HIT cid: [debug] ==> default: 8.1.9-annotationfiles-5-f889260044802601cdd1289e980c5455 [87.54 sec, ==> default: 15.33 MB] ==> default: Drush bootstrap phase : bootstrap_drupal_login() [87.54 sec, 15.33 [bootstrap] ==> default: MB] ==> default: Find command files for phase 6 (max=7) [87.54 sec, 15.33 MB] [debug] ==> default: Found command: cache-clear (commandfile=cache) [87.54 sec, 15.33 MB] [bootstrap] ==> default: Cache HIT cid: 8.1.9-alias-path--4df8b0ec90f271ae15936e58072c7fca [debug] ==> default: [87.54 sec, 15.33 MB] ==> default: Cache SET cid: 8.1.9-alias-path--4df8b0ec90f271ae15936e58072c7fca [debug] ==> default: [87.54 sec, 15.34 MB] ==> default: Loaded alias @server_localhost from file [notice] ==> default: /var/aegir/.drush/server_localhost.alias.drushrc.php [87.54 sec, ==> default: 15.34 MB] ==> default: Loaded alias @server_master from file [notice] ==> default: /var/aegir/.drush/server_master.alias.drushrc.php [87.54 sec, 15.34 ==> default: MB] ==> default: Loading apache driver for the http service [87.54 sec, 15.34 MB] [notice] ==> default: Loading mysql driver for the db service [87.54 sec, 15.34 MB] [notice] ==> default: Loaded alias @platform_hostmaster from file [notice] ==> default: /var/aegir/.drush/platform_hostmaster.alias.drushrc.php [87.54 sec, ==> default: 15.34 MB] ==> default: Calling hook drush_cache_clear_pre_validate [87.54 sec, 15.34 MB] [debug] ==> default: Returned from hook drush_cache_clear_pre_validate [87.54 sec, 15.34 [debug] ==> default: MB] ==> default: Calling hook drush_cache_command_clear [87.54 sec, 15.34 MB] [debug] ==> default: Calling drush_cache_clear_drush() [87.54 sec, 15.34 MB] [debug] ==> default: 'drush' cache was cleared. [87.54 sec, 15.34 MB] [success] ==> default: Returned from hook drush_cache_command_clear [87.54 sec, 15.34 MB] [debug] ==> default: Command dispatch complete [87.54 sec, 15.35 MB] [notice] ==> default: Cache MISS cid: 8.1.9-alias-path--4df8b0ec90f271ae15936e58072c7fca [debug] ==> default: [87.54 sec, 15.35 MB] ==> default: Cache SET cid: 8.1.9-alias-path--4df8b0ec90f271ae15936e58072c7fca [debug] ==> default: [87.54 sec, 15.35 MB] ==> default: Returned from hook drush_provision_post_hostmaster_install [87.54 [debug] ==> default: sec, 15.35 MB] ==> default: Command dispatch complete [87.54 sec, 15.35 MB] [notice] ==> default: ERROR 1045 (28000): Access denied for user 'intntnllyInvalid'@'localhost' (using password: NO) ==> default: Aegir 7.x-3.x automated install script ==> default: ============================================================================== ==> default: Some settings have not been provided and will now be prompted. ==> default: Don't worry: you will get to review those settings after the final install ==> default: ==> default: This script will operate the following changes in your system: ==> default: ==> default: 1. Create server-level configuration directories ==> default: 2. Create the Hostmaster frontend platform ==> default: 3. Install the frontend site ==> default: 4. Setup the dispatcher (a user cron job) ==> default: ==> default: We are making the following assumptions: ==> default: * you have read and are following the install instructions at: ==> default: http://docs.aegirproject.org/en/3.x/install/ ==> default: * the FQDN of this machine is valid and resolves ==> default: * you are executing this script as your "aegir" user ==> default: ==> default: The following settings will be used: ==> default: Aegir frontend URL: aegir.example.com ==> default: Master server FQDN: aegir3-deb-unstable-no-puppet.test ==> default: Aegir root: /var/aegir ==> default: Aegir user: aegir ==> default: Web group: www-data ==> default: Web server: apache ==> default: Web server port: 80 ==> default: Aegir DB host: localhost ==> default: Aegir DB user: root ==> default: Aegir DB password: <previously set> ==> default: Aegir DB port: 3306 ==> default: Aegir version: 7.x-3.x ==> default: Aegir platform path: /var/aegir/hostmaster-7.x-3.x ==> default: Admin email: aegir@example.com ==> default: Aegir makefile: /usr/share/drush/commands/provision/aegir.make ==> default: ==> default: Aegir install profile: hostmaster ==> default: ==> default: Do you really want to proceed with the install (y/n): y ==> default: Initializing the hosting system ==> default: ==> default: ============================================================================== ==> default: ==> default: ==> default: Congratulations, Aegir has now been installed. ==> default: ==> default: You should now log in to the Aegir frontend by opening the following link in your web browser: ==> default: ==> default: http://aegir.example.com/user/reset/1/1487159359/xv_EdcCIGd20VTk-tiDOu3kMXg2sYbgH1QIF5RTl0jQ/login ==> default: ==> default: ==> default: ============================================================================== ==> default: Returned from hook drush_provision_backend_parse [87.54 sec, 13.9 MB] [debug] ==> default: Command dispatch complete [87.54 sec, 12.33 MB] [notice] ==> default: + su -s /bin/sh aegir -c drush cache-clear drush ==> default: 'drush' cache was cleared. [success] ==> default: + echo Enabling hosting-queued daemon ==> default: Enabling hosting-queued daemon ==> default: + su -s /bin/sh aegir -c drush @hostmaster pm-enable -y hosting_queued ==> default: The following extensions will be enabled: hosting_queued ==> default: Do you really want to continue? (y/n): ==> default: y ==> default: hosting_queued was enabled successfully. [ok] ==> default: Enabling Hosting queue daemon feature. [status] ==> default: + service hosting-queued start ==> default: + [ -f /bin/systemctl ] ==> default: + /bin/systemctl enable hosting-queued ==> default: Synchronizing state for hosting-queued.service with sysvinit using update-rc.d... ==> default: Executing /usr/sbin/update-rc.d hosting-queued defaults ==> default: Executing /usr/sbin/update-rc.d hosting-queued enable ==> default: + [ -d /etc/apache2/conf-enabled ] ==> default: + ln -sf /var/aegir/config/apache.conf /etc/apache2/conf-enabled/aegir.conf ==> default: + a2enmod ssl rewrite ==> default: Considering dependency setenvif for ssl: ==> default: Module setenvif already enabled ==> default: Considering dependency mime for ssl: ==> default: Module mime already enabled ==> default: Considering dependency socache_shmcb for ssl: ==> default: Enabling module socache_shmcb. ==> default: Enabling module ssl. ==> default: See /usr/share/doc/apache2/README.Debian.gz on how to configure SSL and create self-signed certificates. ==> default: Enabling module rewrite. ==> default: To activate the new configuration, you need to run: ==> default: service apache2 restart ==> default: + apache2ctl graceful ==> default: AH00548: NameVirtualHost has no effect and will be removed in the next release /etc/apache2/conf-enabled/aegir.conf:3 ==> default: + ==> default: grep ==> default: -q ==> default: Drupal bootstrap.*Successful ==> default: + ==> default: su ==> default: -s ==> default: /bin/sh ==> default: aegir ==> default: -c ==> default: drush @hostmaster status ==> default: + echo Aegir frontend bootstrap correctly, operation was a success! ==> default: Aegir frontend bootstrap correctly, operation was a success! ==> default: + echo Use this URL to login on your new site: ==> default: Use this URL to login on your new site: ==> default: + su -s /bin/sh aegir -c drush @hostmaster uli ==> default: http://aegir.example.com/user/reset/1/1487159397/Zu2tks9gcjpKZvGUsyE_k8_5nDALp9OVyFmS52hrGpU/login ==> default: + [ -x /etc/init.d/hosting-queued ] ==> default: + update-rc.d hosting-queued defaults ==> default: + invoke-rc.d hosting-queued start ==> default: + exit 0 ==> default: Setting up aegir3 (3.9+571.c7a38f8) ... ==> default: Setting up libhtml-template-perl (2.95-1) ... ==> default: Setting up mysql-server (5.5.54-0+deb8u1) ... ==> default: Setting up php5-readline (5.6.30+dfsg-0+deb8u1) ... ==> default: ==> default: Creating config file /etc/php5/mods-available/readline.ini with new version ==> default: DEBUG: dpkg-maintscript-helper: Executing /usr/bin/dpkg-maintscript-helper mv_conffile in postinst of php5-readline ==> default: DEBUG: dpkg-maintscript-helper: CONFFILE=/etc/php5/conf.d/readline.ini -> /etc/php5/mods-available/readline.ini PACKAGE=php5-readline:amd64 LASTVERSION=5.4.0~rc6-1 ACTION=configure PARAM= ==> default: php5_invoke: Enable module readline for apache2 SAPI ==> default: php5_invoke: Enable module readline for cli SAPI ==> default: Setting up rsync (3.1.1-3) ... ==> default: Setting up ssl-cert (1.0.35) ... ==> default: Processing triggers for libc-bin (2.19-18+deb8u1) ... ==> default: Processing triggers for systemd (215-17+deb8u2) ... ==> default: Processing triggers for libapache2-mod-php5 (5.6.30+dfsg-0+deb8u1) ... This command should only be run on a clean Aegir install, and data may be lost! Do you want to continue? (y/n): y Create a custom verify task for the hostmaster site and place it in [ok] the queue. Phar detected. Proceeding to drush_main(). Loading drushrc "/var/aegir/.drush/drushrc.php" into "home.drush" [bootstrap] scope. [0.01 sec, 3.52 MB] Cache HIT cid: 8.1.9-commandfiles-0-a1ab56b0d5baa05a84508f79f69564a5 [debug] [0.01 sec, 3.59 MB] Loaded alias @hostmaster from file [notice] /var/aegir/.drush/hostmaster.alias.drushrc.php [0.16 sec, 10.52 MB] Cache SET cid: 8.1.9-alias-path--4df8b0ec90f271ae15936e58072c7fca [debug] [0.16 sec, 10.52 MB] Loading drushrc [bootstrap] "/var/aegir/hostmaster-7.x-3.x/sites/all/drush/drushrc.php" into "drupal" scope. [0.17 sec, 10.52 MB] Loading drushrc [bootstrap] "/var/aegir/hostmaster-7.x-3.x/sites/all/drush/drushrc.php" into "drupal" scope. [0.17 sec, 11.09 MB] Executing: mysql --defaults-extra-file=/tmp/drush_a4zQcT --database=aegirexamplecom --host=localhost --port=3306 --silent < /tmp/drush_uwusNK 1 Executing: mysql --defaults-extra-file=/tmp/drush_JdLv39 --database=aegirexamplecom --host=localhost --port=3306 --silent < /tmp/drush_YuG9C1 actions authmap batch block block_custom block_node_type block_role blocked_ips cache cache_admin_menu cache_block cache_bootstrap cache_field cache_filter cache_form cache_menu cache_page cache_path cache_update cache_views cache_views_data ctools_css_cache ctools_object_cache date_format_locale date_format_type date_formats field_config field_config_instance file_managed file_usage filter filter_format flood history hosting_client hosting_client_user hosting_context hosting_db_server hosting_ip_addresses hosting_package hosting_package_instance hosting_package_languages hosting_platform hosting_platform_client_access hosting_server hosting_service hosting_site hosting_site_backups hosting_task hosting_task_arguments hosting_task_log menu_custom menu_links menu_router node node_access node_revision node_type queue registry registry_file role role_permission semaphore sequences sessions system url_alias users users_roles variable views_display views_view Starting task "Verify: aegir.example.com" [node/141]. [ok] Calling proc_open(/usr/local/bin/drush --strict= --verbose --debug --yes --uri=aegir.example.com --root=/var/aegir/hostmaster-7.x-3.x hosting-task 141 2>&1 --quiet &); Launched task "Verify: aegir.example.com" in a forked process. [ok] [node/141] Timer Cum (sec) Count Avg (msec) page 30.381 1 30381.29 Finished running a custom verify task for the hostmaster site. [ok] Building platform: drupal6 and adding to hostmaster. [ok] Beginning to build [ok] /usr/share/drush/commands/provision/provision-tests/makes/drupal6.make. drupal-6.38 downloaded. [ok] drupal patched with drupal7-unicode_requirements-2332295-23.patch. [ok] Generated PATCHES.txt file for drupal [ok] Generated config in write(): Drush configuration file [success] (/var/aegir/.drush/platform_drupal6.alias.drushrc.php) Changed permissions of [success] /var/aegir/.drush/platform_drupal6.alias.drushrc.php to 440 Starting task "Verify: aegir.example.com" [node/142]. [ok] Launched task "Verify: aegir.example.com" in a forked process. [ok] [node/142] Starting task "Verify: drupal6" [node/145]. [ok] Launched task "Verify: drupal6" in a forked process. [node/145] [ok] Building platform: drupal7 and adding to hostmaster. [ok] Beginning to build [ok] /usr/share/drush/commands/provision/provision-tests/makes/drupal7.make. drupal-7.54 downloaded. [ok] Generated config in write(): Drush configuration file [success] (/var/aegir/.drush/platform_drupal7.alias.drushrc.php) Changed permissions of [success] /var/aegir/.drush/platform_drupal7.alias.drushrc.php to 440 Connection to 127.0.0.1 closed by remote host. 'provision-tests-run' failed. Leaving vm in place for forensic analysis. Build step 'Execute shell' marked build as failure Finished: FAILURE