/000-notes.md

## 000-notes.md

      
    Raw
  

              000-notes.md
            
          
    fulllog-2.md is from our regular workflow
fulllog-3.md is what we see when we add the boot-to-disk task

  
## 001-issue.md

      
    Raw
  

              001-issue.md
            
          
    Background:
Fresh install in a vagrant Ubuntu 14.04 server (rachd01) attempting to pxe boot and install ubuntu onto vagrant vm pxe01.
This worked with versions we had from April. We are attempting to recreate this with August versions of RackHD.
The node is booted, discovered, matches a sku, and proceeds through the workflow, getting Ubuntu installed. Once Ubuntu installs,
our next task is a Noop task intended to sleep long enough for pxe01 to boot from the local hard disk. In prior versions, this worked
because on-dhcp-proxy would have no ipxe profile to send, so pxe boot would time out and default to local hard drive. In our new version,
on-dhcp-proxy sends monorail and attempts to chainload the next profile, which fails.
on-dhcp-proxy		1.1-1master-20160802191250Z1	RackHD Imaging workflow DHCP-Proxy engine
on-http      		1.1-1master-20160809130557Z1	RackHD HTTP engine service
on-syslog    		1.1-1master-20160802205506Z1	RackHD Syslog service component
on-taskgraph 		1.1-1master-20160803150017Z1	RackHD Imaging workflow taskgraph engine
on-tftp      		1.1-1master-20160802205406Z1	RackHD Imaging workflow tftp engine

Our unadaltered workflow is here: (link), but the important portion is below. The failed pxe boot takes
place during delay-ansible-start.
    "install-ubuntu": {
      "profile": "install-trusty.ipxe",
      "version": "trusty",
      "username": "renasar",
      "password": "renasar",
      "uid": "1010",
      "hostname": "{{ context.rubicon.config_values.hostname }}",
      "domain": "{{ context.rubicon.config_values.domain }}"
    },
    "delay-ansible-start": {
      "delay": 300000
    },
    "ansible-post-install": {
      "playbook": "/opt/rackhd/ansible/site.yml",
      "vars": {
        "metallica_role": "vagrant-trusty"
      }
    }

Our first workaround was to attempt adding a Disk Boot task between install-ubuntu and delay-ansible-start.
(note: diskboot.ipxe will not work in a vagrant environment, we have an error.ipxe that issues a sanboot command that works)
error.ipxe (sanboot --no-describe --drive 0x80)
New graph:
options: {
    "boot-to-disk": {
        "profile": "error.ipxe"
    }
}

...

{
      "label": "install-ubuntu",
      "taskName": "Task.Os.Install.Ubuntu",
      "waitOn": {
        "shell-reboot": "succeeded"
      }
    },
    {
      "label": "boot-to-disk",
      "taskName": "Task.BootProfile",
      "waitOn": {
        "install-ubuntu": "succeeded"
      }
    },
    {
      "label": "delay-ansible-start",
      "taskName": "Task.noop",
      "waitOn": {
        "boot-to-disk": "succeeded"
      }
    },
    {
      "label": "ansible-post-install",
      "taskName": "Task.Base.Ansible.PostInstall",
      "waitOn": {
        "delay-ansible-start": "finished"
      }
    }

With this graph, it proceeds in the same way, but we get the 503 during the boot-to-disk task. We get the same
results no matter which profile we define (bootdisk.ipxe or error.ipxe).
The node does not receive our ipxe file, it fails to boot, and then taskgraph marks the boot-to-disk task as succeeded. Subsequent
tasks will fail because the node is not up.  However, if are reboot the vm, watch console and hit "ESC" during ipxe boot, it will cancel ipxe and boot
into the hard drive, allowing the remaining tasks to succeed.
==> /var/log/rackhd/on-taskgraph.log <==
[debug] [2016-08-23T17:09:56.582Z] [on-taskgraph] [Job.BootProfile] [7c72fe] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.BootProfile
name:    BootProfileJob
options: 
  profile:      error.ipxe
  _taskTimeout: 86400000
taskId:  2515fa69-4293-4646-9067-03907323a8df
target:  57bc81b9cb0345ac7f7c72fe

==> /var/log/rackhd/on-dhcp-proxy.log <==
[debug] [2016-08-23T17:10:04.179Z] [on-dhcp-proxy] [DHCP.parser] [7c72fe] Unhandled DHCP options (97:17,94:3)
 -> /lib/parser.js:331
macaddress: 08:00:27:bc:f8:88
[warning] [2016-08-23T17:10:05.211Z] [on-dhcp-proxy] [DHCP.messageHandler] [Server] Could not get TFTP bootfile name from active task. Sending default bootfile.
 -> /lib/message-handler.js:201
error: 
  message: Request Timed Out (on.task:methods.getBootProfile.57bc81b9cb0345ac7f7c72fe:Object).
  name:    RequestTimedOutError
  context: 

==> /var/log/rackhd/on-http.log <==
[debug] [2016-08-23T17:10:06.366Z] [on-http] [Http.Server] [7c72fe] http: GET 200 17.542 - /api/current/profiles
 -> /lib/services/http-service.js:333
ipAddress: 192.168.37.201

==> /var/log/rackhd/on-syslog.log <==
[info] [2016-08-23T17:10:07.414Z] [on-syslog] [Syslog] [7c72fe] ipxe: Configuring (net0 08:00:27:bc:f8:88).... ok
 -> /lib/app.js:65
facility: 0
priority: 6
[info] [2016-08-23T17:10:07.418Z] [on-syslog] [Syslog] [7c72fe] ipxe: net0: 192.168.37.201/255.255.255.0
 -> /lib/app.js:65
facility: 0
priority: 6
[info] [2016-08-23T17:10:07.419Z] [on-syslog] [Syslog] [7c72fe] ipxe: MonoRail Boilerplate iPXE Completed.
 -> /lib/app.js:65
facility: 0
priority: 6
[info] [2016-08-23T17:10:07.423Z] [on-syslog] [Syslog] [7c72fe] ipxe:
 -> /lib/app.js:65
facility: 0
priority: 6
[info] [2016-08-23T17:10:07.424Z] [on-syslog] [Syslog] [7c72fe] ipxe: Chainloading next profile
 -> /lib/app.js:65
facility: 0
priority: 6


