SuperMicro ITX for @VictorLowther

ipmi-configure-error

RuntimeError: Chef jig run for system: d00-25-90-e7-43-72.b.com: ipmi-configure failed
Out: [2014-07-10T22:00:28-04:00] INFO: Forking chef instance to converge...
[2014-07-10T22:00:28-04:00] INFO: *** Chef 11.10.0 ***
[2014-07-10T22:00:28-04:00] INFO: Chef-client pid: 3873
[2014-07-10T22:00:30-04:00] INFO: Run List is [role[crowbar-d00-25-90-e7-43-72_b_com]]
[2014-07-10T22:00:30-04:00] INFO: Run List expands to [barclamp, ohai, utils, ipmi::configure]
[2014-07-10T22:00:30-04:00] INFO: Starting Chef Run for d00-25-90-e7-43-72.b.com
[2014-07-10T22:00:30-04:00] INFO: Running start handlers
[2014-07-10T22:00:30-04:00] INFO: Start handlers complete.
[2014-07-10T22:00:30-04:00] INFO: HTTP Request Returned 404 Object Not Found: 
[2014-07-10T22:00:30-04:00] INFO: Loading cookbooks [barclamp, ipmi, ohai, utils]
[2014-07-10T22:00:30-04:00] INFO: Removing cookbooks/network/chefignore from the cache; its cookbook is no longer needed on this client.
[2014-07-10T22:00:30-04:00] INFO: Removing cookbooks/network/recipes/default.rb from the cache; its cookbook is no longer needed on this client.
[2014-07-10T22:00:30-04:00] INFO: Removing cookbooks/network/recipes/switch_config.rb from the cache; its cookbook is no longer needed on this client.
[2014-07-10T22:00:30-04:00] INFO: Removing cookbooks/network/templates/default/redhat-cfg.erb from the cache; its cookbook is no longer needed on this client.
[2014-07-10T22:00:30-04:00] INFO: Removing cookbooks/network/metadata.json from the cache; its cookbook is no longer needed on this client.
[2014-07-10T22:00:30-04:00] INFO: Storing updated cookbooks/ipmi/recipes/ipmitool.rb in the cache.
[2014-07-10T22:00:30-04:00] INFO: Storing updated cookbooks/ipmi/recipes/configure.rb in the cache.
[2014-07-10T22:00:30-04:00] INFO: Storing updated cookbooks/ipmi/recipes/discover.rb in the cache.
[2014-07-10T22:00:30-04:00] INFO: Storing updated cookbooks/ipmi/recipes/master.rb in the cache.
[2014-07-10T22:00:31-04:00] INFO: Storing updated cookbooks/ipmi/libraries/bmc.rb in the cache.
[2014-07-10T22:00:31-04:00] INFO: Storing updated cookbooks/ipmi/attributes/default.rb in the cache.
[2014-07-10T22:00:31-04:00] INFO: Storing updated cookbooks/ipmi/metadata.json in the cache.
[2014-07-10T22:00:31-04:00] INFO: ohai plugins will be at: /etc/chef/ohai_plugins
[2014-07-10T22:00:31-04:00] INFO: Processing directory[/etc/chef/ohai_plugins] action create (ohai::default line 23)
[2014-07-10T22:00:31-04:00] INFO: Processing remote_directory[/etc/chef/ohai_plugins] action create (ohai::default line 32)
[2014-07-10T22:00:31-04:00] INFO: Processing cookbook_file[/etc/chef/ohai_plugins/crowbar.rb] action create (dynamically defined)
[2014-07-10T22:00:31-04:00] INFO: Processing cookbook_file[/etc/chef/ohai_plugins/README] action create (dynamically defined)
[2014-07-10T22:00:33-04:00] INFO: Processing directory[/etc/chef/ohai_plugins] action nothing (ohai::default line 23)
[2014-07-10T22:00:33-04:00] INFO: Processing remote_directory[/etc/chef/ohai_plugins] action nothing (ohai::default line 32)
[2014-07-10T22:00:33-04:00] INFO: Processing log[running on OS:[centos] on X7SPA-HF hardware ] action write (utils::default line 28)
[2014-07-10T22:00:33-04:00] INFO: running on OS:[centos] on X7SPA-HF hardware 
[2014-07-10T22:00:33-04:00] INFO: Processing ruby_block[Signal success in setting user creds] action nothing (ipmi::configure line 40)
[2014-07-10T22:00:33-04:00] INFO: Processing bash[Set IPMI credentials and enable LAN channel access] action run (ipmi::configure line 50)

================================================================================
Error executing action `run` on resource 'bash[Set IPMI credentials and enable LAN channel access]'
================================================================================


Mixlib::ShellOut::ShellCommandFailed
------------------------------------
Expected process to exit with [0], but received '1'
---- Begin output of "bash"  "/tmp/chef-script20140710-3873-4xdc18" ----
STDOUT: 
STDERR: + ipmitool user set name 2 root
Set User Name command failed (user 2, name root): Command not supported in present state
---- End output of "bash"  "/tmp/chef-script20140710-3873-4xdc18" ----
Ran "bash"  "/tmp/chef-script20140710-3873-4xdc18" returned 1


