user@domain:~/Public/Sites$ ./Scripts/siteUpdate-v2.sh Updating module and theme installations in /home/user/Public/Sites/drupal Bootstrap to phase 0. [0.2 sec, 1.69 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drush() [0.21 sec, 1.83 MB] [bootstrap] Bootstrap to phase 6. [0.51 sec, 4.05 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_root() [0.51 sec, 4.05 MB] [bootstrap] Initialized Drupal 7.5 root directory at /home/user/Public/Sites/drupal [0.67 sec, 5.01 MB] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_site() [0.67 sec, 5.01 MB] [bootstrap] Initialized Drupal site dev.example1.com at sites/example1.com [0.68 sec, 5.01 MB] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_configuration() [0.72 sec, 5.04 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_database() [0.77 sec, 5.23 MB] [bootstrap] Successfully connected to the Drupal database. [0.77 sec, 5.23 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_full() [0.83 sec, 5.73 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_login() [2.56 sec, 21.15 Mo] [bootstrap] Successfully logged into Drupal as Anonyme (uid=0) [2.57 sec, 21.16 Mo] [bootstrap] Found command: pm-updatecode (commandfile=pm) [2.57 sec, 21.16 Mo] [bootstrap] Initializing drush commandfile: user [2.57 sec, 21.16 Mo] [bootstrap] Including /home/user/Public/Sites/Scripts/drush/commands/pm/updatecode.pm.inc [2.58 sec, 21.16 Mo] [bootstrap] Executing: wget --version Running: /usr/bin/php /home/user/Public/Sites/Scripts/drush/drush.php --php='/usr/bin/php' --root='/home/user/Public/Sites/drupal' [command] --uri='http://dev.example1.com' batch-process '25' --backend 2>&1 [5.91 sec, 22.97 Mo] Bootstrap to phase 0. [9.3 sec, 23 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drush() [9.3 sec, 23 Mo] [bootstrap] Bootstrap to phase 6. [9.3 sec, 23 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_root() [9.3 sec, 23 Mo] [bootstrap] Initialized Drupal 7.5 root directory at /home/user/Public/Sites/drupal [9.3 sec, 23 Mo] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_site() [9.31 sec, 23 Mo] [bootstrap] Initialized Drupal site dev.example1.com at sites/example1.com [9.31 sec, 23 Mo] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_configuration() [9.31 sec, 23 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_database() [9.31 sec, 23 Mo] [bootstrap] Successfully connected to the Drupal database. [9.31 sec, 23.01 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_full() [9.31 sec, 23.01 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_login() [9.31 sec, 23.01 Mo] [bootstrap] Successfully logged into Drupal as Anonyme (uid=0) [9.31 sec, 23.01 Mo] [bootstrap] Found command: batch-process (commandfile=core) [9.31 sec, 23.01 Mo] [bootstrap] Initializing drush commandfile: user [9.31 sec, 23.01 Mo] [bootstrap] Batch process has consumed in excess of 50% of available memory. Starting new thread [9.31 sec, 23.01 Mo] [batch] Checked available update data for one project. [9.31 sec, 23.01 Mo] [status] Command dispatch complete [9.32 sec, 23.01 Mo] [notice] Peak memory usage was 21.63 Mo [9.32 sec, 23.01 Mo] [memory] Update information last refreshed: Sun, 31/07/2011 - 15:14 Update status information on all installed and enabled Drupal projects: Nom Installed version Proposed version Statut Administration menu 7.x-3.0-rc1 7.x-3.0-rc1 À jour APC - Alternative PHP Cache 7.x-1.0-beta3 7.x-1.0-beta3 À jour Backup and Migrate 7.x-2.2 7.x-2.2 À jour Drupal core 7.5 7.7 Update available Chaos tool suite (ctools) 7.x-1.0-beta1 7.x-1.0-rc1 Update available Media 7.x-1.0-beta5 7.x-1.0-beta5 À jour GMap Module 7.x-1.x-dev 7.x-1.x-dev À jour Localization update 7.x-1.0-beta2 7.x-1.0-beta2 À jour Maxlength 7.x-2.x-dev 7.x-2.x-dev À jour Media: YouTube 7.x-1.0-alpha5 7.x-1.0-alpha5 À jour Memcache API and Integration 7.x-0.0 7.x-0.0 À jour 404 Nausertion 7.x-1.0 7.x-1.0 À jour Varnish HTTP Accelerator Integration 7.x-1.x-dev 7.x-1.x-dev À jour Wysiwyg 7.x-2.1 7.x-2.1 À jour Zen 7.x-3.1 7.x-3.1 À jour Drush folder at /home/user/Public/Sites/Scripts/drush is not writable [9.5 sec, 23.02 Mo] [notice] NOTE: A code update for the Drupal core is available. Skipping core update (--no-core specified). Code updates will be made to the following projects: Chaos tool suite (ctools) [ctools-7.x-1.0-rc1] Note: A backup of your project will be stored to backups directory if it is not managed by a supported version control system. Note: If you have made any modifications to any file that belongs to one of these projects, you will have to migrate those modifications after updating. Do you really want to continue with the update process? (y/n): y Starting to update Chaos tool suite (ctools) code at sites/all/modules/ctools... [134.12 sec, 23.02 Mo] [notice] Verifying signature for svn version control engine. [134.12 sec, 23.03 Mo] [debug] Executing: svn info '/home/user/Public/Sites/drupal/sites/all/modules/ctools' sh: svn: not found Verifying signature for bzr version control engine. [134.13 sec, 23.03 Mo] [debug] Executing: bzr root '/home/user/Public/Sites/drupal/sites/all/modules/ctools' sh: bzr: not found Executing: mkdir '/home/user/Public/Sites/drush-backups/dev_example1/20110731131800' Executing: mkdir '/home/user/Public/Sites/drush-backups/dev_example1/20110731131800/modules' Calling is_readable(/home/user/Public/Sites/drupal/sites/all/modules/ctools) [134.2 sec, 23.04 Mo] [debug] Calling is_writable(/home/user/Public/Sites/drush-backups/dev_example1/20110731131800/modules) [134.2 sec, 23.04 Mo] [debug] Calling rename(/home/user/Public/Sites/drupal/sites/all/modules/ctools, /home/user/Public/Sites/drush-backups/dev_example1/20110731131800/modules/ctools) [debug] [134.2 sec, 23.04 Mo] Executing: wget -P . 'http://ftp.drupal.org/files/projects/ctools-7.x-1.0-rc1.tar.gz' --2011-07-31 15:20:14-- http://ftp.drupal.org/files/projects/ctools-7.x-1.0-rc1.tar.gz Resolving ftp.drupal.org... 64.50.233.100 Connecting to ftp.drupal.org|64.50.233.100|:80... connected. HTTP request sent, awaiting response... 200 OK Length: 381078 (372K) [application/x-gzip] Saving to: `./ctools-7.x-1.0-rc1.tar.gz' 0K .......... .......... .......... .......... .......... 13% 113K 3s [...SNIP...] 350K .......... .......... .. 100% 113K=2.8s 2011-07-31 15:20:17 (134 KB/s) - `./ctools-7.x-1.0-rc1.tar.gz' saved [381078/381078] Downloading ctools-7.x-1.0-rc1.tar.gz was successful. [137.34 sec, 23.04 Mo] [notice] Calling md5_file(ctools-7.x-1.0-rc1.tar.gz) [137.34 sec, 23.04 Mo] [debug] Md5 checksum of ctools-7.x-1.0-rc1.tar.gz verified. [137.35 sec, 23.04 Mo] [notice] Calling chdir(.) [137.35 sec, 23.05 Mo] [debug] Executing: tar -C '/home/user/Public/Sites/drupal/sites/all/modules' -xzf 'ctools-7.x-1.0-rc1.tar.gz' Calling chdir(/home/user/Public/Sites/drupal) [137.66 sec, 23.05 Mo] [debug] Calling chdir(.) [137.66 sec, 23.05 Mo] [debug] Executing: tar -tf 'ctools-7.x-1.0-rc1.tar.gz' ctools/ [...SNIP...] ctools/bulk_export/bulk_export.info Calling chdir(/home/user/Public/Sites/drupal) [42.47 sec, 23.12 Mo] [debug] Calling unlink(ctools-7.x-1.0-rc1.tar.gz) [42.47 sec, 23.14 Mo] [debug] Project ctools was updated successfully. Installed version is now 7.x-1.0-rc1. Backups were saved into the directory /home/user/Public/Sites/drush-backups/dev_example1/20110731131434/modules/ctools. [42.48 sec, 23.11 Mo] [ok] Running: /usr/bin/php /home/user/Public/Sites/Scripts/drush/drush.php --php='/usr/bin/php' --root='/home/user/Public/Sites/drupal' [command] --uri='http://dev.example1.com' pm-updatecode-postupdate --backend 2>&1 [42.51 sec, 22.07 Mo] Bootstrap to phase 0. [59.14 sec, 22.1 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drush() [59.14 sec, 22.11 Mo] [bootstrap] Bootstrap to phase 6. [59.15 sec, 22.11 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_root() [59.15 sec, 22.11 Mo] [bootstrap] Initialized Drupal 7.5 root directory at /home/user/Public/Sites/drupal [59.15 sec, 22.11 Mo] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_site() [59.15 sec, 22.11 Mo] [bootstrap] Initialized Drupal site dev.example1.com at sites/example1.com [59.15 sec, 22.11 Mo] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_configuration() [59.15 sec, 22.11 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_database() [59.15 sec, 22.11 Mo] [bootstrap] Successfully connected to the Drupal database. [59.15 sec, 22.11 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_full() [59.15 sec, 22.11 Mo] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_login() [59.15 sec, 22.11 Mo] [bootstrap] Successfully logged into Drupal as Anonyme (uid=0) [59.15 sec, 22.11 Mo] [bootstrap] Found command: pm-updatecode-postupdate (commandfile=pm) [59.15 sec, 22.11 Mo] [bootstrap] Initializing drush commandfile: user [59.16 sec, 22.12 Mo] [bootstrap] Including /home/user/Public/Sites/Scripts/drush/commands/core/clear.cache.inc [59.16 sec, 22.12 Mo] [bootstrap] 'all' cache was cleared [59.17 sec, 22.12 Mo] [success] Command dispatch complete [59.17 sec, 22.12 Mo] [notice] Peak memory usage was 33.34 Mo [59.17 sec, 22.12 Mo] [memory] Command dispatch complete [59.18 sec, 22.05 Mo] [notice] Timer Cum (sec) Count Avg (msec) page 58.479 1 58478.84 Peak memory usage was 23.81 Mo [59.19 sec, 22.05 Mo] [memory] Here the second iteration of the for loop starts Bootstrap to phase 0. [0.09 sec, 1.69 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drush() [0.1 sec, 1.83 MB] [bootstrap] Bootstrap to phase 6. [0.4 sec, 4.05 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_root() [0.4 sec, 4.05 MB] [bootstrap] Initialized Drupal 7.5 root directory at /home/user/Public/Sites/drupal [0.55 sec, 5.01 MB] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_site() [0.55 sec, 5.01 MB] [bootstrap] Initialized Drupal site dev.example2.com at sites/example2.com [0.55 sec, 5.01 MB] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_configuration() [0.59 sec, 5.04 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_database() [0.64 sec, 5.23 MB] [bootstrap] Successfully connected to the Drupal database. [0.64 sec, 5.23 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_full() [0.71 sec, 5.73 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_login() [8.83 sec, 48.67 MB] [bootstrap] Successfully logged into Drupal as Anonymous (uid=0) [8.97 sec, 49.03 MB] [bootstrap] Found command: pm-updatecode (commandfile=pm) [8.98 sec, 49.03 MB] [bootstrap] Initializing drush commandfile: user [8.98 sec, 49.03 MB] [bootstrap] Including /home/user/Public/Sites/Scripts/drush/commands/pm/updatecode.pm.inc [8.98 sec, 49.04 MB] [bootstrap] Executing: wget --version Running: /usr/bin/php /home/user/Public/Sites/Scripts/drush/drush.php --php='/usr/bin/php' --root='/home/user/Public/Sites/drupal' [command] --uri='http://dev.example2.com' batch-process '11' --backend 2>&1 [12.37 sec, 50.94 MB] Bootstrap to phase 0. [24.03 sec, 50.97 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drush() [24.04 sec, 50.97 MB] [bootstrap] Bootstrap to phase 6. [24.04 sec, 50.97 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_root() [24.04 sec, 50.97 MB] [bootstrap] Initialized Drupal 7.5 root directory at /home/user/Public/Sites/drupal [24.04 sec, 50.97 MB] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_site() [24.04 sec, 50.97 MB] [bootstrap] Initialized Drupal site dev.example2.com at sites/example2.com [24.04 sec, 50.97 MB] [notice] Drush bootstrap phase : _drush_bootstrap_drupal_configuration() [24.04 sec, 50.97 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_database() [24.04 sec, 50.97 MB] [bootstrap] Successfully connected to the Drupal database. [24.04 sec, 50.98 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_full() [24.04 sec, 50.98 MB] [bootstrap] Drush bootstrap phase : _drush_bootstrap_drupal_login() [24.04 sec, 50.98 MB] [bootstrap] Successfully logged into Drupal as Anonymous (uid=0) [24.04 sec, 50.98 MB] [bootstrap] Found command: batch-process (commandfile=core) [24.04 sec, 50.98 MB] [bootstrap] Initializing drush commandfile: user [24.04 sec, 50.98 MB] [bootstrap] Batch process has consumed in excess of 50% of available memory. Starting new thread [24.05 sec, 50.98 MB] [batch] Checked available update data for one project. [24.05 sec, 50.98 MB] [status] Command dispatch complete [24.05 sec, 50.98 MB] [notice] Peak memory usage was 51.02 MB [24.05 sec, 50.98 MB] [memory] Update information last refreshed: Sun, 31/07/2011 - 14:56 Update status information on all installed and enabled Drupal projects: Name Installed version Proposed version Status Address Field 7.x-1.0-beta1 7.x-1.0-beta1 Up to date Administration menu 7.x-3.0-rc1 7.x-3.0-rc1 Up to date Advanced help 7.x-1.0-beta1 7.x-1.0-beta1 Up to date APC - Alternative PHP Cache 7.x-1.0-beta3 7.x-1.0-beta3 Up to date Backup and Migrate 7.x-2.2 7.x-2.2 Up to date Drupal core 7.5 7.7 Update available Drupal Commerce 7.x-1.0-rc1 7.x-1.0-rc1 Up to date Commerce PayPal 7.x-1.x-dev 7.x-1.x-dev Up to date Chaos tool suite (ctools) 7.x-1.0-rc1 7.x-1.0-rc1 Up to date Entity API 7.x-1.0-beta10 7.x-1.0-beta10 Up to date Entity translation 7.x-1.x-dev 7.x-1.x-dev Up to date Media 7.x-1.0-beta5 7.x-1.0-beta5 Up to date Internationalization 7.x-1.0-rc2 7.x-1.0-rc2 Up to date Localization update 7.x-1.0-beta2 7.x-1.0-beta2 Up to date Language icons 7.x-1.0-beta1 7.x-1.0-beta1 Up to date Memcache API and Integration 7.x-0.0 7.x-0.0 Up to date 404 Nausertion 7.x-1.0 7.x-1.0 Up to date Rules 7.x-2.0-beta2 7.x-2.0-beta2 Up to date Title 7.x-1.x-dev 7.x-1.x-dev Up to date Variable 7.x-1.0 7.x-1.0 Up to date Varnish HTTP Accelerator Integration 7.x-1.x-dev 7.x-1.x-dev Up to date Views 7.x-3.0-rc1 7.x-3.0-rc1 Up to date Wysiwyg 7.x-2.1 7.x-2.1 Up to date Zen 7.x-3.1 7.x-3.1 Up to date Drush folder at /home/user/Public/Sites/Scripts/drush is not writable [24.34 sec, 51 MB] [notice] Code updates will be made to drupal core. WARNING: Updating core will discard any modifications made to Drupal core files, most noteworthy among these are .htaccess and robots.txt. If you have made any modifications to these files, please back them up before updating so that you can re-create your modifications in the updated version of the file. Note: Updating core can potentially break your site. It is NOT recommended to update production sites without prior testing. Do you really want to continue? (y/n): y Executing: mkdir '/home/user/Public/Sites/drupal/drupal-7.7' Calling is_readable(/home/user/Public/Sites/drupal/INSTALL.pgsql.txt) [130.87 sec, 51.03 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.87 sec, 51.03 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/INSTALL.pgsql.txt, /home/user/Public/Sites/drupal/drupal-7.7/INSTALL.pgsql.txt) [130.87 sec, 51.03 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/CHANGELOG.txt) [130.87 sec, 51.04 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.87 sec, 51.04 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/CHANGELOG.txt, /home/user/Public/Sites/drupal/drupal-7.7/CHANGELOG.txt) [130.87 sec, 51.04 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/cron.php) [130.88 sec, 51.04 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.88 sec, 51.04 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/cron.php, /home/user/Public/Sites/drupal/drupal-7.7/cron.php) [130.88 sec, 51.04 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/INSTALL.txt) [130.89 sec, 51.04 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.89 sec, 51.04 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/INSTALL.txt, /home/user/Public/Sites/drupal/drupal-7.7/INSTALL.txt) [130.89 sec, 51.04 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/.htaccess) [130.89 sec, 51.04 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.89 sec, 51.05 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/.htaccess, /home/user/Public/Sites/drupal/drupal-7.7/.htaccess) [130.89 sec, 51.05 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/README.txt) [130.9 sec, 51.05 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.91 sec, 51.05 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/README.txt, /home/user/Public/Sites/drupal/drupal-7.7/README.txt) [130.91 sec, 51.05 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/web.config) [130.91 sec, 51.05 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.91 sec, 51.05 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/web.config, /home/user/Public/Sites/drupal/drupal-7.7/web.config) [130.91 sec, 51.05 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/COPYRIGHT.txt) [130.91 sec, 51.05 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.92 sec, 51.06 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/COPYRIGHT.txt, /home/user/Public/Sites/drupal/drupal-7.7/COPYRIGHT.txt) [130.92 sec, 51.06 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/xmlrpc.php) [130.92 sec, 51.06 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.92 sec, 51.06 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/xmlrpc.php, /home/user/Public/Sites/drupal/drupal-7.7/xmlrpc.php) [130.93 sec, 51.06 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/MAINTAINERS.txt) [130.93 sec, 51.06 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.93 sec, 51.06 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/MAINTAINERS.txt, /home/user/Public/Sites/drupal/drupal-7.7/MAINTAINERS.txt) [130.93 sec, 51.06 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/UPGRADE.txt) [130.93 sec, 51.06 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.93 sec, 51.06 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/UPGRADE.txt, /home/user/Public/Sites/drupal/drupal-7.7/UPGRADE.txt) [130.94 sec, 51.07 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/robots.txt) [130.94 sec, 51.07 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.94 sec, 51.07 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/robots.txt, /home/user/Public/Sites/drupal/drupal-7.7/robots.txt) [130.95 sec, 51.07 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/install.php) [130.95 sec, 51.07 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.96 sec, 51.07 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/install.php, /home/user/Public/Sites/drupal/drupal-7.7/install.php) [130.96 sec, 51.07 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/INSTALL.sqlite.txt) [130.96 sec, 51.07 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.96 sec, 51.07 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/INSTALL.sqlite.txt, /home/user/Public/Sites/drupal/drupal-7.7/INSTALL.sqlite.txt) [130.96 sec, 51.08 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/LICENSE.txt) [130.96 sec, 51.08 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.96 sec, 51.08 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/LICENSE.txt, /home/user/Public/Sites/drupal/drupal-7.7/LICENSE.txt) [130.97 sec, 51.08 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/update.php) [130.97 sec, 51.08 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.97 sec, 51.08 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/update.php, /home/user/Public/Sites/drupal/drupal-7.7/update.php) [130.97 sec, 51.08 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/.gitignore) [130.98 sec, 51.08 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.98 sec, 51.08 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/.gitignore, /home/user/Public/Sites/drupal/drupal-7.7/.gitignore) [130.98 sec, 51.08 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/INSTALL.mysql.txt) [130.98 sec, 51.09 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.98 sec, 51.09 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/INSTALL.mysql.txt, /home/user/Public/Sites/drupal/drupal-7.7/INSTALL.mysql.txt) [130.98 sec, 51.09 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/authorize.php) [130.98 sec, 51.09 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.99 sec, 51.09 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/authorize.php, /home/user/Public/Sites/drupal/drupal-7.7/authorize.php) [130.99 sec, 51.09 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/index.php) [130.99 sec, 51.09 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal/drupal-7.7) [130.99 sec, 51.09 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/index.php, /home/user/Public/Sites/drupal/drupal-7.7/index.php) [131 sec, 51.09 MB] [debug] Verifying signature for svn version control engine. [131 sec, 51.09 MB] [debug] Executing: svn info '/home/user/Public/Sites/drupal/drupal-7.7' sh: svn: not found Verifying signature for bzr version control engine. [131.02 sec, 51.09 MB] [debug] Executing: bzr root '/home/user/Public/Sites/drupal/drupal-7.7' sh: bzr: not found Executing: mkdir '/home/user/Public/Sites/drush-backups/dev_example2' Executing: mkdir '/home/user/Public/Sites/drush-backups/dev_example2/20110731132306' Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7) [131.11 sec, 51.1 MB] [debug] Calling is_writable(/home/user/Public/Sites/drush-backups/dev_example2/20110731132306) [131.12 sec, 51.1 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7, /home/user/Public/Sites/drush-backups/dev_example2/20110731132306/drupal) [131.12 sec, 51.1 MB] [debug] Executing: wget -P . 'http://ftp.drupal.org/files/projects/drupal-7.7.tar.gz' --2011-07-31 15:25:17-- http://ftp.drupal.org/files/projects/drupal-7.7.tar.gz Resolving ftp.drupal.org... 64.50.236.52 Connecting to ftp.drupal.org|64.50.236.52|:80... connected. HTTP request sent, awaiting response... 200 OK Length: 2754113 (2.6M) [application/x-gzip] Saving to: `./drupal-7.7.tar.gz' 0K .......... .......... .......... .......... .......... 1% 53.5K 49s [...SNIP...] 2650K .......... .......... .......... ......... 100% 185K=27s 2011-07-31 15:25:44 (98.8 KB/s) - `./drupal-7.7.tar.gz' saved [2754113/2754113] Downloading drupal-7.7.tar.gz was successful. [158.8 sec, 51.12 MB] [notice] Calling md5_file(drupal-7.7.tar.gz) [158.8 sec, 51.12 MB] [debug] Md5 checksum of drupal-7.7.tar.gz verified. [158.9 sec, 51.12 MB] [notice] Calling chdir(.) [158.9 sec, 51.12 MB] [debug] Executing: tar -C '/home/user/Public/Sites/drupal' -xzf 'drupal-7.7.tar.gz' Calling chdir(/home/user/Public/Sites/drupal) [160.54 sec, 51.11 MB] [debug] Calling chdir(.) [160.54 sec, 51.11 MB] [debug] Executing: tar -tf 'drupal-7.7.tar.gz' [...SNIP...] drupal-7.7/CHANGELOG.txt drupal-7.7/web.config drupal-7.7/cron.php Calling chdir(/home/user/Public/Sites/drupal) [85.38 sec, 24.38 MB] [debug] Calling unlink(drupal-7.7.tar.gz) [85.38 sec, 24.45 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/INSTALL.pgsql.txt) [85.4 sec, 24.4 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.4 sec, 24.4 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/INSTALL.pgsql.txt, /home/user/Public/Sites/drupal/INSTALL.pgsql.txt) [85.4 sec, 24.4 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/CHANGELOG.txt) [85.41 sec, 24.4 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.41 sec, 24.4 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/CHANGELOG.txt, /home/user/Public/Sites/drupal/CHANGELOG.txt) [85.41 sec, 24.4 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/cron.php) [85.41 sec, 24.4 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.42 sec, 24.4 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/cron.php, /home/user/Public/Sites/drupal/cron.php) [85.42 sec, 24.4 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/INSTALL.txt) [85.42 sec, 24.41 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.42 sec, 24.41 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/INSTALL.txt, /home/user/Public/Sites/drupal/INSTALL.txt) [85.42 sec, 24.41 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/.htaccess) [85.42 sec, 24.41 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.43 sec, 24.41 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/.htaccess, /home/user/Public/Sites/drupal/.htaccess) [85.43 sec, 24.41 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/README.txt) [85.45 sec, 24.41 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.45 sec, 24.41 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/README.txt, /home/user/Public/Sites/drupal/README.txt) [85.45 sec, 24.41 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/web.config) [85.6 sec, 24.41 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.6 sec, 24.42 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/web.config, /home/user/Public/Sites/drupal/web.config) [85.6 sec, 24.42 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/COPYRIGHT.txt) [85.6 sec, 24.42 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.6 sec, 24.42 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/COPYRIGHT.txt, /home/user/Public/Sites/drupal/COPYRIGHT.txt) [85.6 sec, 24.42 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/xmlrpc.php) [85.6 sec, 24.42 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.61 sec, 24.42 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/xmlrpc.php, /home/user/Public/Sites/drupal/xmlrpc.php) [85.77 sec, 24.42 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/MAINTAINERS.txt) [85.77 sec, 24.42 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.77 sec, 24.43 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/MAINTAINERS.txt, /home/user/Public/Sites/drupal/MAINTAINERS.txt) [85.77 sec, 24.43 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/UPGRADE.txt) [85.77 sec, 24.43 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.78 sec, 24.43 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/UPGRADE.txt, /home/user/Public/Sites/drupal/UPGRADE.txt) [85.78 sec, 24.43 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/robots.txt) [85.78 sec, 24.43 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.78 sec, 24.43 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/robots.txt, /home/user/Public/Sites/drupal/robots.txt) [85.79 sec, 24.43 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/install.php) [85.79 sec, 24.43 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.79 sec, 24.43 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/install.php, /home/user/Public/Sites/drupal/install.php) [85.79 sec, 24.44 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/INSTALL.sqlite.txt) [85.8 sec, 24.44 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.8 sec, 24.44 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/INSTALL.sqlite.txt, /home/user/Public/Sites/drupal/INSTALL.sqlite.txt) [85.8 sec, 24.44 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/LICENSE.txt) [85.8 sec, 24.44 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.8 sec, 24.44 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/LICENSE.txt, /home/user/Public/Sites/drupal/LICENSE.txt) [85.81 sec, 24.44 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/update.php) [85.81 sec, 24.44 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.82 sec, 24.44 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/update.php, /home/user/Public/Sites/drupal/update.php) [85.82 sec, 24.45 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/.gitignore) [85.82 sec, 24.45 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.82 sec, 24.45 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/.gitignore, /home/user/Public/Sites/drupal/.gitignore) [85.82 sec, 24.45 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/INSTALL.mysql.txt) [85.83 sec, 24.45 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.83 sec, 24.45 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/INSTALL.mysql.txt, /home/user/Public/Sites/drupal/INSTALL.mysql.txt) [85.83 sec, 24.45 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/authorize.php) [85.84 sec, 24.45 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.84 sec, 24.45 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/authorize.php, /home/user/Public/Sites/drupal/authorize.php) [85.84 sec, 24.45 MB] [debug] Calling is_readable(/home/user/Public/Sites/drupal/drupal-7.7/index.php) [85.84 sec, 24.46 MB] [debug] Calling is_writable(/home/user/Public/Sites/drupal) [85.84 sec, 24.46 MB] [debug] Calling rename(/home/user/Public/Sites/drupal/drupal-7.7/index.php, /home/user/Public/Sites/drupal/index.php) [85.84 sec, 24.46 MB] [debug] Project drupal was updated successfully. Installed version is now 7.7. Backups were saved into the directory /home/user/Public/Sites/drush-backups/dev_alighterstate/20110731140130/drupal. [86.26 sec, 24.44 MB] [ok] Undefined index: drush_batch_process_finished in _drush_backend_batch_process() (line 61 of /home/user/Public/Sites/Scripts/drush/commands/core/drupal/batch.inc). [notice] [86.3 sec, 23.42 MB] Undefined index: drush_batch_process_finished in _drush_backend_batch_process() (line 61 of /home/user/Public/Sites/Scripts/drush/commands/core/drupal/batch.inc). [notice] [86.3 sec, 23.42 MB] Running: /usr/bin/php /home/user/Public/Sites/Scripts/drush/drush.php --php='/usr/bin/php' --root='/home/user/Public/Sites/drupal' [command] --uri='http://dev.example2.com' pm-updatecode-postupdate --backend 2>&1 [86.31 sec, 23.43 MB]