==> /var/log/rackhd/on-taskgraph.log <==
[info] [2016-08-23T17:10:07.466Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Task finished
 -> /lib/task-runner.js:296
instanceId:   2515fa69-4293-4646-9067-03907323a8df
state:        succeeded
taskRunnerId: f1e7440f-8c4e-4e4d-86b8-c6d06ff467f8


## 002-workflow-regular.json
{
  "injectableName": "Graph.Rubicon.Vagrant.Trusty.End2End",
  "friendlyName": "Vagrant Trusty End to End",
  "options": {
    "bootstrap-ubuntu": {
      "overlayfs": "common/rubicon.overlay.cpio.gz"
    },
    "install-ubuntu": {
      "profile": "install-trusty.ipxe",
      "version": "trusty",
      "username": "renasar",
      "password": "renasar",
      "uid": "1010",
      "hostname": "{{ context.rubicon.config_values.hostname }}",
      "domain": "{{ context.rubicon.config_values.domain }}"
    },
    "delay-ansible-start": {
      "delay": 300000
    },
    "ansible-post-install": {
      "playbook": "/opt/rackhd/ansible/site.yml",
      "vars": {
        "metallica_role": "vagrant-trusty"
      }
    }
  },
  "tasks": [
    {
      "label": "bootstrap-ubuntu",
      "taskName": "Task.Linux.Bootstrap.Ubuntu"
    },
    {
      "label": "create-rubicon-catalog",
      "taskName": "Task.Catalog.Rubicon",
      "waitOn": {
        "bootstrap-ubuntu": "succeeded"
      }
    },
    {
      "taskName": "Task.Base.Ansible.Enrich",
      "label": "ansible-gertie-enrich",
      "waitOn": {
        "create-rubicon-catalog": "succeeded"
      }
    },
    {
      "label": "provide-rubicon-catalog-data",
      "taskName": "Task.Catalogs.Provide.Rubicon.CatalogData",
      "waitOn": {
        "ansible-gertie-enrich": "succeeded"
      }
    },
    {
      "label": "shell-reboot",
      "taskName": "Task.ProcShellReboot",
      "waitOn": {
        "provide-rubicon-catalog-data": "succeeded"
      }
    },
    {
      "label": "install-ubuntu",
      "taskName": "Task.Os.Install.Ubuntu",
      "waitOn": {
        "shell-reboot": "succeeded"
      }
    },
    {
      "label": "delay-ansible-start",
      "taskName": "Task.noop",
      "waitOn": {
        "install-ubuntu": "succeeded"
      }
    },
    {
      "label": "ansible-post-install",
      "taskName": "Task.Base.Ansible.PostInstall",
      "waitOn": {
        "delay-ansible-start": "finished"
      }
    }
  ]
}

## 003-workflow-graph-withboot.json
{
  "injectableName": "Graph.Rubicon.Vagrant.Trusty.End2End",
  "friendlyName": "Vagrant Trusty End to End",
  "options": {
    "bootstrap-ubuntu": {
      "overlayfs": "common/rubicon.overlay.cpio.gz"
    },
    "install-ubuntu": {
      "profile": "install-trusty.ipxe",
      "version": "trusty",
      "username": "renasar",
      "password": "renasar",
      "uid": "1010",
      "hostname": "{{ context.rubicon.config_values.hostname }}",
      "domain": "{{ context.rubicon.config_values.domain }}"
    },
    "boot-to-disk": {
      "profile": "error.ipxe"
    },
    "delay-ansible-start": {
      "delay": 300000
    },
    "ansible-post-install": {
      "playbook": "/opt/rackhd/ansible/site.yml",
      "vars": {
        "metallica_role": "vagrant-trusty"
      }
    }
  },
  "tasks": [
    {
      "label": "bootstrap-ubuntu",
      "taskName": "Task.Linux.Bootstrap.Ubuntu"
    },
    {
      "label": "create-rubicon-catalog",
      "taskName": "Task.Catalog.Rubicon",
      "waitOn": {
        "bootstrap-ubuntu": "succeeded"
      }
    },
    {
      "taskName": "Task.Base.Ansible.Enrich",
      "label": "ansible-gertie-enrich",
      "waitOn": {
        "create-rubicon-catalog": "succeeded"
      }
    },
    {
      "label": "provide-rubicon-catalog-data",
      "taskName": "Task.Catalogs.Provide.Rubicon.CatalogData",
      "waitOn": {
        "ansible-gertie-enrich": "succeeded"
      }
    },
    {
      "label": "shell-reboot",
      "taskName": "Task.ProcShellReboot",
      "waitOn": {
        "provide-rubicon-catalog-data": "succeeded"
      }
    },
    {
      "label": "install-ubuntu",
      "taskName": "Task.Os.Install.Ubuntu",
      "waitOn": {
        "shell-reboot": "succeeded"
      }
    },
    {
      "label": "boot-to-disk",
      "taskName": "Task.BootProfile",
      "waitOn": {
        "install-ubuntu": "succeeded"
      }
    },
    {
      "label": "delay-ansible-start",
      "taskName": "Task.noop",
      "waitOn": {
        "boot-to-disk": "succeeded"
      }
    },
    {
      "label": "ansible-post-install",
      "taskName": "Task.Base.Ansible.PostInstall",
      "waitOn": {
        "delay-ansible-start": "finished"
      }
    }
  ]
}

## fulllog-2.log
==> /var/log/rackhd/on-dhcp-proxy.log <==
[info] [2016-08-23T15:18:45.748Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (color => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:18:45.748Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (logColorEnable => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:18:45.766Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (dhcpProxyOutPort => 68).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:18:45.766Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (dhcpProxyEFIOutPort => 4011).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:18:45.770Z] [on-dhcp-proxy] [Server] [Server] proxyDHCP server is listening on 192.168.37.2:4011
 -> /lib/server.js:59

==> /var/log/rackhd/on-http.log <==
ipAddress: 127.0.0.1
[debug] [2016-08-23T15:20:46.880Z] [on-http] [Http.Server] [Server] http: PUT 200 8.187 - /api/1.1/workflows
 -> /lib/services/http-service.js:333
ipAddress: 127.0.0.1
[debug] [2016-08-23T15:20:46.896Z] [on-http] [Http.Server] [Server] http: GET 200 7.400 - /api/1.1/skus
 -> /lib/services/http-service.js:333
ipAddress: 127.0.0.1
[debug] [2016-08-23T15:20:46.912Z] [on-http] [Http.Server] [Server] http: POST 200 13.383 - /api/1.1/skus
 -> /lib/services/http-service.js:333
ipAddress: 127.0.0.1

==> /var/log/rackhd/on-syslog.log <==
[info] [2016-08-23T15:19:47.300Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:19:47.300Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:19:47.448Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (color => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:19:47.448Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (logColorEnable => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:19:47.487Z] [on-syslog] [Syslog] [Server] Syslog service is listening
 -> /lib/app.js:72

==> /var/log/rackhd/on-taskgraph.log <==
[debug] [2016-08-23T15:19:52.767Z] [on-taskgraph] [Job.Poller.Alert.Ipmi.Sel] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.Poller.Alert.Ipmi.Sel
name:    IpmiSelPollerAlertJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
taskId:  29b5ad37-2f5b-4ba2-ad48-bfde8489db4a
target:  none

==> /var/log/rackhd/on-tftp.log <==
[info] [2016-08-23T15:19:46.873Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:19:46.874Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:19:47.125Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (color => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:19:47.125Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (logColorEnable => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:19:47.176Z] [on-tftp] [Tftp.Server] [Server] TFTP Server Listening 192.168.37.2:69
 -> /lib/server.js:58

==> /var/log/rackhd/on-syslog.log <==
[info] [2016-08-23T15:21:54.227Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.227Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.234Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (postgresql => postgres://rackhd:rackhd@localhost:5432/pxe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.234Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (migrate => safe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.238Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.238Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.239Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.239Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.239Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.241Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.241Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.242Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.242Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.243Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.244Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.244Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.245Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.246Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.247Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.247Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.247Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.248Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.249Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.430Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (color => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.436Z] [on-syslog] [Services.Configuration] [Server] Configuration value is undefined, using default (logColorEnable => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.526Z] [on-syslog] [Syslog] [Server] Syslog service is listening
 -> /lib/app.js:72

==> /var/log/rackhd/on-dhcp-proxy.log <==
[info] [2016-08-23T15:21:54.414Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.415Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.415Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (postgresql => postgres://rackhd:rackhd@localhost:5432/pxe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.415Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (migrate => safe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.422Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.422Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.423Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.428Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.429Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.430Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.431Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.432Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.432Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.433Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.434Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.435Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.435Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.437Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.438Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.439Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.439Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.439Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.441Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.810Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (color => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.810Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (logColorEnable => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.907Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (dhcpProxyOutPort => 68).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.913Z] [on-dhcp-proxy] [Services.Configuration] [Server] Configuration value is undefined, using default (dhcpProxyEFIOutPort => 4011).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:54.929Z] [on-dhcp-proxy] [Server] [Server] proxyDHCP server is listening on 192.168.37.2:4011
 -> /lib/server.js:59

==> /var/log/rackhd/on-tftp.log <==
[info] [2016-08-23T15:21:55.066Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.066Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.067Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (postgresql => postgres://rackhd:rackhd@localhost:5432/pxe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.068Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (migrate => safe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.077Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.078Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.078Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.079Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.080Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.081Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.081Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.084Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.085Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.086Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.087Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.087Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.096Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.097Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.098Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.098Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.099Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.099Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.099Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.371Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (color => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.371Z] [on-tftp] [Services.Configuration] [Server] Configuration value is undefined, using default (logColorEnable => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:55.442Z] [on-tftp] [Tftp.Server] [Server] TFTP Server Listening 192.168.37.2:69
 -> /lib/server.js:58

==> /var/log/rackhd/on-taskgraph.log <==
[info] [2016-08-23T15:21:56.412Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.413Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.427Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (postgresql => postgres://rackhd:rackhd@localhost:5432/pxe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.427Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (migrate => safe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.431Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.433Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.433Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.434Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.434Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.434Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.435Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.435Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.436Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.437Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.438Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.438Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.439Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.441Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.441Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.443Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.443Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.444Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.445Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.630Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (color => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.630Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (logColorEnable => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73

==> /var/log/rackhd/on-http.log <==
[info] [2016-08-23T15:21:56.907Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.907Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.908Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (postgresql => postgres://rackhd:rackhd@localhost:5432/pxe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.908Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (migrate => safe).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.911Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.912Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.913Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.913Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.913Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.920Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.921Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.921Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.922Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.923Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.923Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.928Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.930Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.932Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.932Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.933Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.933Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.933Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (databaseType => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:56.934Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (taskgraph-store => mongo).
 -> /node_modules/on-core/lib/services/configuration.js:73

==> /var/log/rackhd/on-taskgraph.log <==
[info] [2016-08-23T15:21:57.075Z] [on-taskgraph] [TaskGraph.DataLoader] [Server] Loaded 84 tasks from Task.taskLibrary dependency
 -> /lib/loader.js:128
[info] [2016-08-23T15:21:57.075Z] [on-taskgraph] [TaskGraph.DataLoader] [Server] Loaded 73 graphs matching the pattern 'lib/graphs/**/*-graph.+(js|json)'
 -> /lib/loader.js:129

==> /var/log/rackhd/on-http.log <==
[info] [2016-08-23T15:21:57.101Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (color => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.101Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (logColorEnable => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73

==> /var/log/rackhd/on-taskgraph.log <==
[info] [2016-08-23T15:21:57.131Z] [on-taskgraph] [TaskGraph.DataLoader] [Server] Loaded task/graph definitions:
 -> /lib/loader.js:136
tasks:
  - Task.Os.Esx.Analyze.Repo
  - Task.Os.Boot.LiveCD
  - Task.BootProfile
  - Task.Linux.Bootstrap.Rancher
  - Task.Linux.Bootstrap.Ubuntu
  - Task.WinPE.Bootstrap
  - Task.Catalog.ami
  - Task.Catalog.bmc
  - Task.Catalog.dmi
  - Task.Catalog.Dpkg
  - Task.Catalog.Drive.Id
  - Task.Catalog.flashupdt
  - Task.Ssh.Catalog.Ip
  - Task.Catalog.LLDP
  - Task.Catalog.Local.DMI
  - Task.Catalog.Local.LLDP
  - Task.Catalog.lsall
  - Task.Catalog.megaraid
  - Task.Catalog.Mgmt.bmc
  - Task.Catalog.ohai
  - Task.Catalog.perccli
  - Task.Catalog.smart
  - Task.Catalog.Rpm
  - Task.Snmp.Catalog
  - Task.Obm.Node.ClearSEL
  - Task.Evaluate.Condition
  - Task.Ssh.CopyKey
  - Task.Pollers.CreateDefault
  - Task.Obm.Ipmi.CreateSettings
  - Task.Raid.Create.MegaRAID
  - Task.Raid.Delete.MegaRAID
  - Task.Dell.Racadm.Control
  - Task.Dell.Racadm.GetBIOS
  - Task.Dell.Racadm.GetConfigCatalog
  - Task.Dell.Racadm.SetBIOS
  - Task.Dell.Racadm.Update.Firmware
  - Task.Linux.DownloadFiles
  - Task.Emc.Compose.System
  - Task.Emc.Redfish.Catalog
  - Task.Trigger.Send.Finish
  - Task.Linux.Flash.Ami.Bios
  - Task.Linux.Flash.LSI.MegaRAID
  - Task.Linux.Flash.Quanta.Bmc
  - Task.Catalog.GenerateEnclosure
  - Task.Catalog.GenerateSku
  - Task.Catalog.GenerateTag
  - Task.Obm.Node.IdentifyOff
  - Task.Obm.Node.IdentifyOn
  - Task.Os.Install.CentOS
  - Task.Os.Install.CoreOS
  - Task.Os.Install.ESXi
  - Task.Os.Install.PhotonOS
  - Task.Os.Install.SUSE
  - Task.Os.Install.Ubuntu
  - Task.Os.Install.Win
  - Task.IscDhcpLeasePoller
  - Task.Obm.Node.McResetCold
  - Task.Alert.Node.Discovered
  - Task.noop
  - Task.Obm.Node.PowerOff
  - Task.Obm.Node.PowerOn
  - Task.ProcShellReboot
  - Task.Catalogs.Provide.Ami.BiosVersion
  - Task.Obm.Node.Reboot
  - Task.Redfish.Discovery
  - Task.Obm.Redfish.Actions.Reset
  - Task.Obm.Node.Reset
  - Task.Remove.BMC.Credentials
  - Task.Os.Run.Emc.Diag
  - Task.Run.Uefi
  - Task.Drive.SecureErase
  - Task.Linux.SetNvram.Ami
  - Task.Set.BMC.Credentials
  - Task.Obm.Node.PxeBoot
  - Task.Sftp
  - Task.Linux.Commands
  - Task.Snmp.Collect.Discovery
  - Task.Snmp.Node.Update
  - Task.Snmp.Ping
  - Task.Snmp.Update.Lookups
  - Task.Obm.Node.Reset.Soft
  - Task.Ssh.Exec
  - Task.Ssh.Validation
  - Task.Wait.Completion.Uri
graphs:
  - Graph.Switch.Discovery.Arista.Ztp
  - Graph.BootLiveCD
  - Graph.Bootstrap.With.BMC.Credentials.Remove
  - Graph.Bootstrap.With.BMC.Credentials.Setup
  - Graph.Bootstrap.Decommission.Node
  - Graph.BootstrapUbuntu
  - Graph.Switch.Discovery.Brocade.Ztp
  - Graph.Switch.Discovery.Cisco.Poap
  - Graph.ClearSEL.Node
  - Graph.Emc.Redfish.FabricService.Poller.Create
  - Graph.Obm.Ipmi.CreateSettings
  - Graph.Raid.Create.MegaRAID
  - Graph.Redfish.Chassis.Poller.Create
  - Graph.Redfish.Managers.Poller.Create
  - Graph.Redfish.Systems.Poller.Create
  - Graph.Obm.Vbox.CreateSettings
  - Graph.Raid.Delete.MegaRAID
  - Graph.Dell.Disable.VTx
  - Graph.Dell.Enable.VTx
  - Graph.Dell.Racadm.GetBIOS
  - Graph.Dell.Racadm.GetConfigCatalog
  - Graph.Dell.Racadm.SetBIOS
  - Graph.Dell.Racadm.Update.Firmware
  - Graph.Discovery
  - Graph.Mgmt.Discovery
  - Graph.MgmtSKU.Discovery
  - Graph.Redfish.System.Discovery
  - Graph.Refresh.Delayed.Discovery
  - Graph.Refresh.Immediate.Discovery
  - Graph.SKU.Discovery
  - Graph.Emc.Compose.System
  - Graph.Emc.Redfish.Catalog
  - Graph.BootstrapUbuntuMocks
  - Graph.Flash.LSI.MegaRAID
  - Graph.Flash.Quanta
  - Graph.Flash.Quanta.BIOS
  - Graph.Flash.Quanta.Bmc
  - Graph.GenerateSku
  - Graph.GenerateTags
  - Graph.InstallCoreOS
  - Graph.Service.IscDhcpLeasePoller
  - Graph.Flash.Quanta.MegaRAID
  - Graph.InstallCentOS
  - Graph.InstallESXi
  - Graph.InstallPhotonOS
  - Graph.InstallRHEL
  - Graph.InstallSUSE
  - Graph.InstallUbuntu
  - Graph.InstallWindowsServer
  - Graph.Catalog.Intel.Flashupdt
  - Graph.McReset
  - Graph.noop-example
  - Graph.PDU.Discovery
  - Graph.Service.Poller
  - Graph.PowerOff.Node
  - Graph.PowerOn.Node
  - Graph.Reboot.Node
  - Graph.Redfish.Discovery
  - Graph.Redfish.Actions.Reset
  - Graph.Remove.Bmc.Credentials
  - Graph.Reset.Node
  - Graph.Run.Emc.Diag
  - Graph.Drive.SecureErase
  - Graph.Set.Bmc.Credentials
  - Graph.ShellCommands
  - Graph.Reset.Soft.Node
  - Graph.SKU.Switch.Discovery.Active
  - Graph.Switch.Discovery
  - Graph.Switch.SKU.Discovery.Hooks.Post
  - Graph.RunUefi
  - Graph.BootstrapWinPE
  - Graph.Write.Quanta.BIOS.NVRAM
  - Graph.Arista.Zerotouch.vEOS
[info] [2016-08-23T15:21:57.188Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Task runner started
 -> /lib/task-runner.js:435
TaskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
domain:       default
[info] [2016-08-23T15:21:57.194Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduler started
 -> /lib/task-scheduler.js:676
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
domain:      default
[debug] [2016-08-23T15:21:57.363Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     a1075c2a-ec8e-43f6-83cd-28e49edaced5
taskId:      9972f44c-22e4-477c-b7dc-d9cda74a84ad
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[debug] [2016-08-23T15:21:57.365Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     a1075c2a-ec8e-43f6-83cd-28e49edaced5
taskId:      9738b1fb-34ed-4cab-8dc4-9b16d0adc813
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[debug] [2016-08-23T15:21:57.365Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     a1075c2a-ec8e-43f6-83cd-28e49edaced5
taskId:      e391feaa-3aa1-4fd7-81dc-13b69d5f8d96
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[debug] [2016-08-23T15:21:57.365Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     a1075c2a-ec8e-43f6-83cd-28e49edaced5
taskId:      1be9b866-9f9c-4d60-a7be-b46c6a97b18d
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[debug] [2016-08-23T15:21:57.365Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     a1075c2a-ec8e-43f6-83cd-28e49edaced5
taskId:      d1f776dd-9d87-4499-a41e-85814bb1a589
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[debug] [2016-08-23T15:21:57.366Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     a1075c2a-ec8e-43f6-83cd-28e49edaced5
taskId:      53b26607-07ec-4251-901e-cf6d1b5b3600
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[debug] [2016-08-23T15:21:57.366Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     a1075c2a-ec8e-43f6-83cd-28e49edaced5
taskId:      bd67d921-7b74-4ee9-b857-21973a739596
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[debug] [2016-08-23T15:21:57.366Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     a1075c2a-ec8e-43f6-83cd-28e49edaced5
taskId:      bd88573e-a459-43a7-be7d-79560b4233a4
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[debug] [2016-08-23T15:21:57.366Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     a1075c2a-ec8e-43f6-83cd-28e49edaced5
taskId:      a2f697b9-44d1-48a7-a7aa-77aa4421f016
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[info] [2016-08-23T15:21:57.429Z] [on-taskgraph] [TaskGraph] [Server] Task Graph Runner Started.
 -> /index.js:55
[debug] [2016-08-23T15:21:57.470Z] [on-taskgraph] [TaskGraph.TaskScheduler] [Server] Task scheduled
 -> /lib/task-scheduler.js:471
domain:      default
graphId:     3f8f6da1-3b34-4fae-988a-29bcadff75c8
taskId:      c4575706-2a48-4585-90ab-ac3a88b7d5c7
schedulerId: e6fc9187-fd84-473c-b810-463e75be2619
[info] [2016-08-23T15:21:57.476Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       d1f776dd-9d87-4499-a41e-85814bb1a589
taskName:     Task.Inline.Poller.Alert.Ipmi.Sdr
[info] [2016-08-23T15:21:57.477Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       9972f44c-22e4-477c-b7dc-d9cda74a84ad
taskName:     Task.Inline.Poller.WorkItems.Clean
[info] [2016-08-23T15:21:57.477Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       1be9b866-9f9c-4d60-a7be-b46c6a97b18d
taskName:     Task.Inline.Snmp
[info] [2016-08-23T15:21:57.477Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       53b26607-07ec-4251-901e-cf6d1b5b3600
taskName:     Task.Inline.Poller.Alert.Ipmi.Sel
[info] [2016-08-23T15:21:57.477Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       e391feaa-3aa1-4fd7-81dc-13b69d5f8d96
taskName:     Task.Inline.Ipmi
[info] [2016-08-23T15:21:57.478Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       9738b1fb-34ed-4cab-8dc4-9b16d0adc813
taskName:     Task.Inline.Poller.WorkItems.Run
[info] [2016-08-23T15:21:57.478Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       bd67d921-7b74-4ee9-b857-21973a739596
taskName:     Task.Inline.Poller.Alert.Snmp
[info] [2016-08-23T15:21:57.510Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       a2f697b9-44d1-48a7-a7aa-77aa4421f016
taskName:     Task.Inline.Redfish
[info] [2016-08-23T15:21:57.510Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       bd88573e-a459-43a7-be7d-79560b4233a4
taskName:     Task.Inline.Poller.Cache
[info] [2016-08-23T15:21:57.525Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: e391feaa-3aa1-4fd7-81dc-13b69d5f8d96
name:   Ipmi requester
job:    Job.Ipmi
[info] [2016-08-23T15:21:57.530Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: d1f776dd-9d87-4499-a41e-85814bb1a589
name:   IPMI Sdr alerter
job:    Job.Poller.Alert.Ipmi.Sdr
[info] [2016-08-23T15:21:57.531Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: 9738b1fb-34ed-4cab-8dc4-9b16d0adc813
name:   Run Poller Work Items
job:    Job.WorkItems.Run
[info] [2016-08-23T15:21:57.531Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: 9972f44c-22e4-477c-b7dc-d9cda74a84ad
name:   Clean Poller Work Items
job:    Job.WorkItems.Clean
[info] [2016-08-23T15:21:57.531Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: bd67d921-7b74-4ee9-b857-21973a739596
name:   SNMP alerter
job:    Job.Poller.Alert.Snmp
[info] [2016-08-23T15:21:57.532Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: 1be9b866-9f9c-4d60-a7be-b46c6a97b18d
name:   SNMP requester
job:    Job.Snmp
[info] [2016-08-23T15:21:57.533Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: 53b26607-07ec-4251-901e-cf6d1b5b3600
name:   IPMI Sel alerter
job:    Job.Poller.Alert.Ipmi.Sel
[debug] [2016-08-23T15:21:57.533Z] [on-taskgraph] [Job.Ipmi] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.Ipmi
name:    IpmiJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
taskId:  e391feaa-3aa1-4fd7-81dc-13b69d5f8d96
target:  none
[debug] [2016-08-23T15:21:57.535Z] [on-taskgraph] [Job.Poller.Alert.Ipmi.Sdr] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.Poller.Alert.Ipmi.Sdr
name:    IpmiSdrPollerAlertJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
taskId:  d1f776dd-9d87-4499-a41e-85814bb1a589
target:  none
[debug] [2016-08-23T15:21:57.538Z] [on-taskgraph] [Job.WorkItems.Run] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.WorkItems.Run
name:    RunWorkItemsJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:         -1
  queuePollInterval:    1000
  defaultLeaseDuration: 15000
taskId:  9738b1fb-34ed-4cab-8dc4-9b16d0adc813
target:  none
[debug] [2016-08-23T15:21:57.539Z] [on-taskgraph] [Job.WorkItems.Clean] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.WorkItems.Clean
name:    CleanWorkItemsJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
  queuePollInterval:  10000
  leaseAdjust:        0
taskId:  9972f44c-22e4-477c-b7dc-d9cda74a84ad
target:  none
[debug] [2016-08-23T15:21:57.542Z] [on-taskgraph] [Job.Poller.Alert.Snmp] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.Poller.Alert.Snmp
name:    SnmpPollerAlertJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
taskId:  bd67d921-7b74-4ee9-b857-21973a739596
target:  none
[debug] [2016-08-23T15:21:57.545Z] [on-taskgraph] [Job.Snmp] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.Snmp
name:    SnmpJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
taskId:  1be9b866-9f9c-4d60-a7be-b46c6a97b18d
target:  none
[debug] [2016-08-23T15:21:57.546Z] [on-taskgraph] [Job.Poller.Alert.Ipmi.Sel] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.Poller.Alert.Ipmi.Sel
name:    IpmiSelPollerAlertJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
taskId:  53b26607-07ec-4251-901e-cf6d1b5b3600
target:  none
[info] [2016-08-23T15:21:57.639Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (pollerCacheSize => 10).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.639Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: bd88573e-a459-43a7-be7d-79560b4233a4
name:   Poller cache
job:    Job.Message.Cache
[info] [2016-08-23T15:21:57.640Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: a2f697b9-44d1-48a7-a7aa-77aa4421f016
name:   Redfish requester
job:    Job.Redfish
[debug] [2016-08-23T15:21:57.640Z] [on-taskgraph] [Job.Message.Cache] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.Message.Cache
name:    PollerMessageCacheJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
taskId:  bd88573e-a459-43a7-be7d-79560b4233a4
target:  none
[debug] [2016-08-23T15:21:57.650Z] [on-taskgraph] [Job.Redfish] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.Redfish
name:    RedfishJob
options:
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
taskId:  a2f697b9-44d1-48a7-a7aa-77aa4421f016
target:  none
[info] [2016-08-23T15:21:57.700Z] [on-taskgraph] [TaskGraph.TaskRunner] [Server] Running task
 -> /lib/task-runner.js:340
taskRunnerId: ddd93ec3-1e90-44eb-bc66-4625b8053365
taskId:       c4575706-2a48-4585-90ab-ac3a88b7d5c7
taskName:     Task.IscDhcpLeasePoller
[info] [2016-08-23T15:21:57.717Z] [on-taskgraph] [Services.Configuration] [Server] Configuration value is undefined, using default (dhcpLeasesPath => /var/lib/dhcp/dhcpd.leases).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.718Z] [on-taskgraph] [Task.Task] [Server] Running task job.
 -> /node_modules/on-tasks/lib/task.js:361
taskId: c4575706-2a48-4585-90ab-ac3a88b7d5c7
name:   isc-dhcp leases poller
job:    Job.IscDhcpLeasePoller
[debug] [2016-08-23T15:21:57.718Z] [on-taskgraph] [Job.IscDhcpLeasePoller] [Server] Running job.
 -> /node_modules/on-tasks/lib/jobs/base-job.js:112
module:  Job.IscDhcpLeasePoller
name:    IscDhcpLeasePollerJob
options:
  leasesFile:         /var/lib/dhcp/dhcpd.leases
  schedulerOverrides:
    timeout: -1
  _taskTimeout:       -1
taskId:  c4575706-2a48-4585-90ab-ac3a88b7d5c7
target:  none

==> /var/log/rackhd/on-http.log <==
[info] [2016-08-23T15:21:57.853Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (skuPackRoot => ./skupack.d).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.859Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (enableUPnP => true).
 -> /node_modules/on-core/lib/services/configuration.js:73
[debug] [2016-08-23T15:21:57.869Z] [on-http] [Http.Services.uPnP] [Server] Starting SSDP/uPnP server
 -> /lib/services/upnp-service.js:200
usn:
  urn:   upnp:rootdevice
  path:  /upnp/upnp-device-description.xml
  alive: false
[debug] [2016-08-23T15:21:57.872Z] [on-http] [Http.Services.uPnP] [Server] Starting SSDP/uPnP server
 -> /lib/services/upnp-service.js:200
usn:
  urn:   urn:schemas-upnp-org:device:on-http:1
  path:  /upnp/upnp-device-description.xml
  alive: false
[debug] [2016-08-23T15:21:57.872Z] [on-http] [Http.Services.uPnP] [Server] Starting SSDP/uPnP server
 -> /lib/services/upnp-service.js:200
usn:
  urn:   urn:schemas-upnp-org:service:api:1.1
  path:  /api/1.1/
  alive: false
[debug] [2016-08-23T15:21:57.873Z] [on-http] [Http.Services.uPnP] [Server] Starting SSDP/uPnP server
 -> /lib/services/upnp-service.js:200
usn:
  urn:   urn:schemas-upnp-org:service:api:2.0
  path:  /api/2.0/
  alive: false
[debug] [2016-08-23T15:21:57.873Z] [on-http] [Http.Services.uPnP] [Server] Starting SSDP/uPnP server
 -> /lib/services/upnp-service.js:200
usn:
  urn:   urn:dmtf-org:service:redfish-rest:1.0
  path:  /redfish/v1/
  alive: false
[debug] [2016-08-23T15:21:57.873Z] [on-http] [Http.Services.uPnP] [Server] Starting SSDP/uPnP server
 -> /lib/services/upnp-service.js:200
usn:
  urn:   urn:schemas-upnp-org:service:api:2.0:southbound
  path:  /api/2.0/
  alive: false
[debug] [2016-08-23T15:21:57.873Z] [on-http] [Http.Services.uPnP] [Server] Starting SSDP/uPnP server
 -> /lib/services/upnp-service.js:200
usn:
  urn:   urn:schemas-upnp-org:service:api:1.1:southbound
  path:  /api/1.1/
  alive: false
[debug] [2016-08-23T15:21:57.873Z] [on-http] [Http.Services.uPnP] [Server] Starting SSDP/uPnP server
 -> /lib/services/upnp-service.js:200
usn:
  urn:   urn:dmtf-org:service:redfish-rest:1.0:southbound
  path:  /redfish/v1/
  alive: false
[debug] [2016-08-23T15:21:57.876Z] [on-http] [Http.Services.uPnP] [Server] addMembership
 -> /lib/services/upnp-service.js:216
code:    EADDRINUSE
errno:   EADDRINUSE
syscall: addMembership
[debug] [2016-08-23T15:21:57.876Z] [on-http] [Http.Services.uPnP] [Server] addMembership
 -> /lib/services/upnp-service.js:216
code:    EADDRINUSE
errno:   EADDRINUSE
syscall: addMembership
[debug] [2016-08-23T15:21:57.876Z] [on-http] [Http.Services.uPnP] [Server] addMembership
 -> /lib/services/upnp-service.js:216
code:    EADDRINUSE
errno:   EADDRINUSE
syscall: addMembership
[debug] [2016-08-23T15:21:57.877Z] [on-http] [Http.Services.uPnP] [Server] addMembership
 -> /lib/services/upnp-service.js:216
code:    EADDRINUSE
errno:   EADDRINUSE
syscall: addMembership
[debug] [2016-08-23T15:21:57.877Z] [on-http] [Http.Services.uPnP] [Server] addMembership
 -> /lib/services/upnp-service.js:216
code:    EADDRINUSE
errno:   EADDRINUSE
syscall: addMembership
[info] [2016-08-23T15:21:57.880Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (authTokenSecret => RackHDRocks!).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.881Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (authTokenExpireIn => 86400).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.909Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (versionBase => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.911Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (trustedProxy => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.937Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (authTokenSecret => RackHDRocks!).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.938Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (authTokenExpireIn => 86400).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.938Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (versionBase => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.938Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (trustedProxy => undefined).
 -> /node_modules/on-core/lib/services/configuration.js:73
[info] [2016-08-23T15:21:57.950Z] [on-http] [Services.Configuration] [Server] Configuration value is undefined, using default (maxTaskPayloadSize => 10mb).
 -> /node_modules/on-core/lib/services/configuration.js:73
[{"code":"UNUSED_DEFINITION","message":"Definition is not used: #/definitions/lease","path":["definitions","lease"]}] 2
[HPM] Proxy created: /ubuntu/  ->  http://192.168.37.2:3142
[HPM] Proxy rewrite rule created: "^/ubuntu/" ~> "/ubuntu/"
[HPM] Proxy created: /sles-install/  ->  http://repo.core.rcsops.com
[HPM] Proxy rewrite rule created: "^/sles-install/" ~> "/sles-install/"
[info] [2016-08-23T15:21:59.428Z] [on-http] [Http.Server] [Server] Create proxy succeeded
 -> /lib/services/http-service.js:409
localPath:     /ubuntu/
remotePath:    /ubuntu/
serverAddress: http://192.168.37.2:3142
[info] [2016-08-23T15:21:59.429Z] [on-http] [Http.Server] [Server] Create proxy succeeded
 -> /lib/services/http-service.js:409
localPath:     /sles-install/
remotePath:    /sles-install/
serverAddress: http://repo.core.rcsops.com
[{"code":"UNUSED_DEFINITION","message":"Definition is not used: #/definitions/lease","path":["definitions","lease"]}] 2
[HPM] Proxy created: /ubuntu/  ->  http://192.168.37.2:3142
[HPM] Proxy rewrite rule created: "^/ubuntu/" ~> "/ubuntu/"
[HPM] Proxy created: /sles-install/  ->  http://repo.core.rcsops.com
[HPM] Proxy rewrite rule created: "^/sles-install/" ~> "/sles-install/"
[info] [2016-08-23T15:22:00.433Z] [on-http] [Http.Server] [Server] Create proxy succeeded
 -> /lib/services/http-service.js:409
localPath:     /ubuntu/
remotePath:    /ubuntu/
serverAddress: http://192.168.37.2:3142
[info] [2016-08-23T15:22:00.434Z] [on-http] [Http.Server] [Server] Create proxy succeeded
 -> /lib/services/http-service.js:409
localPath:     /sles-install/
remotePath:    /sles-install/
serverAddress: http://repo.core.rcsops.com
[info] [2016-08-23T15:22:00.441Z] [on-http] [Http.Server] [Server] Server Started.
 -> /index.js:68
[debug] [2016-08-23T15:22:00.879Z] [on-http] [Http.Services.uPnP] [Server] advertise-alive
 -> /lib/services/upnp-service.js:110
usn:
  urn:   upnp:rootdevice
  path:  /upnp/upnp-device-description.xml
  alive: true
NT:  upnp:rootdevice
[debug] [2016-08-23T15:22:00.880Z] [on-http] [Http.Services.uPnP] [Server] advertise-alive
 -> /lib/services/upnp-service.js:110
usn:
  urn:   urn:schemas-upnp-org:device:on-http:1
  path:  /upnp/upnp-device-description.xml
  alive: true
NT:  urn:schemas-upnp-org:device:on-http:1
[debug] [2016-08-23T15:22:00.880Z] [on-http] [Http.Services.uPnP] [Server] advertise-alive
 -> /lib/services/upnp-service.js:110
usn:
  urn:   urn:schemas-upnp-org:service:api:1.1
  path:  /api/1.1/
  alive: true
NT:  urn:schemas-upnp-org:service:api:1.1
[debug] [2016-08-23T15:22:00.880Z] [on-http] [Http.Services.uPnP] [Server] advertise-alive
 -> /lib/services/upnp-service.js:110
usn:
  urn:   urn:schemas-upnp-org:service:api:2.0
  path:  /api/2.0/
  alive: true
NT:  urn:schemas-upnp-org:service:api:2.0
[debug] [2016-08-23T15:22:00.880Z] [on-http] [Http.Services.uPnP] [Server] advertise-alive
 -> /lib/services/upnp-service.js:110
usn:
  urn:   urn:dmtf-org:service:redfish-rest:1.0
  path:  /redfish/v1/
  alive: true
NT:  urn:dmtf-org:service:redfish-rest:1.0
[debug] [2016-08-23T15:22:00.881Z] [on-http] [Http.Services.uPnP] [Server] advertise-alive
 -> /lib/services/upnp-service.js:110
usn:
  urn:   urn:schemas-upnp-org:service:api:2.0:southbound
  path:  /api/2.0/
  alive: true
NT:  urn:schemas-upnp-org:service:api:2.0:southbound
[debug] [2016-08-23T15:22:00.881Z] [on-http] [Http.Services.uPnP] [Server] advertise-alive
 -> /lib/services/upnp-service.js:110
usn:
  urn:   urn:schemas-upnp-org:service:api:1.1:southbound
  path:  /api/1.1/
  alive: true
NT:  urn:schemas-upnp-org:service:api:1.1:southbound
[debug] [2016-08-23T15:22:00.881Z] [on-http] [Http.Services.uPnP] [Server] advertise-alive
 -> /lib/services/upnp-service.js:110
usn:
  urn:   urn:dmtf-org:service:redfish-rest:1.0:southbound
  path:  /redfish/v1/
  alive: true
NT:  urn:dmtf-org:service:redfish-rest:1.0:southbound

==> /var/log/rackhd/on-syslog.log <==
[info] [2016-08-23T15:22:14.321Z] [on-syslog] [Syslog] [Server] gertie: omapi connection worked

## fulllog-3.log
==> /var/log/rackhd/on-dhcp-proxy.log <==
  name:    RequestTimedOutError
  context:

[debug] [2016-08-23T15:31:22.660Z] [on-dhcp-proxy] [DHCP.messageHandler] [Server] DHCP packetData:
 -> /lib/message-handler.js:225
userClass:             MonoRail
vendorClassIdentifier: PXEClient:Arch:00000:UNDI:002001
archType:              0
[debug] [2016-08-23T15:31:22.661Z] [on-dhcp-proxy] [Server] [Server] current OutPort => 68
 -> /lib/server.js:39

==> /var/log/rackhd/on-http.log <==
    at Async._drainQueue (/var/renasar/on-http/node_modules/on-core/node_modules/waterline/node_modules/bluebird/js/main/async.js:123:16)
    at Async._drainQueues (/var/renasar/on-http/node_modules/on-core/node_modules/waterline/node_modules/bluebird/js/main/async.js:133:10)
    at Immediate.Async.drainQueues [as _onImmediate] (/var/renasar/on-http/node_modules/on-core/node_modules/waterline/node_modules/bluebird/js/main/async.js:15:14)
    at processImmediate [as _immediateCallback] (timers.js:383:17)
[debug] [2016-08-23T17:00:01.487Z] [on-http] [Http.Server] [Server] http: GET 200 5.927 - /api/1.1/nodes/57bc6a357ffe716e62905465/catalogs/rubicon
 -> /lib/services/http-service.js:333
ipAddress: 192.168.37.2
[debug] [2016-08-23T17:00:01.496Z] [on-http] [Http.Server] [Server] http: GET 200 6.306 - /api/1.1/nodes/57bc6a357ffe716e62905465/catalogs/lshw
 -> /lib/services/http-service.js:333
ipAddress: 192.168.37.2

==> /var/log/rackhd/on-syslog.log <==
facility: 1
priority: 6
[info] [2016-08-23T17:00:01.497Z] [on-syslog] [Syslog] [Server] gertie: 57bc6a357ffe716e62905465: fqdn: pxe01.vagrant.lab
	{
	"injectableName": "Graph.Rubicon.Vagrant.Trusty.End2End",
	"friendlyName": "Vagrant Trusty End to End",
	"options": {
	"bootstrap-ubuntu": {
	"overlayfs": "common/rubicon.overlay.cpio.gz"
	},
	"install-ubuntu": {
	"profile": "install-trusty.ipxe",
	"version": "trusty",
	"username": "renasar",
	"password": "renasar",
	"uid": "1010",
	"hostname": "{{ context.rubicon.config_values.hostname }}",
	"domain": "{{ context.rubicon.config_values.domain }}"
	},
	"delay-ansible-start": {
	"delay": 300000
	},
	"ansible-post-install": {
	"playbook": "/opt/rackhd/ansible/site.yml",
	"vars": {
	"metallica_role": "vagrant-trusty"
	}
	}
	},
	"tasks": [
	{
	"label": "bootstrap-ubuntu",
	"taskName": "Task.Linux.Bootstrap.Ubuntu"
	},
	{
	"label": "create-rubicon-catalog",
	"taskName": "Task.Catalog.Rubicon",
	"waitOn": {
	"bootstrap-ubuntu": "succeeded"
	}
	},
	{
	"taskName": "Task.Base.Ansible.Enrich",
	"label": "ansible-gertie-enrich",
	"waitOn": {
	"create-rubicon-catalog": "succeeded"
	}
	},
	{
	"label": "provide-rubicon-catalog-data",
	"taskName": "Task.Catalogs.Provide.Rubicon.CatalogData",
	"waitOn": {
	"ansible-gertie-enrich": "succeeded"
	}
	},
	{
	"label": "shell-reboot",
	"taskName": "Task.ProcShellReboot",
	"waitOn": {
	"provide-rubicon-catalog-data": "succeeded"
	}
	},
	{
	"label": "install-ubuntu",
	"taskName": "Task.Os.Install.Ubuntu",
	"waitOn": {
	"shell-reboot": "succeeded"
	}
	},
	{
	"label": "delay-ansible-start",
	"taskName": "Task.noop",
	"waitOn": {
	"install-ubuntu": "succeeded"
	}
	},
	{
	"label": "ansible-post-install",
	"taskName": "Task.Base.Ansible.PostInstall",
	"waitOn": {
	"delay-ansible-start": "finished"
	}
	}
	]
	}
	==> /var/log/rackhd/on-dhcp-proxy.log <==
	name: RequestTimedOutError
	context:

	[debug] [2016-08-23T15:31:22.660Z] [on-dhcp-proxy] [DHCP.messageHandler] [Server] DHCP packetData:
	-> /lib/message-handler.js:225
	userClass: MonoRail
	vendorClassIdentifier: PXEClient:Arch:00000:UNDI:002001
	archType: 0
	[debug] [2016-08-23T15:31:22.661Z] [on-dhcp-proxy] [Server] [Server] current OutPort => 68
	-> /lib/server.js:39

	==> /var/log/rackhd/on-http.log <==
	at Async._drainQueue (/var/renasar/on-http/node_modules/on-core/node_modules/waterline/node_modules/bluebird/js/main/async.js:123:16)
	at Async._drainQueues (/var/renasar/on-http/node_modules/on-core/node_modules/waterline/node_modules/bluebird/js/main/async.js:133:10)
	at Immediate.Async.drainQueues [as _onImmediate] (/var/renasar/on-http/node_modules/on-core/node_modules/waterline/node_modules/bluebird/js/main/async.js:15:14)
	at processImmediate [as _immediateCallback] (timers.js:383:17)
	[debug] [2016-08-23T17:00:01.487Z] [on-http] [Http.Server] [Server] http: GET 200 5.927 - /api/1.1/nodes/57bc6a357ffe716e62905465/catalogs/rubicon
	-> /lib/services/http-service.js:333
	ipAddress: 192.168.37.2
	[debug] [2016-08-23T17:00:01.496Z] [on-http] [Http.Server] [Server] http: GET 200 6.306 - /api/1.1/nodes/57bc6a357ffe716e62905465/catalogs/lshw
	-> /lib/services/http-service.js:333
	ipAddress: 192.168.37.2

	==> /var/log/rackhd/on-syslog.log <==
	facility: 1
	priority: 6
	[info] [2016-08-23T17:00:01.497Z] [on-syslog] [Syslog] [Server] gertie: 57bc6a357ffe716e62905465: fqdn: pxe01.vagrant.lab