Resource Declaration:
---------------------
# In /var/chef/cache/cookbooks/ipmi/recipes/configure.rb

 50: bash "Set IPMI credentials and enable LAN channel access" do
 51:   code <<EOC
 52: set -e -x
 53: ipmitool user set name #{bmc_userid} #{bmc_user}
 54: ipmitool user set password #{bmc_userid} #{bmc_password}
 55: ipmitool user priv #{bmc_userid} 4 #{chan}
 56: ipmitool channel setaccess #{chan} #{bmc_userid} callin=on link=on ipmi=on privilege=4
 57: ipmitool user enable #{bmc_userid}
 58: ipmitool lan set #{chan} access on
 59: EOC
 60:   notifies :create, "ruby_block[Signal success in setting user creds]"
 61:   not_if {
 62:     salt = (node["crowbar_wall"]["status"]["ipmi"]["user_salt"] || 0 rescue 0)
 63:     hash = Digest::SHA1.new.base64digest("#{salt}:#{bmc_user}:#{bmc_password}")
 64:     hash == (node["crowbar_wall"]["status"]["ipmi"]["user_hash"] || "" rescue "")
 65:   }
 66: end
 67: 



Compiled Resource:
------------------
# Declared in /var/chef/cache/cookbooks/ipmi/recipes/configure.rb:50:in `from_file'

bash("Set IPMI credentials and enable LAN channel access") do
  action "run"
  retries 0
  retry_delay 2
  command "\"bash\"  \"/tmp/chef-script20140710-3873-4xdc18\""
  backup 5
  returns 0
  code "set -e -x\nipmitool user set name 2 root\nipmitool user set password 2 cr0wBar!\nipmitool user priv 2 4 1\nipmitool channel setaccess 1 2 callin=on link=on ipmi=on privilege=4\nipmitool user enable 2\nipmitool lan set 1 access on\n"
  interpreter "bash"
  cookbook_name "ipmi"
  recipe_name "configure"
  not_if { #code block }
end



[2014-07-10T22:00:33-04:00] INFO: Running queued delayed notifications before re-raising exception
[2014-07-10T22:00:33-04:00] ERROR: Running exception handlers
[2014-07-10T22:00:33-04:00] ERROR: Exception handlers complete
[2014-07-10T22:00:33-04:00] FATAL: Stacktrace dumped to /var/chef/cache/chef-stacktrace.out
[2014-07-10T22:00:33-04:00] ERROR: bash[Set IPMI credentials and enable LAN channel access] (ipmi::configure line 50) had an error: Mixlib::ShellOut::ShellCommandFailed: Expected process to exit with [0], but received '1'
---- Begin output of "bash"  "/tmp/chef-script20140710-3873-4xdc18" ----
STDOUT: 
STDERR: + ipmitool user set name 2 root
Set User Name command failed (user 2, name root): Command not supported in present state
---- End output of "bash"  "/tmp/chef-script20140710-3873-4xdc18" ----
Ran "bash"  "/tmp/chef-script20140710-3873-4xdc18" returned 1
[2014-07-10T22:00:33-04:00] FATAL: Chef::Exceptions::ChildConvergeError: Chef run process exited unsuccessfully (exit code 1)

Err:Warning: Permanently added 'fc57:54bc:e3ad:1:63c5:5463:c925:8a1b' (RSA) to the list of known hosts.

Backtrace:
/opt/opencrowbar/core/rails/app/models/barclamp_chef/jig.rb:70:in `run'
/opt/opencrowbar/core/rails/app/models/jig.rb:163:in `block in run_job'
/opt/opencrowbar/core/rails/app/models/jig.rb:157:in `loop'
/opt/opencrowbar/core/rails/app/models/jig.rb:157:in `run_job'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/performable_method.rb:26:in `perform'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/backend/base.rb:105:in `block in invoke_job'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:60:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:60:in `block in initialize'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:65:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:65:in `execute'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:38:in `run_callbacks'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/backend/base.rb:102:in `invoke_job'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:207:in `block (2 levels) in run'
/usr/share/ruby/timeout.rb:66:in `timeout'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:207:in `block in run'
/usr/share/ruby/benchmark.rb:296:in `realtime'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:206:in `run'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:274:in `block in reserve_and_run_one_job'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:60:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:60:in `block in initialize'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:65:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:65:in `execute'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:38:in `run_callbacks'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:274:in `reserve_and_run_one_job'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:190:in `block in work_off'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:189:in `times'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:189:in `work_off'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:154:in `block (4 levels) in start'
/usr/share/ruby/benchmark.rb:296:in `realtime'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:153:in `block (3 levels) in start'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:60:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:60:in `block in initialize'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:65:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:65:in `execute'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:38:in `run_callbacks'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:152:in `block (2 levels) in start'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:151:in `loop'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:151:in `block in start'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/plugins/clear_locks.rb:7:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/plugins/clear_locks.rb:7:in `block (2 levels) in <class:ClearLocks>'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:78:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:78:in `block (2 levels) in add'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:60:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:60:in `block in initialize'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:78:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:78:in `block in add'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:65:in `call'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:65:in `execute'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/lifecycle.rb:38:in `run_callbacks'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/worker.rb:150:in `start'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/command.rb:104:in `run'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/command.rb:92:in `block in run_process'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons/application.rb:255:in `call'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons/application.rb:255:in `block in start_proc'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons/daemonize.rb:82:in `call'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons/daemonize.rb:82:in `call_as_daemon'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons/application.rb:259:in `start_proc'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons/application.rb:296:in `start'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons/controller.rb:70:in `run'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons.rb:197:in `block in run_proc'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons/cmdline.rb:109:in `call'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons/cmdline.rb:109:in `catch_exceptions'
/var/cache/crowbar/gems/ruby/gems/daemons-1.1.9/lib/daemons.rb:196:in `run_proc'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/command.rb:90:in `run_process'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/command.rb:83:in `block in daemonize'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/command.rb:81:in `times'
/var/cache/crowbar/gems/ruby/gems/delayed_job-4.0.2/lib/delayed/command.rb:81:in `daemonize'
script/delayed_job:5:in `<main>'