{"action":"created","issue":{"url":"https://api.github.com/repos/martinisoft/chef-rvm/issues/171","labels_url":"https://api.github.com/repos/martinisoft/chef-rvm/issues/171/labels{/name}","comments_url":"https://api.github.com/repos/martinisoft/chef-rvm/issues/171/comments","events_url":"https://api.github.com/repos/martinisoft/chef-rvm/issues/171/events","html_url":"https://github.com/martinisoft/chef-rvm/issues/171","id":11194779,"number":171,"title":"Slow execution of rvm recipes","user":{"login":"mgalkiewicz","id":486247,"avatar_url":"https://avatars.githubusercontent.com/u/486247?v=3","gravatar_id":"","url":"https://api.github.com/users/mgalkiewicz","html_url":"https://github.com/mgalkiewicz","followers_url":"https://api.github.com/users/mgalkiewicz/followers","following_url":"https://api.github.com/users/mgalkiewicz/following{/other_user}","gists_url":"https://api.github.com/users/mgalkiewicz/gists{/gist_id}","starred_url":"https://api.github.com/users/mgalkiewicz/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/mgalkiewicz/subscriptions","organizations_url":"https://api.github.com/users/mgalkiewicz/orgs","repos_url":"https://api.github.com/users/mgalkiewicz/repos","events_url":"https://api.github.com/users/mgalkiewicz/events{/privacy}","received_events_url":"https://api.github.com/users/mgalkiewicz/received_events","type":"User","site_admin":false},"labels":[],"state":"closed","locked":false,"assignee":null,"milestone":null,"comments":2,"created_at":"2013-02-20T12:15:33Z","updated_at":"2015-03-06T19:32:13Z","closed_at":"2015-03-06T19:32:13Z","body":"I have a problem with rvm cookbook performance. Even if all resources do nothing chef-client still needs around 45 seconds to execute recipes from run list described below. Next problem is very long installation of gem linecache19 (around 20 minutes). The same installation on the same machine but performed by hand lasts around 6 minutes. Do you have any suggestions how to optimize execution?\r\n\r\nRun list:\r\n```ruby\r\n\"recipe[rvm::system_install]\",\r\n\"recipe[instance_base::rvm_rubygems]\",\r\n\"recipe[rvm::system]\"\r\n```\r\ninstance_base::rvm_rubygems:\r\n```ruby\r\nrvm_shell \"gem install linecache19\" do\r\n  ruby_string \"ruby-1.9.2\"\r\n  code %Q{gem install linecache19 --no-rdoc --no-ri -v 0.5.12}\r\n  timeout 3600\r\n  not_if %Q{bash -c 'source /etc/profile.d/rvm.sh && rvm ruby-1.9.2 do gem list -i linecache19 -v 0.5.12'}\r\nend\r\n\r\nnode['rvm']['rubygems'].each do |rubies, rubygems|\r\n  rvm_shell \"lock down rubygems for #{rubies}\" do\r\n    ruby_string rubies\r\n    code \"rvm rubygems #{rubygems}\"\r\n    not_if \"bash -c \\\"source /etc/profile ; rvm use #{rubies} ; gem -v | grep #{rubygems}\\\"\"\r\n    ignore_failure true\r\n  end\r\nend\r\n```\r\n\r\nAttributes:\r\n```ruby\r\n 'rvm' => {'version' => \"1.17.5\",\r\n           'branch' => \"none\",\r\n           'default_ruby' => \"system\",\r\n           'user_default_ruby' => \"system\",\r\n           'rubygems' => {\"ree-1.8.7-2012.02\" => \"1.4.1\",\r\n                          \"1.9.2-p320\" => \"1.5.2\",\r\n                          \"1.9.3-p327\" => \"1.8.24\"},\r\n            'rubies' => [\"ree-1.8.7-2012.02\", \"1.9.2-p320\", \"1.9.3-p327\", \"jruby-1.7.1\"],\r\n            'global_gems' => [{'name'    => 'bundler',\r\n                               'version' => '1.0.10'},\r\n                              {'name'    => 'rake',\r\n                               'version' => '0.8.7'}],\r\n            'user_global_gems' => [{'name'    => 'bundler',\r\n                                    'version' => '1.0.10'},\r\n                                   {'name'    => 'rake',\r\n                                    'version' => '0.8.7'}],\r\n            'gems' => {\r\n                        'ruby-1.9.2-p320' => [\r\n                          { 'name'    => 'bundler',\r\n                            'version' => '1.0.10'\r\n                          },  \r\n                          { 'name'    => 'rake',\r\n                            'version' => '0.8.7'\r\n                          }   \r\n                        ],  \r\n                        'ruby-1.9.3-p327' => [\r\n                          { 'name'    => 'bundler',\r\n                            'version' => '1.1.0'\r\n                          },  \r\n                          { 'name'    => 'rake',\r\n                            'version' => '0.9.2.2'\r\n                          }   \r\n                        ],  \r\n                        'jruby-1.7.1' => [\r\n                          { 'name'    => 'bundler',\r\n                            'version' => '1.1.0'\r\n                          },  \r\n                          { 'name'    => 'rake',\r\n                            'version' => '0.9.2.2'\r\n                          }\r\n                        ],\r\n                        'ree-1.8.7-2012.02' => [\r\n                          { 'name'    => 'bundler',\r\n                            'version' => '1.0.10'\r\n                          },\r\n                          { 'name'    => 'rake',\r\n                            'version' => '0.8.7'\r\n                          }\r\n                        ]\r\n                      },\r\n            'rvmrc' => {:rvm_project_rvmrc => 0, :rvm_make_flags => \"\\\"-j8\\\"\"}\r\n          }\r\n```\r\n\r\nlogs:\r\n```\r\n[2013-02-20T11:48:55+01:00] INFO: Processing gem_package[rvm] action nothing (rvm::default line 21)\r\n[2013-02-20T11:48:55+01:00] DEBUG: gem_package[rvm] using gem from running ruby environment\r\n[2013-02-20T11:48:55+01:00] DEBUG: gem_package[rvm] found installed gem rvm version 1.11.3.6 matching rvm (>= 0)\r\n[2013-02-20T11:48:55+01:00] DEBUG: Doing nothing for gem_package[rvm]\r\n[2013-02-20T11:48:55+01:00] INFO: Processing package[sed] action install (rvm::system_install line 51)\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[sed] checking package status for sed\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[sed] current version is 4.2.1-10\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[sed] candidate version is 4.2.1-10\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[sed] is already installed - nothing to do\r\n[2013-02-20T11:48:55+01:00] INFO: Processing package[grep] action install (rvm::system_install line 51)\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[grep] checking package status for grep\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[grep] current version is 2.12-2\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[grep] candidate version is 2.12-2\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[grep] is already installed - nothing to do\r\n[2013-02-20T11:48:55+01:00] INFO: Processing package[tar] action install (rvm::system_install line 51)\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[tar] checking package status for tar\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[tar] current version is 1.26+dfsg-0.1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[tar] candidate version is 1.26+dfsg-0.1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[tar] is already installed - nothing to do\r\n[2013-02-20T11:48:55+01:00] INFO: Processing package[gzip] action install (rvm::system_install line 51)\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[gzip] checking package status for gzip\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[gzip] current version is 1.5-1.1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[gzip] candidate version is 1.5-1.1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[gzip] is already installed - nothing to do\r\n[2013-02-20T11:48:55+01:00] INFO: Processing package[bzip2] action install (rvm::system_install line 51)\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[bzip2] checking package status for bzip2\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[bzip2] current version is 1.0.6-4\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[bzip2] candidate version is 1.0.6-4\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[bzip2] is already installed - nothing to do\r\n[2013-02-20T11:48:55+01:00] INFO: Processing package[bash] action install (rvm::system_install line 51)\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[bash] checking package status for bash\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[bash] current version is 4.2+dfsg-0.1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[bash] candidate version is 4.2+dfsg-0.1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[bash] is already installed - nothing to do\r\n[2013-02-20T11:48:55+01:00] INFO: Processing package[curl] action install (rvm::system_install line 51)\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[curl] checking package status for curl\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[curl] current version is 7.26.0-1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[curl] candidate version is 7.26.0-1+wheezy1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[curl] is already installed - nothing to do\r\n[2013-02-20T11:48:55+01:00] INFO: Processing package[git-core] action install (rvm::system_install line 51)\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[git-core] checking package status for git-core\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[git-core] current version is 1:1.7.10.4-1+wheezy1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[git-core] candidate version is 1:1.7.10.4-1+wheezy1\r\n[2013-02-20T11:48:55+01:00] DEBUG: package[git-core] is already installed - nothing to do\r\n[2013-02-20T11:48:55+01:00] INFO: Processing template[/etc/rvmrc] action create (rvm::system_install line 140)\r\n[2013-02-20T11:48:55+01:00] DEBUG: Not fetching cookbooks/rvm/templates/default/rvmrc.erb, as the cache is up to date.\r\n[2013-02-20T11:48:55+01:00] DEBUG: current checksum: b12d9954f6671d0e95be67a8dc6b7504; manifest checksum: b12d9954f6671d0e95be67a8dc6b7504)\r\n[2013-02-20T11:48:55+01:00] DEBUG: Current content's checksum:  a4d33aaa9e8a545345067f592a8141486df09602ab9b2147477d5aa0df9d5193\r\n[2013-02-20T11:48:55+01:00] DEBUG: Rendered content's checksum: a4d33aaa9e8a545345067f592a8141486df09602ab9b2147477d5aa0df9d5193\r\n[2013-02-20T11:48:55+01:00] DEBUG: template[/etc/rvmrc] content has not changed.\r\n[2013-02-20T11:48:55+01:00] INFO: Processing execute[install system-wide RVM] action run (rvm::system_install line 76)\r\n[2013-02-20T11:48:55+01:00] DEBUG: Skipping execute[install system-wide RVM] due to not_if command `bash -c \"source /etc/profile.d/rvm.sh && type rvm | cat | head -1 | grep -q '^rvm is a function$'\"`\r\n[2013-02-20T11:48:55+01:00] INFO: Processing execute[upgrade system-wide RVM to none] action run (rvm::system_install line 110)\r\n[2013-02-20T11:48:55+01:00] DEBUG: Skipping execute[upgrade system-wide RVM to none] due to not_if ruby block\r\n[2013-02-20T11:48:55+01:00] INFO: Processing rvm_shell[gem install linecache19] action run (instance_base::rvm_rubygems line 1)\r\n[2013-02-20T11:48:56+01:00] DEBUG: Skipping rvm_shell[gem install linecache19] due to not_if command `bash -c 'source /etc/profile.d/rvm.sh && rvm ruby-1.9.2 do gem list -i linecache19 -v 0.5.12'`\r\n[2013-02-20T11:48:56+01:00] INFO: Processing rvm_shell[lock down rubygems for ree-1.8.7-2012.02] action run (instance_base::rvm_rubygems line 10)\r\n[2013-02-20T11:48:56+01:00] DEBUG: Skipping rvm_shell[lock down rubygems for ree-1.8.7-2012.02] due to not_if command `bash -c \"source /etc/profile ; rvm use ree-1.8.7-2012.02 ; gem -v | grep 1.4.1\"`\r\n[2013-02-20T11:48:56+01:00] INFO: Processing rvm_shell[lock down rubygems for 1.9.2-p320] action run (instance_base::rvm_rubygems line 10)\r\n[2013-02-20T11:48:57+01:00] DEBUG: Skipping rvm_shell[lock down rubygems for 1.9.2-p320] due to not_if command `bash -c \"source /etc/profile ; rvm use 1.9.2-p320 ; gem -v | grep 1.5.2\"`\r\n[2013-02-20T11:48:57+01:00] INFO: Processing rvm_shell[lock down rubygems for 1.9.3-p327] action run (instance_base::rvm_rubygems line 10)\r\n[2013-02-20T11:48:57+01:00] DEBUG: Skipping rvm_shell[lock down rubygems for 1.9.3-p327] due to not_if command `bash -c \"source /etc/profile ; rvm use 1.9.3-p327 ; gem -v | grep 1.8.24\"`\r\n[2013-02-20T11:48:57+01:00] INFO: Processing rvm_ruby[ree-1.8.7-2012.02] action install (rvm::system line 170)\r\n[2013-02-20T11:48:57+01:00] DEBUG: Fetching canonical RVM string for: ree-1.8.7-2012.02 (system)\r\n[2013-02-20T11:48:57+01:00] DEBUG: Canonical RVM string is: ree-1.8.7-2012.02 => ree-1.8.7-2012.02 (system)\r\n[2013-02-20T11:48:57+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:48:57+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:57+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:57+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:57+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:57+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: rvm_ruby[ree-1.8.7-2012.02] is already installed, so skipping\r\n[2013-02-20T11:48:58+01:00] INFO: Processing rvm_ruby[1.9.2-p320] action install (rvm::system line 170)\r\n[2013-02-20T11:48:58+01:00] DEBUG: Fetching canonical RVM string for: 1.9.2-p320 (system)\r\n[2013-02-20T11:48:58+01:00] DEBUG: Canonical RVM string is: 1.9.2-p320 => ruby-1.9.2-p320 (system)\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: rvm_ruby[ruby-1.9.2-p320] is already installed, so skipping\r\n[2013-02-20T11:48:58+01:00] INFO: Processing rvm_ruby[1.9.3-p327] action install (rvm::system line 170)\r\n[2013-02-20T11:48:58+01:00] DEBUG: Fetching canonical RVM string for: 1.9.3-p327 (system)\r\n[2013-02-20T11:48:58+01:00] DEBUG: Canonical RVM string is: 1.9.3-p327 => ruby-1.9.3-p327 (system)\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:58+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:59+01:00] DEBUG: rvm_ruby[ruby-1.9.3-p327] is already installed, so skipping\r\n[2013-02-20T11:48:59+01:00] INFO: Processing rvm_ruby[jruby-1.7.1] action install (rvm::system line 170)\r\n[2013-02-20T11:48:59+01:00] DEBUG: Fetching canonical RVM string for: jruby-1.7.1 (system)\r\n[2013-02-20T11:48:59+01:00] DEBUG: Canonical RVM string is: jruby-1.7.1 => jruby-1.7.1 (system)\r\n[2013-02-20T11:48:59+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:48:59+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:59+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:59+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:59+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:48:59+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: rvm_ruby[jruby-1.7.1] is already installed, so skipping\r\n[2013-02-20T11:49:00+01:00] INFO: Processing rvm_default_ruby[system] action create (rvm::system line 177)\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'default' 'string'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: Chef::Provider::RvmDefaultRuby: `system' is already default, so skipping\r\n[2013-02-20T11:49:00+01:00] INFO: Processing rvm_global_gem[bundler] action install (rvm::system line 183)\r\n[2013-02-20T11:49:00+01:00] INFO: Processing execute[Add bundler to /usr/local/rvm/gemsets/global.gems] action run (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 79)\r\n[2013-02-20T11:49:00+01:00] DEBUG: Skipping execute[Add bundler to /usr/local/rvm/gemsets/global.gems] due to not_if command `grep -q \"^bundler\" \"/usr/local/rvm/gemsets/global.gems\"`\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:00+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:01+01:00] WARN: Cloning resource attributes for rvm_gem[bundler] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:01+01:00] WARN: Previous rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/libraries/chef_rvm_recipe_helpers.rb:198:in `block (2 levels) in install_rubies'\r\n[2013-02-20T11:49:01+01:00] WARN: Current  rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:01+01:00] INFO: Processing rvm_gem[bundler] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:01+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:01+01:00] DEBUG: RVM version = 1.17.5 (system)\r\n[2013-02-20T11:49:04+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:04+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:04+01:00] WARN: Cloning resource attributes for rvm_gem[bundler] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:04+01:00] WARN: Previous rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:04+01:00] WARN: Current  rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:04+01:00] INFO: Processing rvm_gem[bundler] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:04+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:04+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:04+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:04+01:00] WARN: Cloning resource attributes for rvm_gem[bundler] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:04+01:00] WARN: Previous rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:04+01:00] WARN: Current  rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:04+01:00] INFO: Processing rvm_gem[bundler] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:04+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:05+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:05+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:05+01:00] WARN: Cloning resource attributes for rvm_gem[bundler] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:05+01:00] WARN: Previous rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:05+01:00] WARN: Current  rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:05+01:00] INFO: Processing rvm_gem[bundler] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:05+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:06+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:06+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:06+01:00] INFO: Processing execute[Add bundler to /usr/local/rvm/gemsets/global.gems] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 79)\r\n[2013-02-20T11:49:06+01:00] DEBUG: Skipping execute[Add bundler to /usr/local/rvm/gemsets/global.gems] due to not_if command `grep -q \"^bundler\" \"/usr/local/rvm/gemsets/global.gems\"`\r\n[2013-02-20T11:49:06+01:00] INFO: Processing rvm_gem[bundler] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:06+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:08+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:08+01:00] DEBUG: Doing nothing for rvm_gem[bundler]\r\n[2013-02-20T11:49:08+01:00] INFO: Processing rvm_gem[bundler] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:08+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:09+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:09+01:00] DEBUG: Doing nothing for rvm_gem[bundler]\r\n[2013-02-20T11:49:09+01:00] INFO: Processing rvm_gem[bundler] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:09+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:09+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:09+01:00] DEBUG: Doing nothing for rvm_gem[bundler]\r\n[2013-02-20T11:49:09+01:00] INFO: Processing rvm_gem[bundler] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:09+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:10+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:10+01:00] DEBUG: Doing nothing for rvm_gem[bundler]\r\n[2013-02-20T11:49:10+01:00] INFO: Processing rvm_global_gem[bundler] action install (rvm::system line 183)\r\n[2013-02-20T11:49:10+01:00] INFO: Processing execute[Add bundler -v1.0.10 to /usr/local/rvm/gemsets/global.gems] action run (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 79)\r\n[2013-02-20T11:49:10+01:00] DEBUG: Skipping execute[Add bundler -v1.0.10 to /usr/local/rvm/gemsets/global.gems] due to not_if command `grep -q \"^bundler -v1.0.10\" \"/usr/local/rvm/gemsets/global.gems\"`\r\n[2013-02-20T11:49:10+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:49:10+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:10+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:10+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:10+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:10+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:11+01:00] WARN: Cloning resource attributes for rvm_gem[bundler] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:11+01:00] WARN: Previous rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:11+01:00] WARN: Current  rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:11+01:00] INFO: Processing rvm_gem[bundler] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:11+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:13+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:13+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:13+01:00] WARN: Cloning resource attributes for rvm_gem[bundler] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:13+01:00] WARN: Previous rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:13+01:00] WARN: Current  rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:13+01:00] INFO: Processing rvm_gem[bundler] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:13+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:14+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:14+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:14+01:00] WARN: Cloning resource attributes for rvm_gem[bundler] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:14+01:00] WARN: Previous rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:14+01:00] WARN: Current  rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:14+01:00] INFO: Processing rvm_gem[bundler] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:14+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:15+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:15+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:15+01:00] WARN: Cloning resource attributes for rvm_gem[bundler] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:15+01:00] WARN: Previous rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:15+01:00] WARN: Current  rvm_gem[bundler]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:15+01:00] INFO: Processing rvm_gem[bundler] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:15+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:15+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:15+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:15+01:00] INFO: Processing execute[Add bundler -v1.0.10 to /usr/local/rvm/gemsets/global.gems] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 79)\r\n[2013-02-20T11:49:15+01:00] DEBUG: Skipping execute[Add bundler -v1.0.10 to /usr/local/rvm/gemsets/global.gems] due to not_if command `grep -q \"^bundler -v1.0.10\" \"/usr/local/rvm/gemsets/global.gems\"`\r\n[2013-02-20T11:49:15+01:00] INFO: Processing rvm_gem[bundler] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:15+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:18+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:18+01:00] DEBUG: Doing nothing for rvm_gem[bundler]\r\n[2013-02-20T11:49:18+01:00] INFO: Processing rvm_gem[bundler] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:18+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:18+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:18+01:00] DEBUG: Doing nothing for rvm_gem[bundler]\r\n[2013-02-20T11:49:18+01:00] INFO: Processing rvm_gem[bundler] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:18+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:19+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:19+01:00] DEBUG: Doing nothing for rvm_gem[bundler]\r\n[2013-02-20T11:49:19+01:00] INFO: Processing rvm_gem[bundler] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:19+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:20+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:20+01:00] DEBUG: Doing nothing for rvm_gem[bundler]\r\n[2013-02-20T11:49:20+01:00] INFO: Processing rvm_global_gem[rake] action install (rvm::system line 183)\r\n[2013-02-20T11:49:20+01:00] INFO: Processing execute[Add rake -v0.8.7 to /usr/local/rvm/gemsets/global.gems] action run (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 79)\r\n[2013-02-20T11:49:20+01:00] DEBUG: Skipping execute[Add rake -v0.8.7 to /usr/local/rvm/gemsets/global.gems] due to not_if command `grep -q \"^rake -v0.8.7\" \"/usr/local/rvm/gemsets/global.gems\"`\r\n[2013-02-20T11:49:20+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:49:20+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:20+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:20+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:20+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:20+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:20+01:00] WARN: Cloning resource attributes for rvm_gem[rake] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:20+01:00] WARN: Previous rvm_gem[rake]: /var/lib/chef/cache/cookbooks/rvm/libraries/chef_rvm_recipe_helpers.rb:198:in `block (2 levels) in install_rubies'\r\n[2013-02-20T11:49:20+01:00] WARN: Current  rvm_gem[rake]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:20+01:00] INFO: Processing rvm_gem[rake] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:20+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:23+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:23+01:00] DEBUG: rvm_gem[rake] is already installed - nothing to do\r\n[2013-02-20T11:49:23+01:00] WARN: Cloning resource attributes for rvm_gem[rake] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:23+01:00] WARN: Previous rvm_gem[rake]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:23+01:00] WARN: Current  rvm_gem[rake]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:23+01:00] INFO: Processing rvm_gem[rake] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:23+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:23+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:23+01:00] DEBUG: rvm_gem[rake] is already installed - nothing to do\r\n[2013-02-20T11:49:23+01:00] WARN: Cloning resource attributes for rvm_gem[rake] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:23+01:00] WARN: Previous rvm_gem[rake]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:23+01:00] WARN: Current  rvm_gem[rake]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:23+01:00] INFO: Processing rvm_gem[rake] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:23+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:24+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:24+01:00] DEBUG: rvm_gem[rake] is already installed - nothing to do\r\n[2013-02-20T11:49:24+01:00] WARN: Cloning resource attributes for rvm_gem[rake] from prior resource (CHEF-3694)\r\n[2013-02-20T11:49:24+01:00] WARN: Previous rvm_gem[rake]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:24+01:00] WARN: Current  rvm_gem[rake]: /var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb:48:in `gem_package_wrapper'\r\n[2013-02-20T11:49:24+01:00] INFO: Processing rvm_gem[rake] action install (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:24+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:25+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:25+01:00] DEBUG: rvm_gem[rake] is already installed - nothing to do\r\n[2013-02-20T11:49:25+01:00] INFO: Processing execute[Add rake -v0.8.7 to /usr/local/rvm/gemsets/global.gems] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 79)\r\n[2013-02-20T11:49:25+01:00] DEBUG: Skipping execute[Add rake -v0.8.7 to /usr/local/rvm/gemsets/global.gems] due to not_if command `grep -q \"^rake -v0.8.7\" \"/usr/local/rvm/gemsets/global.gems\"`\r\n[2013-02-20T11:49:25+01:00] INFO: Processing rvm_gem[rake] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:25+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:27+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:27+01:00] DEBUG: Doing nothing for rvm_gem[rake]\r\n[2013-02-20T11:49:27+01:00] INFO: Processing rvm_gem[rake] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:27+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:28+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:28+01:00] DEBUG: Doing nothing for rvm_gem[rake]\r\n[2013-02-20T11:49:28+01:00] INFO: Processing rvm_gem[rake] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:28+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:29+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:29+01:00] DEBUG: Doing nothing for rvm_gem[rake]\r\n[2013-02-20T11:49:29+01:00] INFO: Processing rvm_gem[rake] action nothing (/var/lib/chef/cache/cookbooks/rvm/providers/global_gem.rb line 48)\r\n[2013-02-20T11:49:29+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:29+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:29+01:00] DEBUG: Doing nothing for rvm_gem[rake]\r\n[2013-02-20T11:49:29+01:00] INFO: Processing rvm_environment[ruby-1.9.2-p320] action create (rvm::system line 193)\r\n[2013-02-20T11:49:29+01:00] DEBUG: Fetching canonical RVM string for: ruby-1.9.2-p320 (system)\r\n[2013-02-20T11:49:29+01:00] DEBUG: Canonical RVM string is: ruby-1.9.2-p320 => ruby-1.9.2-p320 (system)\r\n[2013-02-20T11:49:29+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:49:29+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:29+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:29+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:29+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:29+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:30+01:00] INFO: Processing rvm_gem[bundler] action install (rvm::system line 198)\r\n[2013-02-20T11:49:30+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:30+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:30+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:30+01:00] INFO: Processing rvm_gem[rake] action install (rvm::system line 198)\r\n[2013-02-20T11:49:30+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:31+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:31+01:00] DEBUG: rvm_gem[rake] is already installed - nothing to do\r\n[2013-02-20T11:49:31+01:00] INFO: Processing rvm_environment[ruby-1.9.3-p327] action create (rvm::system line 193)\r\n[2013-02-20T11:49:31+01:00] DEBUG: Fetching canonical RVM string for: ruby-1.9.3-p327 (system)\r\n[2013-02-20T11:49:31+01:00] DEBUG: Canonical RVM string is: ruby-1.9.3-p327 => ruby-1.9.3-p327 (system)\r\n[2013-02-20T11:49:31+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:49:31+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:31+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:31+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:31+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:31+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:32+01:00] INFO: Processing rvm_gem[bundler] action install (rvm::system line 198)\r\n[2013-02-20T11:49:32+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:32+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.1.0 matching bundler (= 1.1.0)\r\n[2013-02-20T11:49:32+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:32+01:00] INFO: Processing rvm_gem[rake] action install (rvm::system line 198)\r\n[2013-02-20T11:49:32+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:33+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.9.2.2 matching rake (= 0.9.2.2)\r\n[2013-02-20T11:49:33+01:00] DEBUG: rvm_gem[rake] is already installed - nothing to do\r\n[2013-02-20T11:49:33+01:00] INFO: Processing rvm_environment[jruby-1.7.1] action create (rvm::system line 193)\r\n[2013-02-20T11:49:33+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:49:33+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:33+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:33+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:33+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:33+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:34+01:00] INFO: Processing rvm_gem[bundler] action install (rvm::system line 198)\r\n[2013-02-20T11:49:34+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:36+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.1.0 matching bundler (= 1.1.0)\r\n[2013-02-20T11:49:36+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:36+01:00] INFO: Processing rvm_gem[rake] action install (rvm::system line 198)\r\n[2013-02-20T11:49:36+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:39+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.9.2.2 matching rake (= 0.9.2.2)\r\n[2013-02-20T11:49:39+01:00] DEBUG: rvm_gem[rake] is already installed - nothing to do\r\n[2013-02-20T11:49:39+01:00] INFO: Processing rvm_environment[ree-1.8.7-2012.02] action create (rvm::system line 193)\r\n[2013-02-20T11:49:39+01:00] DEBUG: RVM::Shell::ChefWrapper subprocess executing with environment of: [{}].\r\n[2013-02-20T11:49:39+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/var/lib/gems/1.9.1/gems/rvm-1.11.3.6/lib/rvm/shell/shell_wrapper.sh'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:39+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [export rvm_path='/usr/local/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:39+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [source '/usr/local/rvm/scripts/rvm'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:39+01:00] DEBUG: RVM::Shell::ChefWrapper silently executing: [rvm 'use' 'default'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:39+01:00] DEBUG: RVM::Shell::ChefWrapper executing: [rvm 'list' 'strings'; __rvm_show_command_epilog]\r\n[2013-02-20T11:49:39+01:00] INFO: Processing rvm_gem[bundler] action install (rvm::system line 198)\r\n[2013-02-20T11:49:39+01:00] DEBUG: rvm_gem[bundler] using gem from running ruby environment\r\n[2013-02-20T11:49:40+01:00] DEBUG: rvm_gem[bundler] found installed gem bundler version 1.0.10 matching bundler (= 1.0.10)\r\n[2013-02-20T11:49:40+01:00] DEBUG: rvm_gem[bundler] is already installed - nothing to do\r\n[2013-02-20T11:49:40+01:00] INFO: Processing rvm_gem[rake] action install (rvm::system line 198)\r\n[2013-02-20T11:49:40+01:00] DEBUG: rvm_gem[rake] using gem from running ruby environment\r\n[2013-02-20T11:49:40+01:00] DEBUG: rvm_gem[rake] found installed gem rake version 0.8.7 matching rake (= 0.8.7)\r\n[2013-02-20T11:49:40+01:00] DEBUG: rvm_gem[rake] is already installed - nothing to do\r\n[2013-02-20T11:49:40+01:00] INFO: Processing group[rvm] action create (rvm::system line 33)\r\n[2013-02-20T11:49:40+01:00] DEBUG: Skipping group[rvm] due to only_if ruby block\r\n```"},"comment":{"url":"https://api.github.com/repos/martinisoft/chef-rvm/issues/comments/77620710","html_url":"https://github.com/martinisoft/chef-rvm/issues/171#issuecomment-77620710","issue_url":"https://api.github.com/repos/martinisoft/chef-rvm/issues/171","id":77620710,"user":{"login":"cmluciano","id":4790487,"avatar_url":"https://avatars.githubusercontent.com/u/4790487?v=3","gravatar_id":"","url":"https://api.github.com/users/cmluciano","html_url":"https://github.com/cmluciano","followers_url":"https://api.github.com/users/cmluciano/followers","following_url":"https://api.github.com/users/cmluciano/following{/other_user}","gists_url":"https://api.github.com/users/cmluciano/gists{/gist_id}","starred_url":"https://api.github.com/users/cmluciano/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/cmluciano/subscriptions","organizations_url":"https://api.github.com/users/cmluciano/orgs","repos_url":"https://api.github.com/users/cmluciano/repos","events_url":"https://api.github.com/users/cmluciano/events{/privacy}","received_events_url":"https://api.github.com/users/cmluciano/received_events","type":"User","site_admin":false},"created_at":"2015-03-06T19:32:13Z","updated_at":"2015-03-06T19:32:13Z","body":"I think the bulk of this comes from the time that it takes for certain rubies to be compiled and installed. Let me know if this is still an issue for you. We are looking at a major overhaul soon."}}