gistfile1.sh

~/Sites/example$ cap deploy:migrations                                                                                                            ✹master ‹ruby-1.9.2@rails31›
    triggering start callbacks for `deploy:migrations'
  * 11:44:19 == Currently executing `multistage:ensure'
*** Defaulting to `staging'
  * 11:44:19 == Currently executing `staging'
  * 11:44:19 == Currently executing `deploy:migrations'
  * 11:44:19 == Currently executing `deploy:update_code'
    updating the cached checkout on all servers
    executing locally: "git ls-remote git@github.com:sandboxindustries/example.git master"
    command finished in 1119ms
  * executing "if [ -d /home/deploy/staging.example.com/shared/cached-copy ]; then cd /home/deploy/staging.example.com/shared/cached-copy && git fetch  origin && git fetch --tags  origin && git reset  --hard 00c94259b978ca3e673d97254efabee0a9de16c8 && git clean  -d -x -f; else git clone git@github.com:sandboxindustries/example.git /home/deploy/staging.example.com/shared/cached-copy && cd /home/deploy/staging.example.com/shared/cached-copy && git checkout -b deploy 00c94259b978ca3e673d97254efabee0a9de16c8; fi"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
 ** [staging.example.com :: out] remote: Counting objects: 115, done.
 ** [staging.example.com :: out] remote: Compressing objects:   2% (1/34)   
 ** [staging.example.com :: out] remote: Compressing objects:   5% (2/34)   
 ** [staging.example.com :: out] remote: Compressing objects:   8% (3/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  11% (4/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  14% (5/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  17% (6/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  20% (7/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  23% (8/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  26% (9/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  29% (10/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  32% (11/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  35% (12/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  38% (13/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  41% (14/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  44% (15/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  47% (16/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  50% (17/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  52% (18/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  55% (19/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  58% (20/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  61% (21/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  64% (22/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  67% (23/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  70% (24/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  73% (25/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  76% (26/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  79% (27/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  82% (28/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  85% (29/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  88% (30/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  91% (31/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  94% (32/34)   
 ** [staging.example.com :: out] remote: Compressing objects:  97% (33/34)   
 ** [staging.example.com :: out] remote: Compressing objects: 100% (34/34)   
 ** [staging.example.com :: out] remote: Compressing objects: 100% (34/34), done.
 ** [staging.example.com :: out] remote: Total 73 (delta 58), reused 53 (delta 38)
 ** [staging.example.com :: out] Unpacking objects:   1% (1/73)
 ** [staging.example.com :: out] Unpacking objects:   2% (2/73)
 ** [staging.example.com :: out] Unpacking objects:   4% (3/73)
 ** [staging.example.com :: out] Unpacking objects:   5% (4/73)
 ** [staging.example.com :: out] Unpacking objects:   6% (5/73)
 ** [staging.example.com :: out] Unpacking objects:   8% (6/73)
 ** [staging.example.com :: out] Unpacking objects:   9% (7/73)
 ** [staging.example.com :: out] Unpacking objects:  10% (8/73)
 ** [staging.example.com :: out] Unpacking objects:  12% (9/73)
 ** [staging.example.com :: out] Unpacking objects:  13% (10/73)
 ** [staging.example.com :: out] Unpacking objects:  15% (11/73)
 ** [staging.example.com :: out] Unpacking objects:  16% (12/73)
 ** [staging.example.com :: out] Unpacking objects:  17% (13/73)
 ** [staging.example.com :: out] Unpacking objects:  19% (14/73)
 ** [staging.example.com :: out] Unpacking objects:  20% (15/73)
 ** [staging.example.com :: out] Unpacking objects:  21% (16/73)
 ** [staging.example.com :: out] Unpacking objects:  23% (17/73)
 ** [staging.example.com :: out] Unpacking objects:  24% (18/73)
 ** [staging.example.com :: out] Unpacking objects:  26% (19/73)
 ** [staging.example.com :: out] Unpacking objects:  27% (20/73)
 ** [staging.example.com :: out] Unpacking objects:  28% (21/73)
 ** [staging.example.com :: out] Unpacking objects:  30% (22/73)
 ** [staging.example.com :: out] Unpacking objects:  31% (23/73)
 ** [staging.example.com :: out] Unpacking objects:  32% (24/73)
 ** [staging.example.com :: out] Unpacking objects:  34% (25/73)
 ** [staging.example.com :: out] Unpacking objects:  35% (26/73)
 ** [staging.example.com :: out] Unpacking objects:  36% (27/73)
 ** [staging.example.com :: out] Unpacking objects:  38% (28/73)
 ** [staging.example.com :: out] Unpacking objects:  39% (29/73)
 ** [staging.example.com :: out] Unpacking objects:  41% (30/73)
 ** [staging.example.com :: out] Unpacking objects:  42% (31/73)
 ** [staging.example.com :: out] Unpacking objects:  43% (32/73)
 ** [staging.example.com :: out] Unpacking objects:  45% (33/73)
 ** [staging.example.com :: out] Unpacking objects:  46% (34/73)
 ** [staging.example.com :: out] Unpacking objects:  47% (35/73)
 ** [staging.example.com :: out] Unpacking objects:  49% (36/73)
 ** [staging.example.com :: out] Unpacking objects:  50% (37/73)
 ** [staging.example.com :: out] Unpacking objects:  52% (38/73)
 ** [staging.example.com :: out] Unpacking objects:  53% (39/73)
 ** [staging.example.com :: out] Unpacking objects:  54% (40/73)
 ** [staging.example.com :: out] Unpacking objects:  56% (41/73)
 ** [staging.example.com :: out] Unpacking objects:  57% (42/73)
 ** [staging.example.com :: out] Unpacking objects:  58% (43/73)
 ** [staging.example.com :: out] Unpacking objects:  60% (44/73)
 ** [staging.example.com :: out] Unpacking objects:  61% (45/73)
 ** [staging.example.com :: out] Unpacking objects:  63% (46/73)
 ** [staging.example.com :: out] Unpacking objects:  64% (47/73)
 ** [staging.example.com :: out] Unpacking objects:  65% (48/73)
 ** [staging.example.com :: out] Unpacking objects:  67% (49/73)
 ** [staging.example.com :: out] Unpacking objects:  68% (50/73)
 ** [staging.example.com :: out] Unpacking objects:  69% (51/73)
 ** [staging.example.com :: out] Unpacking objects:  71% (52/73)
 ** [staging.example.com :: out] Unpacking objects:  72% (53/73)
 ** [staging.example.com :: out] Unpacking objects:  73% (54/73)
 ** [staging.example.com :: out] Unpacking objects:  75% (55/73)
 ** [staging.example.com :: out] Unpacking objects:  76% (56/73)
 ** [staging.example.com :: out] Unpacking objects:  78% (57/73)
 ** [staging.example.com :: out] Unpacking objects:  79% (58/73)
 ** [staging.example.com :: out] Unpacking objects:  80% (59/73)
 ** [staging.example.com :: out] Unpacking objects:  82% (60/73)
 ** [staging.example.com :: out] Unpacking objects:  83% (61/73)
 ** [staging.example.com :: out] Unpacking objects:  84% (62/73)
 ** [staging.example.com :: out] Unpacking objects:  86% (63/73)
 ** [staging.example.com :: out] Unpacking objects:  87% (64/73)
 ** [staging.example.com :: out] Unpacking objects:  89% (65/73)
 ** [staging.example.com :: out] Unpacking objects:  90% (66/73)
 ** [staging.example.com :: out] Unpacking objects:  91% (67/73)
 ** [staging.example.com :: out] Unpacking objects:  93% (68/73)
 ** [staging.example.com :: out] Unpacking objects:  94% (69/73)
 ** [staging.example.com :: out] Unpacking objects:  95% (70/73)
 ** [staging.example.com :: out] Unpacking objects:  97% (71/73)
 ** [staging.example.com :: out] Unpacking objects:  98% (72/73)
Unpacking objects: 100% (73/73), done.npacking objects: 100% (73/73)   
 ** [staging.example.com :: out] From github.com:sandboxindustries/example
 ** ff01e95..00c9425  master     -> origin/master
 ** [staging.example.com :: out] HEAD is now at 00c9425 misc fixes
    command finished in 8741ms
    copying the cached version to /home/deploy/staging.example.com/releases/20120105174430
  * executing "ruby /home/deploy/staging.example.com/shared/bin/copy.rb /home/deploy/staging.example.com/shared/cached-copy /home/deploy/staging.example.com/releases/20120105174430  && (echo 00c94259b978ca3e673d97254efabee0a9de16c8 > /home/deploy/staging.example.com/releases/20120105174430/REVISION)"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
    command finished in 1359ms
  * 11:44:32 == Currently executing `deploy:finalize_update'
    triggering before callbacks for `deploy:finalize_update'
  * 11:44:32 == Currently executing `deploy:assets:symlink'
  * executing "rm -rf /home/deploy/staging.example.com/releases/20120105174430/public/assets &&\\\n        mkdir -p /home/deploy/staging.example.com/releases/20120105174430/public &&\\\n        mkdir -p /home/deploy/staging.example.com/shared/assets &&\\\n        ln -s /home/deploy/staging.example.com/shared/assets /home/deploy/staging.example.com/releases/20120105174430/public/assets"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
    command finished in 203ms
  * executing "chmod -R g+w /home/deploy/staging.example.com/releases/20120105174430"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
    command finished in 227ms
  * executing "rm -rf /home/deploy/staging.example.com/releases/20120105174430/log /home/deploy/staging.example.com/releases/20120105174430/public/system /home/deploy/staging.example.com/releases/20120105174430/tmp/pids &&\\\n      mkdir -p /home/deploy/staging.example.com/releases/20120105174430/public &&\\\n      mkdir -p /home/deploy/staging.example.com/releases/20120105174430/tmp &&\\\n      ln -s /home/deploy/staging.example.com/shared/log /home/deploy/staging.example.com/releases/20120105174430/log &&\\\n      ln -s /home/deploy/staging.example.com/shared/system /home/deploy/staging.example.com/releases/20120105174430/public/system &&\\\n      ln -s /home/deploy/staging.example.com/shared/pids /home/deploy/staging.example.com/releases/20120105174430/tmp/pids"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
    command finished in 195ms
  * executing "find /home/deploy/staging.example.com/releases/20120105174430/public/images /home/deploy/staging.example.com/releases/20120105174430/public/stylesheets /home/deploy/staging.example.com/releases/20120105174430/public/javascripts -exec touch -t 201201051744.32 {} ';'; true"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
 ** [out :: staging.example.com] find: `/home/deploy/staging.example.com/releases/20120105174430/public/images'
 ** [out :: staging.example.com] : No such file or directory
 ** [out :: staging.example.com] find: `/home/deploy/staging.example.com/releases/20120105174430/public/stylesheets'
 ** [out :: staging.example.com] : No such file or directory
 ** [out :: staging.example.com] find: `/home/deploy/staging.example.com/releases/20120105174430/public/javascripts'
 ** [out :: staging.example.com] : No such file or directory
    command finished in 229ms
    triggering after callbacks for `deploy:finalize_update'
  * 11:44:33 == Currently executing `bundle:install'
  * executing "ls -x /home/deploy/staging.example.com/releases"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
    command finished in 196ms
  * executing "cd /home/deploy/staging.example.com/releases/20120105174430 && bundle install --gemfile /home/deploy/staging.example.com/releases/20120105174430/Gemfile --path /home/deploy/staging.example.com/shared/bundle --deployment --quiet --without development production"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
    command finished in 4518ms
    triggering after callbacks for `deploy:update_code'
  * 11:44:37 == Currently executing `whenever:clear_crontab'
  * executing "cd /home/deploy/staging.example.com/releases/20120105174430 && bundle exec whenever --clear-crontab example"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
 ** [out :: staging.example.com] [write] crontab file
    command finished in 3366ms
  * 11:44:41 == Currently executing `deploy:assets:precompile'
  * executing "cd /home/deploy/staging.example.com/releases/20120105174430 && bundle exec rake RAILS_ENV=staging RAILS_GROUPS=assets assets:precompile"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
 ** [out :: staging.example.com] /usr/local/bin/ruby /home/deploy/staging.example.com/shared/bundle/ruby/1.9.1/bin/rake assets:precompile:nondigest RAILS_ENV=staging RAILS_GROUPS=assets
    command finished in 114565ms
  * 11:46:35 == Currently executing `deploy:migrate'
  * executing "cd /home/deploy/staging.example.com/current; bundle exec rake db:migrate RAILS_ENV=staging"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
    command finished in 38096ms
  * 11:47:13 == Currently executing `deploy:symlink'
  * executing "rm -f /home/deploy/staging.example.com/current && ln -s /home/deploy/staging.example.com/releases/20120105174430 /home/deploy/staging.example.com/current"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
    command finished in 232ms
    triggering after callbacks for `deploy:symlink'
  * 11:47:14 == Currently executing `whenever:update_crontab'
  * executing "cd /home/deploy/staging.example.com/current && bundle exec whenever --update-crontab example --set environment=staging"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
 ** [out :: staging.example.com] [write] crontab file updated
 ** [out :: staging.example.com] 
    command finished in 3214ms
  * 11:47:17 == Currently executing `deploy:restart'
    triggering before callbacks for `deploy:restart'
  * 11:47:17 == Currently executing `delayed_job:stop'
  * executing "cd /home/deploy/staging.example.com/current;RAILS_ENV=staging script/delayed_job stop"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
 ** [out :: staging.example.com] delayed_job: trying to stop process with pid 20923...
 ** [out :: staging.example.com] delayed_job: process with pid 20923 successfully stopped.
    command finished in 32687ms
  * executing "touch /home/deploy/staging.example.com/current/tmp/restart.txt"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
    command finished in 182ms
    triggering after callbacks for `deploy:restart'
  * 11:47:50 == Currently executing `delayed_job:start'
  * executing "cd /home/deploy/staging.example.com/current;RAILS_ENV=staging script/delayed_job start"
    servers: ["staging.example.com"]
    [staging.example.com] executing command
 ** [out :: staging.example.com] delayed_job: process with pid 12302 started.
 ** [out :: staging.example.com] 
    command finished in 29084ms