proxy.log - Smart Proxy - Foreman

Bug #18970 » proxy.log

Dmitry Okun, 03/21/2017 02:26 PM

       D, [2017-03-21T09:36:15.508208 ] DEBUG -- : accept: 15.162.41.254:41530
       D, [2017-03-21T09:36:15.513473 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:36:15.515272 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T09:36:15.516322 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:36:15 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0015
       D, [2017-03-21T09:36:15.558725 ] DEBUG -- : close: 15.162.41.254:41530
       D, [2017-03-21T09:36:15.624105 ] DEBUG -- : accept: 15.162.41.254:41532
       D, [2017-03-21T09:36:15.627915 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:36:15.628964 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:36:15.629445 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T09:36:15.629785 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T09:36:15.630160 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T09:36:15.630238 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T09:36:15.630661 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:36:15 -0700] "DELETE /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0019
       D, [2017-03-21T09:36:15.672216 ] DEBUG -- : close: 15.162.41.254:41532
       D, [2017-03-21T09:36:15.730043 ] DEBUG -- : accept: 15.162.41.254:41534
       D, [2017-03-21T09:36:15.733842 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:36:15.735384 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:36:15.735727 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T09:36:15.735989 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T09:36:15.746103 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T09:36:15.746281 ] DEBUG -- : nil
       D, [2017-03-21T09:36:15.746392 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T09:36:15.746463 ] DEBUG -- : nil
       D, [2017-03-21T09:36:15.746546 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T09:36:15.746612 ] DEBUG -- : nil
       D, [2017-03-21T09:36:15.746686 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T09:36:15.746755 ] DEBUG -- : nil
       D, [2017-03-21T09:36:15.747062 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T09:36:15.747160 ] DEBUG -- : nil
       D, [2017-03-21T09:36:15.747247 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T09:36:15.747310 ] DEBUG -- : nil
       E, [2017-03-21T09:36:15.785349 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T09:36:15.785984 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T09:36:15.786070 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T09:36:15.787128 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:36:15 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0523
       D, [2017-03-21T09:36:15.828232 ] DEBUG -- : close: 15.162.41.254:41534
       D, [2017-03-21T09:36:15.905567 ] DEBUG -- : accept: 15.162.41.254:41538
       D, [2017-03-21T09:36:15.910085 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:36:15.911539 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:36:15.924324 ] DEBUG -- : running /usr/bin/nsupdate -k /etc/rndc.key
       D, [2017-03-21T09:36:15.952005 ] DEBUG -- : nsupdate: executed - server 127.0.0.1
       D, [2017-03-21T09:36:15.952212 ] DEBUG -- : nsupdate: executed - update delete 241.41.162.15.in-addr.arpa PTR
       I, [2017-03-21T09:36:15.987044 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:36:15 -0700] "DELETE /dns/241.41.162.15.in-addr.arpa HTTP/1.1" 200 - 0.0758
       D, [2017-03-21T09:36:15.990230 ] DEBUG -- : close: 15.162.41.254:41538
       D, [2017-03-21T09:36:16.090025 ] DEBUG -- : accept: 15.162.41.254:41540
       D, [2017-03-21T09:36:16.094291 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:36:16.096725 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:36:16.097792 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub2/grub.cfg-01-2c-44-fd-94-c1-d0 created successfully
       I, [2017-03-21T09:36:16.098271 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:36:16 -0700] "POST /tftp/PXEGrub2/2c:44:fd:94:c1:d0 HTTP/1.1" 200 - 0.0028
       D, [2017-03-21T09:36:16.099341 ] DEBUG -- : close: 15.162.41.254:41540
       D, [2017-03-21T09:36:16.171465 ] DEBUG -- : accept: 15.162.41.254:41542
       D, [2017-03-21T09:36:16.175274 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:36:16.176977 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:36:16.177689 ] DEBUG -- : TFTP: /var/lib/tftpboot/pxelinux.cfg/01-2c-44-fd-94-c1-d0 created successfully
       I, [2017-03-21T09:36:16.178130 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:36:16 -0700] "POST /tftp/PXELinux/2c:44:fd:94:c1:d0 HTTP/1.1" 200 - 0.0019
       D, [2017-03-21T09:36:16.180228 ] DEBUG -- : close: 15.162.41.254:41542
       D, [2017-03-21T09:36:16.256444 ] DEBUG -- : accept: 15.162.41.254:41544
       D, [2017-03-21T09:36:16.260569 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:36:16.262068 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:36:16.262900 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub/menu.lst.012C44FD94C1D0 created successfully
       D, [2017-03-21T09:36:16.263286 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub/01-2C-44-FD-94-C1-D0 created successfully
       I, [2017-03-21T09:36:16.263709 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:36:16 -0700] "POST /tftp/PXEGrub/2c:44:fd:94:c1:d0 HTTP/1.1" 200 - 0.0022
       D, [2017-03-21T09:36:16.264706 ] DEBUG -- : close: 15.162.41.254:41544
       D, [2017-03-21T09:38:44.648849 ] DEBUG -- : accept: 15.162.41.254:41546
       D, [2017-03-21T09:38:44.654688 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:38:44.656133 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T09:38:44.656984 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:38:44 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0011
       D, [2017-03-21T09:38:44.698281 ] DEBUG -- : close: 15.162.41.254:41546
       D, [2017-03-21T09:38:44.763106 ] DEBUG -- : accept: 15.162.41.254:41548
       D, [2017-03-21T09:38:44.767658 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:38:44.768695 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:38:44.769138 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T09:38:44.769456 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T09:38:44.769798 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T09:38:44.769911 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T09:38:44.770351 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:38:44 -0700] "DELETE /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0018
       D, [2017-03-21T09:38:44.811209 ] DEBUG -- : close: 15.162.41.254:41548
       D, [2017-03-21T09:38:44.869047 ] DEBUG -- : accept: 15.162.41.254:41550
       D, [2017-03-21T09:38:44.873121 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:38:44.874505 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:38:44.874876 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T09:38:44.875116 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T09:38:44.883716 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T09:38:44.883922 ] DEBUG -- : nil
       D, [2017-03-21T09:38:44.884036 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T09:38:44.884102 ] DEBUG -- : nil
       D, [2017-03-21T09:38:44.884196 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T09:38:44.884261 ] DEBUG -- : nil
       D, [2017-03-21T09:38:44.884334 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T09:38:44.884396 ] DEBUG -- : nil
       D, [2017-03-21T09:38:44.884661 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T09:38:44.884746 ] DEBUG -- : nil
       D, [2017-03-21T09:38:44.884822 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T09:38:44.884918 ] DEBUG -- : nil
       E, [2017-03-21T09:38:44.924139 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T09:38:44.924759 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T09:38:44.924837 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T09:38:44.925836 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:38:44 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0517
       D, [2017-03-21T09:38:44.967361 ] DEBUG -- : close: 15.162.41.254:41550
       D, [2017-03-21T09:38:45.043476 ] DEBUG -- : accept: 15.162.41.254:41554
       D, [2017-03-21T09:38:45.048185 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:38:45.049626 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:38:45.059838 ] DEBUG -- : running /usr/bin/nsupdate -k /etc/rndc.key
       D, [2017-03-21T09:38:45.065988 ] DEBUG -- : nsupdate: executed - server 127.0.0.1
       D, [2017-03-21T09:38:45.066211 ] DEBUG -- : nsupdate: executed - update delete 241.41.162.15.in-addr.arpa PTR
       I, [2017-03-21T09:38:45.105269 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:38:45 -0700] "DELETE /dns/241.41.162.15.in-addr.arpa HTTP/1.1" 200 - 0.0559
       D, [2017-03-21T09:38:45.109241 ] DEBUG -- : close: 15.162.41.254:41554
       D, [2017-03-21T09:38:45.223431 ] DEBUG -- : accept: 15.162.41.254:41556
       D, [2017-03-21T09:38:45.227903 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:38:45.230270 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:38:45.231240 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub2/grub.cfg-01-2c-44-fd-94-c1-d0 created successfully
       I, [2017-03-21T09:38:45.231683 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:38:45 -0700] "POST /tftp/PXEGrub2/2c:44:fd:94:c1:d0 HTTP/1.1" 200 - 0.0026
       D, [2017-03-21T09:38:45.235101 ] DEBUG -- : close: 15.162.41.254:41556
       D, [2017-03-21T09:38:45.306982 ] DEBUG -- : accept: 15.162.41.254:41558
       D, [2017-03-21T09:38:45.311321 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:38:45.312621 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:38:45.313357 ] DEBUG -- : TFTP: /var/lib/tftpboot/pxelinux.cfg/01-2c-44-fd-94-c1-d0 created successfully
       I, [2017-03-21T09:38:45.313776 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:38:45 -0700] "POST /tftp/PXELinux/2c:44:fd:94:c1:d0 HTTP/1.1" 200 - 0.0015
       D, [2017-03-21T09:38:45.316950 ] DEBUG -- : close: 15.162.41.254:41558
       D, [2017-03-21T09:38:45.397448 ] DEBUG -- : accept: 15.162.41.254:41560
       D, [2017-03-21T09:38:45.401543 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:38:45.403104 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:38:45.403846 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub/menu.lst.012C44FD94C1D0 created successfully
       D, [2017-03-21T09:38:45.404247 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub/01-2C-44-FD-94-C1-D0 created successfully
       I, [2017-03-21T09:38:45.404651 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:38:45 -0700] "POST /tftp/PXEGrub/2c:44:fd:94:c1:d0 HTTP/1.1" 200 - 0.0021
       D, [2017-03-21T09:38:45.406212 ] DEBUG -- : close: 15.162.41.254:41560
       D, [2017-03-21T09:54:38.928898 ] DEBUG -- : accept: 15.162.41.254:41572
       D, [2017-03-21T09:54:38.932854 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:54:38.934097 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:54:38.939512 ] DEBUG -- : running /usr/bin/nsupdate -k /etc/rndc.key
       D, [2017-03-21T09:54:38.945091 ] DEBUG -- : nsupdate: executed - server 127.0.0.1
       D, [2017-03-21T09:54:38.945245 ] DEBUG -- : nsupdate: executed - update delete 241.41.162.15.in-addr.arpa PTR
       I, [2017-03-21T09:54:39.003825 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:54:39 -0700] "DELETE /dns/241.41.162.15.in-addr.arpa HTTP/1.1" 200 - 0.0699
       D, [2017-03-21T09:54:39.010314 ] DEBUG -- : close: 15.162.41.254:41572
       D, [2017-03-21T09:54:39.078809 ] DEBUG -- : accept: 15.162.41.254:41574
       D, [2017-03-21T09:54:39.083410 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:54:39.084725 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T09:54:39.085579 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:54:39 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0012
       D, [2017-03-21T09:54:39.127139 ] DEBUG -- : close: 15.162.41.254:41574
       D, [2017-03-21T09:54:39.173877 ] DEBUG -- : accept: 15.162.41.254:41576
       D, [2017-03-21T09:54:39.177011 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:54:39.177819 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:54:39.178113 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T09:54:39.178317 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T09:54:39.178552 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T09:54:39.178596 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T09:54:39.178908 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:54:39 -0700] "DELETE /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0012
       D, [2017-03-21T09:54:39.222843 ] DEBUG -- : close: 15.162.41.254:41576
       D, [2017-03-21T09:54:39.280611 ] DEBUG -- : accept: 15.162.41.254:41578
       D, [2017-03-21T09:54:39.285797 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:54:39.287010 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:54:39.287896 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub2/grub.cfg-01-2c-44-fd-94-c1-d0 removed successfully
       I, [2017-03-21T09:54:39.288377 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:54:39 -0700] "DELETE /tftp/PXEGrub2/2c:44:fd:94:c1:d0 HTTP/1.1" 200 - 0.0016
       D, [2017-03-21T09:54:39.292966 ] DEBUG -- : close: 15.162.41.254:41578
       D, [2017-03-21T09:54:39.350346 ] DEBUG -- : accept: 15.162.41.254:41580
       D, [2017-03-21T09:54:39.354671 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:54:39.355708 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:54:39.356379 ] DEBUG -- : TFTP: /var/lib/tftpboot/pxelinux.cfg/01-2c-44-fd-94-c1-d0 removed successfully
       I, [2017-03-21T09:54:39.356827 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:54:39 -0700] "DELETE /tftp/PXELinux/2c:44:fd:94:c1:d0 HTTP/1.1" 200 - 0.0013
       D, [2017-03-21T09:54:39.360889 ] DEBUG -- : close: 15.162.41.254:41580
       D, [2017-03-21T09:54:39.417427 ] DEBUG -- : accept: 15.162.41.254:41582
       D, [2017-03-21T09:54:39.421046 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:54:39.422102 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:54:39.422718 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub/menu.lst.012C44FD94C1D0 removed successfully
       D, [2017-03-21T09:54:39.422938 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub/01-2C-44-FD-94-C1-D0 removed successfully
       I, [2017-03-21T09:54:39.423377 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:54:39 -0700] "DELETE /tftp/PXEGrub/2c:44:fd:94:c1:d0 HTTP/1.1" 200 - 0.0015
       D, [2017-03-21T09:54:39.439793 ] DEBUG -- : close: 15.162.41.254:41582
       D, [2017-03-21T09:54:39.573985 ] DEBUG -- : accept: 15.162.41.254:41584
       D, [2017-03-21T09:54:39.578179 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:54:39.579256 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:54:39.580021 ] DEBUG -- : Found puppetca at /opt/puppetlabs/bin/puppet
       D, [2017-03-21T09:54:39.580200 ] DEBUG -- : Found sudo at /usr/bin/sudo
       D, [2017-03-21T09:54:39.580288 ] DEBUG -- : Executing /usr/bin/sudo -S /opt/puppetlabs/bin/puppet cert --ssldir /etc/puppetlabs/puppet/ssl --clean dl380pg8-127.3pardata.com
       D, [2017-03-21T09:54:41.517003 ] DEBUG -- : Attempt to remove nonexistent client certificate for dl380pg8-127.3pardata.com
       E, [2017-03-21T09:54:41.517325 ] ERROR -- : Attempt to remove nonexistent client certificate for dl380pg8-127.3pardata.com
       D, [2017-03-21T09:54:41.517369 ] DEBUG -- : Attempt to remove nonexistent client certificate for dl380pg8-127.3pardata.com
       I, [2017-03-21T09:54:41.517799 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:54:41 -0700] "DELETE /puppet/ca/dl380pg8-127.3pardata.com HTTP/1.1" 404 78 1.9388
       D, [2017-03-21T09:54:41.560334 ] DEBUG -- : close: 15.162.41.254:41584
       D, [2017-03-21T09:54:41.595843 ] DEBUG -- : accept: 15.162.41.254:41586
       D, [2017-03-21T09:54:41.599354 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T09:54:41.600234 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T09:54:41.600642 ] DEBUG -- : Attempt to remove nonexistent client autosign for dl380pg8-127.3pardata.com
       E, [2017-03-21T09:54:41.600809 ] ERROR -- : Attempt to remove nonexistent client autosign for dl380pg8-127.3pardata.com
       D, [2017-03-21T09:54:41.600849 ] DEBUG -- : Attempt to remove nonexistent client autosign for dl380pg8-127.3pardata.com
       I, [2017-03-21T09:54:41.601196 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:09:54:41 -0700] "DELETE /puppet/ca/autosign/dl380pg8-127.3pardata.com HTTP/1.1" 404 75 0.0011
       D, [2017-03-21T09:54:41.643442 ] DEBUG -- : close: 15.162.41.254:41586
       D, [2017-03-21T10:03:13.076193 ] DEBUG -- : accept: 15.162.41.254:41588
       D, [2017-03-21T10:03:13.080834 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:03:13.082173 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:03:13.083112 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:03:13 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0012
       D, [2017-03-21T10:03:13.124269 ] DEBUG -- : close: 15.162.41.254:41588
       D, [2017-03-21T10:03:13.189516 ] DEBUG -- : accept: 15.162.41.254:41590
       D, [2017-03-21T10:03:13.193291 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:03:13.194302 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:03:13.194734 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:03:13.195156 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:03:13.195444 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T10:03:13.195506 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T10:03:13.195935 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:03:13 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0018
       D, [2017-03-21T10:03:13.237212 ] DEBUG -- : close: 15.162.41.254:41590
       D, [2017-03-21T10:03:13.294921 ] DEBUG -- : accept: 15.162.41.254:41592
       D, [2017-03-21T10:03:13.299173 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:03:13.300412 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:03:13.300754 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:03:13.301038 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:03:13.301267 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T10:03:13.301328 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T10:03:13.301719 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:03:13 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0017
       D, [2017-03-21T10:03:13.343221 ] DEBUG -- : close: 15.162.41.254:41592
       D, [2017-03-21T10:03:13.553681 ] DEBUG -- : accept: 15.162.41.254:41594
       D, [2017-03-21T10:03:13.557776 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:03:13.559354 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:03:13.559704 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:03:13.559958 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T10:03:13.565050 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T10:03:13.565258 ] DEBUG -- : nil
       D, [2017-03-21T10:03:13.565368 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T10:03:13.565435 ] DEBUG -- : nil
       D, [2017-03-21T10:03:13.565508 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T10:03:13.565566 ] DEBUG -- : nil
       D, [2017-03-21T10:03:13.565634 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T10:03:13.565699 ] DEBUG -- : nil
       D, [2017-03-21T10:03:13.565969 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T10:03:13.566064 ] DEBUG -- : nil
       D, [2017-03-21T10:03:13.566160 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T10:03:13.566223 ] DEBUG -- : nil
       E, [2017-03-21T10:03:13.602799 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T10:03:13.603342 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T10:03:13.603414 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T10:03:13.604353 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:03:13 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0455
       D, [2017-03-21T10:03:13.645266 ] DEBUG -- : close: 15.162.41.254:41594
       D, [2017-03-21T10:05:37.912789 ] DEBUG -- : accept: 15.162.41.254:41598
       D, [2017-03-21T10:05:37.917145 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:05:37.918825 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:05:37.919834 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:05:37 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0015
       D, [2017-03-21T10:05:37.961205 ] DEBUG -- : close: 15.162.41.254:41598
       D, [2017-03-21T10:05:38.026500 ] DEBUG -- : accept: 15.162.41.254:41600
       D, [2017-03-21T10:05:38.030852 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:05:38.031963 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:05:38.032398 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:05:38.032822 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:05:38.033147 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T10:05:38.033211 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T10:05:38.033623 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:05:38 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0019
       D, [2017-03-21T10:05:38.075189 ] DEBUG -- : close: 15.162.41.254:41600
       D, [2017-03-21T10:05:38.132517 ] DEBUG -- : accept: 15.162.41.254:41602
       D, [2017-03-21T10:05:38.136712 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:05:38.137768 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:05:38.138133 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:05:38.138382 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:05:38.138586 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T10:05:38.138644 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T10:05:38.139084 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:05:38 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0015
       D, [2017-03-21T10:05:38.180188 ] DEBUG -- : close: 15.162.41.254:41602
       D, [2017-03-21T10:05:46.683893 ] DEBUG -- : accept: 15.162.41.254:41604
       D, [2017-03-21T10:05:46.687828 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:05:46.689063 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:05:46.689912 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:05:46 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0011
       D, [2017-03-21T10:05:46.731199 ] DEBUG -- : close: 15.162.41.254:41604
       D, [2017-03-21T10:05:46.796190 ] DEBUG -- : accept: 15.162.41.254:41606
       D, [2017-03-21T10:05:46.800296 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:05:46.801411 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:05:46.801804 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:05:46.802177 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:05:46.802428 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T10:05:46.802488 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T10:05:46.802889 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:05:46 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0016
       D, [2017-03-21T10:05:46.844206 ] DEBUG -- : close: 15.162.41.254:41606
       D, [2017-03-21T10:05:46.900932 ] DEBUG -- : accept: 15.162.41.254:41608
       D, [2017-03-21T10:05:46.904918 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:05:46.905968 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:05:46.906306 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:05:46.906543 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:05:46.906754 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T10:05:46.906813 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T10:05:46.907228 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:05:46 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0015
       D, [2017-03-21T10:05:46.948227 ] DEBUG -- : close: 15.162.41.254:41608
       D, [2017-03-21T10:05:47.099494 ] DEBUG -- : accept: 15.162.41.254:41610
       D, [2017-03-21T10:05:47.103647 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:05:47.105256 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:05:47.105624 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:05:47.105887 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T10:05:47.110786 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T10:05:47.111029 ] DEBUG -- : nil
       D, [2017-03-21T10:05:47.111145 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T10:05:47.111212 ] DEBUG -- : nil
       D, [2017-03-21T10:05:47.111287 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T10:05:47.111344 ] DEBUG -- : nil
       D, [2017-03-21T10:05:47.111412 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T10:05:47.111475 ] DEBUG -- : nil
       D, [2017-03-21T10:05:47.111737 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T10:05:47.111819 ] DEBUG -- : nil
       D, [2017-03-21T10:05:47.111939 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T10:05:47.112013 ] DEBUG -- : nil
       E, [2017-03-21T10:05:47.148061 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T10:05:47.148548 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T10:05:47.148635 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T10:05:47.149568 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:05:47 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0448
       D, [2017-03-21T10:05:47.194979 ] DEBUG -- : close: 15.162.41.254:41610
       D, [2017-03-21T10:18:51.384222 ] DEBUG -- : 'dns' settings: 'dns_ttl': 86400 (default), 'enabled': https, 'use_provider': dns_nsupdate (default)
       D, [2017-03-21T10:18:51.386922 ] DEBUG -- : 'tftp' settings: 'enabled': https, 'tftp_servername': 15.162.41.254, 'tftproot': /var/lib/tftpboot (default)
       D, [2017-03-21T10:18:51.391477 ] DEBUG -- : 'dhcp' settings: 'enabled': true, 'server': 15.162.33.7, 'subnets': [] (default), 'use_provider': dhcp_isc (default)
       D, [2017-03-21T10:18:51.393706 ] DEBUG -- : 'puppetca' settings: 'enabled': https, 'puppetdir': /etc/puppetlabs/puppet, 'ssldir': /etc/puppetlabs/puppet/ssl
       D, [2017-03-21T10:18:51.398026 ] DEBUG -- : 'puppet' settings: 'enabled': https, 'puppet_version': 4.9.4, 'use_provider': [:puppet_proxy_puppet_api]
       D, [2017-03-21T10:18:51.400572 ] DEBUG -- : 'logs' settings: 'enabled': https
       D, [2017-03-21T10:18:51.401031 ] DEBUG -- : Providers ['dns_nsupdate'] are going to be configured for 'dns'
       D, [2017-03-21T10:18:51.401134 ] DEBUG -- : Providers ['dhcp_isc'] are going to be configured for 'dhcp'
       D, [2017-03-21T10:18:51.401215 ] DEBUG -- : Providers ['puppet_proxy_puppet_api'] are going to be configured for 'puppet'
       D, [2017-03-21T10:18:51.404157 ] DEBUG -- : 'dns_nsupdate' settings: 'dns_key': /etc/rndc.key, 'dns_server': 127.0.0.1, 'dns_ttl': 86400, 'use_provider': dns_nsupdate
       D, [2017-03-21T10:18:51.417283 ] DEBUG -- : 'dhcp_isc' settings: 'config': /etc/dhcp/dhcpd.conf (default), 'key_name': omapi_key, 'key_secret': U6lPliIIXZ+tA+kOcIo51X18dCB0Bkse8ZZa3nncvmrwIBPk0NWMLqXEMH91JuVVR1lvbbESBzr4lB/+WDUGvA==, 'leases': /var/lib/dhcpd/dhcpd.leases (default), 'leases_file_observer': inotify_leases_file_observer, 'omapi_port': 7911, 'server': 15.162.33.7, 'subnets': [], 'use_provider': dhcp_isc
       D, [2017-03-21T10:18:51.492509 ] DEBUG -- : 'puppet_proxy_puppet_api' settings: 'api_timeout': 30 (default), 'classes_retriever': apiv3, 'environments_retriever': apiv3, 'puppet_ssl_ca': /etc/puppetlabs/puppet/ssl/certs/ca.pem, 'puppet_ssl_cert': /etc/puppetlabs/puppet/ssl/certs/set-chef-vm.3pardata.com.pem, 'puppet_ssl_key': /etc/puppetlabs/puppet/ssl/private_keys/set-chef-vm.3pardata.com.pem, 'puppet_url': https://set-chef-vm.3pardata.com:8140, 'puppet_version': 4.9.4, 'use_provider': [:puppet_proxy_puppet_api]
       I, [2017-03-21T10:18:51.493485 ]  INFO -- : Successfully initialized 'foreman_proxy'
       I, [2017-03-21T10:18:51.493596 ]  INFO -- : Successfully initialized 'dns_nsupdate'
       I, [2017-03-21T10:18:51.493663 ]  INFO -- : Successfully initialized 'dns'
       I, [2017-03-21T10:18:51.493719 ]  INFO -- : Successfully initialized 'tftp'
       D, [2017-03-21T10:18:51.512822 ] DEBUG -- : Added a subnet: 15.162.32.0
       I, [2017-03-21T10:18:51.513331 ]  INFO -- : Successfully initialized 'dhcp_isc'
       I, [2017-03-21T10:18:51.513653 ]  INFO -- : Successfully initialized 'dhcp'
       I, [2017-03-21T10:18:51.513744 ]  INFO -- : Successfully initialized 'puppetca'
       I, [2017-03-21T10:18:51.513882 ]  INFO -- : Started puppet class cache initialization
       I, [2017-03-21T10:18:51.522023 ]  INFO -- : Successfully initialized 'puppet_proxy_puppet_api'
       I, [2017-03-21T10:18:51.522163 ]  INFO -- : Successfully initialized 'puppet'
       D, [2017-03-21T10:18:51.522252 ] DEBUG -- : Log buffer API initialized, available capacity: 2000/1000
       I, [2017-03-21T10:18:51.522314 ]  INFO -- : Successfully initialized 'logs'
       I, [2017-03-21T10:18:51.532534 ]  INFO -- : WEBrick 1.3.1
       I, [2017-03-21T10:18:51.532643 ]  INFO -- : ruby 2.0.0 (2015-12-16) [x86_64-linux]
       D, [2017-03-21T10:18:51.532881 ] DEBUG -- : TCPServer.new(0.0.0.0, 8443)
       D, [2017-03-21T10:18:51.533007 ] DEBUG -- : TCPServer.new(::, 8443)
       W, [2017-03-21T10:18:51.533134 ]  WARN -- : TCPServer Error: Address already in use - bind(2)
       I, [2017-03-21T10:18:51.534147 ]  INFO -- :
       Certificate:
           Data:
               Version: 3 (0x2)
               Serial Number: 2 (0x2)
           Signature Algorithm: sha256WithRSAEncryption
               Issuer: CN=Puppet CA: set-chef-vm.3pardata.com
               Validity
                   Not Before: Mar 13 02:35:43 2017 GMT
                   Not After : Mar 13 02:35:43 2022 GMT
               Subject: CN=set-chef-vm.3pardata.com
               Subject Public Key Info:
                   Public Key Algorithm: rsaEncryption
                       Public-Key: (4096 bit)
                       Modulus:
 :ba:6f:6c:e2:eb:f2:e0:66:1e:16:94:7c:b5:32:
 a:20:ce:c5:1f:3b:ac:3a:55:b1:93:06:11:a6:a9:
 c:d7:25:c5:96:ab:a8:b6:67:53:11:4e:8a:b7:7f:
                           cc:88:6d:48:17:c4:06:df:74:17:b1:e8:18:4f:8f:
 :f3:26:b6:8f:d3:5d:1e:f9:25:d4:ab:1c:ea:14:
 :7c:55:57:3e:b3:c0:aa:4d:63:67:17:58:8b:56:
                           d0:60:76:7b:3f:31:7a:b5:8a:58:64:fe:05:43:7f:
 f:a6:67:1c:b3:2e:9b:72:1f:62:57:1a:21:8a:71:
                           cf:27:fd:c6:a2:2d:29:e3:3a:64:f9:a4:36:aa:79:
 a:32:c6:5e:1d:5c:7e:2a:bb:ed:a9:b9:37:0b:da:
                           a8:7f:4c:5d:82:90:9e:58:aa:c7:de:16:c5:85:47:
 :74:8c:2e:1a:a7:b7:e8:cd:af:ea:76:06:55:a6:
 :cb:91:d2:74:41:6b:9f:db:7b:85:a2:21:4a:2e:
 :51:b9:64:f3:ad:c4:a2:5b:4f:75:d0:e2:c0:da:
                           dd:23:9f:66:4e:9f:07:e4:0c:14:04:27:a2:69:da:
                           f6:bf:35:15:8a:53:91:59:de:21:d8:4c:bc:37:d5:
 c:55:c6:a4:1e:30:68:33:ff:9b:39:79:76:09:b2:
 :91:5a:08:a7:42:f7:c5:ee:aa:cd:13:f5:f3:d6:
                           c0:00:d6:8a:49:ba:47:70:3a:a5:00:d8:93:34:aa:
                           b9:6b:56:18:a3:f5:90:42:79:58:0c:a6:33:f0:dc:
 b:5f:f4:d1:56:05:16:92:20:2a:5e:80:79:ed:20:
 c:4d:07:b9:8d:cb:a7:ca:64:bf:2d:2f:48:2d:42:
 :35:0c:01:c3:56:c7:4a:0b:fa:55:27:8a:11:9a:
 :fa:f9:53:0d:49:aa:39:d1:90:10:09:f7:6f:64:
                           ec:30:6a:dd:a0:d3:85:68:27:36:34:96:33:eb:7e:
                           b6:f2:ff:b4:8d:77:11:16:12:c4:a5:37:95:34:a2:
 c:a1:01:d1:27:ea:79:6f:9a:e3:02:a3:56:fc:18:
 :c0:8c:1b:a9:0c:fe:12:1c:d0:08:cb:03:b5:9d:
 b:d6:ec:8b:82:fd:29:42:ab:a1:8c:b2:c0:c0:02:
 :9c:14:86:7d:92:9f:a6:4f:3d:e1:c5:2c:54:01:
 a:94:ee:53:47:62:e6:e8:b5:91:21:91:3c:4d:7f:
                           a3:b4:61:f2:37:90:f8:8f:96:ad:e3:75:cf:cd:2c:
 :e6:fc:a6:61:12:74:1e:04:bd:0f:5c:43:77:ec:
 :b8:23:38:dc:6a:03:c0:aa:b7:6e:a3:3d:68:18:
 :9e:bd
                       Exponent: 65537 (0x10001)
               X509v3 extensions:
                   Netscape Comment:
                       .(Puppet Ruby/OpenSSL Internal Certificate
                   X509v3 Subject Alternative Name:
                       DNS:puppet, DNS:puppet.3pardata.com, DNS:set-chef-vm.3pardata.com
                   X509v3 Key Usage: critical
                       Digital Signature, Key Encipherment
                   X509v3 Extended Key Usage: critical
                       TLS Web Server Authentication, TLS Web Client Authentication
                   X509v3 Basic Constraints: critical
                       CA:FALSE
                   X509v3 Subject Key Identifier:
                       C3:8D:BA:8B:C4:21:6B:09:42:64:E9:5A:9C:45:36:1C:C8:FB:08:23
                   X509v3 Authority Key Identifier:
                       keyid:7C:D8:BB:B9:DE:5F:58:60:4E:6A:FF:36:DE:91:A8:37:EF:89:61:37
           Signature Algorithm: sha256WithRSAEncryption
 e:0e:4a:33:60:67:c9:0e:f8:48:64:e1:ce:81:b4:f6:a4:f5:
                f0:3c:d2:d3:e7:11:4f:bc:a4:8c:bd:e7:90:20:6e:3f:d4:5f:
 e:7e:83:00:62:20:2f:13:be:26:0f:98:7e:38:fe:dc:88:d6:
                a2:07:04:02:3c:b5:96:7c:a8:68:3d:80:04:f7:b1:0d:7f:7d:
 e:0f:e5:ef:d8:0f:6a:15:6b:98:53:ea:f8:8d:15:69:10:5a:
 :33:39:49:a5:ef:89:c3:c9:65:52:0f:18:7b:3c:4e:19:5d:
                b5:2d:15:83:34:12:1d:a9:5a:7c:cf:d3:af:e7:71:97:a2:48:
                bc:66:87:e9:32:30:bc:dc:10:24:6e:52:bf:8c:1b:da:bb:35:
 a:7a:e3:e3:e1:ff:78:4a:01:ab:75:1d:d8:17:53:ba:67:14:
 :be:c9:53:bb:36:f8:89:96:e7:1e:bc:db:69:4a:fd:33:31:
 :f8:27:59:01:81:3b:6e:70:d6:58:59:8f:3e:79:56:66:1f:
 :75:49:30:2b:af:ac:0d:0a:ac:8a:1b:0d:ff:c5:aa:f5:50:
 b:a0:03:e2:80:be:5b:8b:20:1b:0d:1d:89:40:7e:46:c5:39:
 :57:99:89:ad:9f:0e:86:7f:33:ed:56:a7:0d:70:fb:6f:4c:
                c4:6d:74:22:2a:ed:66:15:b2:89:46:03:69:b8:a4:11:17:87:
 c:49:de:33:ba:d5:8a:7b:49:7a:96:2e:8a:85:28:b5:96:91:
 :83:0a:21:55:0b:90:a9:60:30:85:7f:cd:ef:7b:b8:f1:02:
 :ba:e5:55:28:c5:e0:9d:52:a8:7d:98:eb:81:38:5d:a7:2f:
 :00:0c:b8:b8:31:1e:7e:4a:66:ac:fa:21:25:26:68:e4:23:
 c:63:d3:8e:8f:80:61:1a:c0:c0:52:cd:30:4c:36:58:fd:97:
 e:88:65:81:6e:be:d8:56:03:39:e8:3a:8c:85:3c:80:30:6c:
                b0:ef:cb:00:f6:7f:da:ed:8b:58:75:38:2a:cc:f1:52:d2:0d:
                a2:29:3f:2d:b3:46:92:b7:fb:18:e5:21:cd:0b:2d:87:d7:ad:
 f:e6:81:e6:5a:13:b4:f5:fc:5f:5b:d6:05:df:78:db:1a:a3:
 :46:7d:bf:e9:9a:cc:c9:66:d7:ee:f1:64:09:89:b3:e9:74:
 c:9a:dd:85:91:63:92:c9:58:49:31:38:cf:53:5c:99:19:e8:
 :90:d8:45:60:cf:a8:84:c8:c4:a8:d4:f8:dd:54:9e:d7:34:
 :5a:4c:73:38:d1:f5:37:f0:63:ec:bb:a8:5a:82:84:e8:e3:
 :51:f0:ed:4d:a5:e3:1f
       D, [2017-03-21T10:18:51.536440 ] DEBUG -- : Rack::Handler::WEBrick is mounted on /.
       I, [2017-03-21T10:18:51.537962 ]  INFO -- : WEBrick::HTTPServer#start: pid=25984 port=8443
       D, [2017-03-21T10:18:51.677354 ] DEBUG -- : Initializing puppet class cache for 'production' environment
       D, [2017-03-21T10:18:51.678262 ] DEBUG -- : Initializing puppet class cache for 'common' environment
       D, [2017-03-21T10:18:51.678617 ] DEBUG -- : Initializing puppet class cache for 'development' environment
       I, [2017-03-21T10:18:52.016182 ]  INFO -- : Finished puppet class cache initialization
       D, [2017-03-21T10:19:08.941325 ] DEBUG -- : accept: 15.162.41.254:41632
       D, [2017-03-21T10:19:08.947499 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:08.960411 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:19:08.965891 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:08 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0058
       D, [2017-03-21T10:19:09.007383 ] DEBUG -- : close: 15.162.41.254:41632
       D, [2017-03-21T10:19:09.074990 ] DEBUG -- : accept: 15.162.41.254:41634
       D, [2017-03-21T10:19:09.079106 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:09.083108 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:19:09.083547 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:19:09.083989 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:19:09.084547 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T10:19:09.084639 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T10:19:09.085119 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:09 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0023
       D, [2017-03-21T10:19:09.126217 ] DEBUG -- : close: 15.162.41.254:41634
       D, [2017-03-21T10:19:09.183616 ] DEBUG -- : accept: 15.162.41.254:41636
       D, [2017-03-21T10:19:09.187651 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:09.188623 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:19:09.188987 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:19:09.189271 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:19:09.189499 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T10:19:09.189556 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T10:19:09.189951 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:09 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0015
       D, [2017-03-21T10:19:09.237591 ] DEBUG -- : close: 15.162.41.254:41636
       D, [2017-03-21T10:19:18.055969 ] DEBUG -- : accept: 15.162.41.254:41638
       D, [2017-03-21T10:19:18.060456 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:18.061435 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:19:18.062102 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:18 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0009
       D, [2017-03-21T10:19:18.106270 ] DEBUG -- : close: 15.162.41.254:41638
       D, [2017-03-21T10:19:18.167001 ] DEBUG -- : accept: 15.162.41.254:41640
       D, [2017-03-21T10:19:18.170696 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:18.171765 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:19:18.172154 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:19:18.172459 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:19:18.172682 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T10:19:18.172736 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T10:19:18.173152 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:18 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0016
       D, [2017-03-21T10:19:18.214770 ] DEBUG -- : close: 15.162.41.254:41640
       D, [2017-03-21T10:19:18.271737 ] DEBUG -- : accept: 15.162.41.254:41642
       D, [2017-03-21T10:19:18.275912 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:18.276965 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:19:18.277329 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:19:18.277595 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:19:18.277820 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T10:19:18.277906 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T10:19:18.278328 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:18 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0016
       D, [2017-03-21T10:19:18.328916 ] DEBUG -- : close: 15.162.41.254:41642
       D, [2017-03-21T10:19:18.469416 ] DEBUG -- : accept: 15.162.41.254:41644
       D, [2017-03-21T10:19:18.474043 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:18.478210 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:19:18.478683 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:19:18.478987 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T10:19:18.485315 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T10:19:18.485472 ] DEBUG -- : nil
       D, [2017-03-21T10:19:18.485574 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T10:19:18.485651 ] DEBUG -- : nil
       D, [2017-03-21T10:19:18.485727 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T10:19:18.485786 ] DEBUG -- : nil
       D, [2017-03-21T10:19:18.485853 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T10:19:18.485950 ] DEBUG -- : nil
       D, [2017-03-21T10:19:18.486237 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T10:19:18.486330 ] DEBUG -- : nil
       D, [2017-03-21T10:19:18.486405 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T10:19:18.486463 ] DEBUG -- : nil
       E, [2017-03-21T10:19:18.518734 ] ERROR -- : Omshell failed:
       > > > > dhcpctl_connect: no more
       , > not connected.
       , > no open object.
       , > no open object.
       , > no open object.
       , > no open object.
       , > no open object.
       , > not connected.
       , >
       E, [2017-03-21T10:19:18.519317 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): No response from DHCP server
       D, [2017-03-21T10:19:18.519397 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): No response from DHCP server (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T10:19:18.520381 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:18 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 126 0.0452
       D, [2017-03-21T10:19:18.564906 ] DEBUG -- : close: 15.162.41.254:41644
       D, [2017-03-21T10:19:51.025769 ] DEBUG -- : accept: 15.162.41.254:41648
       D, [2017-03-21T10:19:51.030603 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       I, [2017-03-21T10:19:51.035912 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:51 -0700] "GET /version HTTP/1.1" 200 133 0.0010
       D, [2017-03-21T10:19:51.077313 ] DEBUG -- : close: 15.162.41.254:41648
       D, [2017-03-21T10:19:57.595966 ] DEBUG -- : accept: 15.162.41.254:41650
       D, [2017-03-21T10:19:57.610025 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:57.611388 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:19:57.612070 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:57 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0009
       D, [2017-03-21T10:19:57.655902 ] DEBUG -- : close: 15.162.41.254:41650
       D, [2017-03-21T10:19:57.825940 ] DEBUG -- : accept: 15.162.41.254:41652
       D, [2017-03-21T10:19:57.844670 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:57.847388 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:19:57.849920 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:57 -0700] "GET /logs/?from_timestamp=0 HTTP/1.1" 200 23576 0.0028
       D, [2017-03-21T10:19:57.922941 ] DEBUG -- : close: 15.162.41.254:41652
       D, [2017-03-21T10:19:58.241928 ] DEBUG -- : accept: 15.162.41.254:41654
       D, [2017-03-21T10:19:58.264826 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:58.267602 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:19:58.268317 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:58 -0700] "GET /puppet/ca/autosign HTTP/1.1" 200 2 0.0009
       D, [2017-03-21T10:19:58.320075 ] DEBUG -- : close: 15.162.41.254:41654
       D, [2017-03-21T10:19:58.558258 ] DEBUG -- : accept: 15.162.41.254:41656
       D, [2017-03-21T10:19:58.562072 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:58.562722 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:19:58.563141 ] DEBUG -- : Found puppetca at /opt/puppetlabs/bin/puppet
       D, [2017-03-21T10:19:58.563254 ] DEBUG -- : Found sudo at /usr/bin/sudo
       D, [2017-03-21T10:19:58.563321 ] DEBUG -- : Executing /usr/bin/sudo -S /opt/puppetlabs/bin/puppet cert --ssldir /etc/puppetlabs/puppet/ssl --list --all
       D, [2017-03-21T10:19:58.609131 ] DEBUG -- : accept: 15.162.41.254:41658
       D, [2017-03-21T10:19:58.627399 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:58.628248 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:19:58.628640 ] DEBUG -- : Found puppetca at /opt/puppetlabs/bin/puppet
       D, [2017-03-21T10:19:58.628740 ] DEBUG -- : Found sudo at /usr/bin/sudo
       D, [2017-03-21T10:19:58.628793 ] DEBUG -- : Executing /usr/bin/sudo -S /opt/puppetlabs/bin/puppet cert --ssldir /etc/puppetlabs/puppet/ssl --list --all
       D, [2017-03-21T10:19:58.652767 ] DEBUG -- : accept: 15.162.41.254:41660
       D, [2017-03-21T10:19:58.673629 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:58.675905 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:19:58.990943 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:58 -0700] "GET /puppet/environments HTTP/1.1" 200 37 0.3152
       D, [2017-03-21T10:19:59.045128 ] DEBUG -- : close: 15.162.41.254:41660
       D, [2017-03-21T10:19:59.159977 ] DEBUG -- : accept: 15.162.41.254:41664
       D, [2017-03-21T10:19:59.182615 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:59.183377 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       W, [2017-03-21T10:19:59.209494 ]  WARN -- : Puppet server classes cache is disabled, classes retrieval can be slow.
       I, [2017-03-21T10:19:59.477659 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:59 -0700] "GET /puppet/environments/production/classes HTTP/1.1" 200 2 0.2944
       D, [2017-03-21T10:19:59.523225 ] DEBUG -- : close: 15.162.41.254:41664
       D, [2017-03-21T10:19:59.625741 ] DEBUG -- : accept: 15.162.41.254:41668
       D, [2017-03-21T10:19:59.635487 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:59.636216 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       W, [2017-03-21T10:19:59.645915 ]  WARN -- : Puppet server classes cache is disabled, classes retrieval can be slow.
       I, [2017-03-21T10:19:59.878335 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:19:59 -0700] "GET /puppet/environments/common/classes HTTP/1.1" 200 2 0.2422
       D, [2017-03-21T10:19:59.902911 ] DEBUG -- : accept: 15.162.41.254:41672
       D, [2017-03-21T10:19:59.917502 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:19:59.918212 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:19:59.918592 ] DEBUG -- : Found puppetca at /opt/puppetlabs/bin/puppet
       D, [2017-03-21T10:19:59.918685 ] DEBUG -- : Found sudo at /usr/bin/sudo
       D, [2017-03-21T10:19:59.918733 ] DEBUG -- : Executing /usr/bin/sudo -S /opt/puppetlabs/bin/puppet cert --ssldir /etc/puppetlabs/puppet/ssl --list --all
       D, [2017-03-21T10:19:59.939484 ] DEBUG -- : close: 15.162.41.254:41668
       D, [2017-03-21T10:20:00.023931 ] DEBUG -- : accept: 15.162.41.254:41674
       D, [2017-03-21T10:20:00.038683 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:20:00.039578 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       W, [2017-03-21T10:20:00.050983 ]  WARN -- : Puppet server classes cache is disabled, classes retrieval can be slow.
       I, [2017-03-21T10:20:00.290298 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:20:00 -0700] "GET /puppet/environments/development/classes HTTP/1.1" 200 2 0.2509
       D, [2017-03-21T10:20:00.340378 ] DEBUG -- : close: 15.162.41.254:41674
       I, [2017-03-21T10:20:02.012058 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:20:02 -0700] "GET /puppet/ca HTTP/1.1" 200 252 3.3840
       D, [2017-03-21T10:20:02.071135 ] DEBUG -- : close: 15.162.41.254:41658
       I, [2017-03-21T10:20:02.082589 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:20:02 -0700] "GET /puppet/ca HTTP/1.1" 200 252 2.1645
       D, [2017-03-21T10:20:02.134089 ] DEBUG -- : close: 15.162.41.254:41672
       I, [2017-03-21T10:20:02.343245 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:20:02 -0700] "GET /puppet/ca HTTP/1.1" 200 252 3.7806
       D, [2017-03-21T10:20:02.384207 ] DEBUG -- : close: 15.162.41.254:41656
       D, [2017-03-21T10:28:29.537378 ] DEBUG -- : accept: 15.162.41.254:41678
       D, [2017-03-21T10:28:29.541592 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       I, [2017-03-21T10:28:29.543636 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:29 -0700] "GET /version HTTP/1.1" 200 133 0.0010
       D, [2017-03-21T10:28:29.590430 ] DEBUG -- : close: 15.162.41.254:41678
       D, [2017-03-21T10:28:34.564709 ] DEBUG -- : accept: 15.162.41.254:41680
       D, [2017-03-21T10:28:34.569154 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:34.570291 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:28:34.571099 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:34 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0010
       D, [2017-03-21T10:28:34.613816 ] DEBUG -- : close: 15.162.41.254:41680
       D, [2017-03-21T10:28:34.701835 ] DEBUG -- : accept: 15.162.41.254:41682
       D, [2017-03-21T10:28:34.705659 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:34.706833 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:28:34.711571 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:34 -0700] "GET /logs/?from_timestamp=0 HTTP/1.1" 200 31354 0.0050
       D, [2017-03-21T10:28:34.763123 ] DEBUG -- : close: 15.162.41.254:41682
       D, [2017-03-21T10:28:34.884710 ] DEBUG -- : accept: 15.162.41.254:41684
       D, [2017-03-21T10:28:34.888653 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:34.889718 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:28:35.063959 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:35 -0700] "GET /puppet/environments HTTP/1.1" 200 37 0.1744
       D, [2017-03-21T10:28:35.105319 ] DEBUG -- : close: 15.162.41.254:41684
       D, [2017-03-21T10:28:35.146426 ] DEBUG -- : accept: 15.162.41.254:41688
       D, [2017-03-21T10:28:35.149824 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:35.150633 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       W, [2017-03-21T10:28:35.151989 ]  WARN -- : Puppet server classes cache is disabled, classes retrieval can be slow.
       I, [2017-03-21T10:28:35.279067 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:35 -0700] "GET /puppet/environments/production/classes HTTP/1.1" 200 2 0.1286
       D, [2017-03-21T10:28:35.320300 ] DEBUG -- : close: 15.162.41.254:41688
       D, [2017-03-21T10:28:35.365649 ] DEBUG -- : accept: 15.162.41.254:41692
       D, [2017-03-21T10:28:35.369097 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:35.369989 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       W, [2017-03-21T10:28:35.370729 ]  WARN -- : Puppet server classes cache is disabled, classes retrieval can be slow.
       I, [2017-03-21T10:28:35.547338 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:35 -0700] "GET /puppet/environments/common/classes HTTP/1.1" 200 2 0.1775
       D, [2017-03-21T10:28:35.588423 ] DEBUG -- : close: 15.162.41.254:41692
       D, [2017-03-21T10:28:35.628849 ] DEBUG -- : accept: 15.162.41.254:41696
       D, [2017-03-21T10:28:35.632535 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:35.633367 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       W, [2017-03-21T10:28:35.634059 ]  WARN -- : Puppet server classes cache is disabled, classes retrieval can be slow.
       I, [2017-03-21T10:28:35.773718 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:35 -0700] "GET /puppet/environments/development/classes HTTP/1.1" 200 2 0.1405
       D, [2017-03-21T10:28:35.824513 ] DEBUG -- : close: 15.162.41.254:41696
       D, [2017-03-21T10:28:35.929188 ] DEBUG -- : accept: 15.162.41.254:41700
       D, [2017-03-21T10:28:35.933363 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:35.934368 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:28:35.934986 ] DEBUG -- : Found puppetca at /opt/puppetlabs/bin/puppet
       D, [2017-03-21T10:28:35.935161 ] DEBUG -- : Found sudo at /usr/bin/sudo
       D, [2017-03-21T10:28:35.935246 ] DEBUG -- : Executing /usr/bin/sudo -S /opt/puppetlabs/bin/puppet cert --ssldir /etc/puppetlabs/puppet/ssl --list --all
       I, [2017-03-21T10:28:38.130898 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:38 -0700] "GET /puppet/ca HTTP/1.1" 200 252 2.1967
       D, [2017-03-21T10:28:38.183123 ] DEBUG -- : close: 15.162.41.254:41700
       D, [2017-03-21T10:28:38.446912 ] DEBUG -- : accept: 15.162.41.254:41702
       D, [2017-03-21T10:28:38.469689 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:38.470466 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:28:38.471044 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:38 -0700] "GET /puppet/ca/autosign HTTP/1.1" 200 2 0.0008
       D, [2017-03-21T10:28:38.523054 ] DEBUG -- : accept: 15.162.41.254:41704
       D, [2017-03-21T10:28:38.523402 ] DEBUG -- : close: 15.162.41.254:41702
       D, [2017-03-21T10:28:38.532225 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:38.532877 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:28:38.533381 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:38 -0700] "GET /puppet/ca/autosign HTTP/1.1" 200 2 0.0006
       D, [2017-03-21T10:28:38.575052 ] DEBUG -- : close: 15.162.41.254:41704
       D, [2017-03-21T10:28:43.938685 ] DEBUG -- : accept: 15.162.41.254:41706
       D, [2017-03-21T10:28:43.942699 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:43.944039 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:28:43.944572 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:28:43.944982 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:28:43.945684 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:43 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0019
       D, [2017-03-21T10:28:43.987188 ] DEBUG -- : close: 15.162.41.254:41706
       D, [2017-03-21T10:28:44.240788 ] DEBUG -- : accept: 15.162.41.254:41708
       D, [2017-03-21T10:28:44.244721 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:44.245965 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:28:44.246429 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:28:44.246702 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:28:44.247639 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:44 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0019
       D, [2017-03-21T10:28:44.289231 ] DEBUG -- : close: 15.162.41.254:41708
       D, [2017-03-21T10:28:48.660766 ] DEBUG -- : accept: 15.162.41.254:41710
       D, [2017-03-21T10:28:48.665573 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:48.666834 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:28:48.667301 ] DEBUG -- : Loading subnets for 15.162.33.7
       I, [2017-03-21T10:28:48.668212 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:48 -0700] "GET /dhcp/15.162.32.0/ HTTP/1.1" 404 27 0.0016
       D, [2017-03-21T10:28:48.709213 ] DEBUG -- : close: 15.162.41.254:41710
       D, [2017-03-21T10:28:48.766741 ] DEBUG -- : accept: 15.162.41.254:41712
       D, [2017-03-21T10:28:48.771263 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:48.772281 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:28:48.772684 ] DEBUG -- : Loading subnets for 15.162.33.7
       I, [2017-03-21T10:28:48.773229 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:48 -0700] "GET /dhcp/15.162.32.0/ HTTP/1.1" 404 27 0.0011
       D, [2017-03-21T10:28:48.813552 ] DEBUG -- : close: 15.162.41.254:41712
       D, [2017-03-21T10:28:48.876841 ] DEBUG -- : accept: 15.162.41.254:41714
       D, [2017-03-21T10:28:48.880842 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:48.881940 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:28:48.882330 ] DEBUG -- : Loading subnets for 15.162.33.7
       I, [2017-03-21T10:28:48.882889 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:48 -0700] "GET /dhcp/15.162.32.0/ HTTP/1.1" 404 27 0.0011
       D, [2017-03-21T10:28:48.924201 ] DEBUG -- : close: 15.162.41.254:41714
       D, [2017-03-21T10:28:48.986203 ] DEBUG -- : accept: 15.162.41.254:41716
       D, [2017-03-21T10:28:48.990484 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:28:48.991536 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:28:48.991944 ] DEBUG -- : Loading subnets for 15.162.33.7
       I, [2017-03-21T10:28:48.992538 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:28:48 -0700] "GET /dhcp/15.162.32.0/ HTTP/1.1" 404 27 0.0011
       D, [2017-03-21T10:28:49.034198 ] DEBUG -- : close: 15.162.41.254:41716
       D, [2017-03-21T10:37:47.512877 ] DEBUG -- : accept: 15.162.41.254:41718
       D, [2017-03-21T10:37:47.517173 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       I, [2017-03-21T10:37:47.519245 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:37:47 -0700] "GET /features HTTP/1.1" 200 48 0.0010
       D, [2017-03-21T10:37:47.560333 ] DEBUG -- : close: 15.162.41.254:41718
       D, [2017-03-21T10:37:47.638025 ] DEBUG -- : accept: 15.162.41.254:41720
       D, [2017-03-21T10:37:47.642355 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       I, [2017-03-21T10:37:47.643850 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:37:47 -0700] "GET /features HTTP/1.1" 200 48 0.0006
       D, [2017-03-21T10:37:47.685192 ] DEBUG -- : close: 15.162.41.254:41720
       D, [2017-03-21T10:37:47.939534 ] DEBUG -- : accept: 15.162.41.254:41722
       D, [2017-03-21T10:37:47.943436 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:37:47.945837 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:37:47.946975 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub2/grub.cfg created successfully
       I, [2017-03-21T10:37:47.947496 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:37:47 -0700] "POST /tftp/PXEGrub2/create_default HTTP/1.1" 200 - 0.0029
       D, [2017-03-21T10:37:47.948609 ] DEBUG -- : close: 15.162.41.254:41722
       D, [2017-03-21T10:37:48.050824 ] DEBUG -- : accept: 15.162.41.254:41724
       D, [2017-03-21T10:37:48.054743 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:37:48.056377 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:37:48.057155 ] DEBUG -- : TFTP: /var/lib/tftpboot/pxelinux.cfg/default created successfully
       I, [2017-03-21T10:37:48.057706 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:37:48 -0700] "POST /tftp/PXELinux/create_default HTTP/1.1" 200 - 0.0019
       D, [2017-03-21T10:37:48.058802 ] DEBUG -- : close: 15.162.41.254:41724
       D, [2017-03-21T10:37:48.166073 ] DEBUG -- : accept: 15.162.41.254:41726
       D, [2017-03-21T10:37:48.170172 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:37:48.171908 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:37:48.172608 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub/menu.lst created successfully
       D, [2017-03-21T10:37:48.173029 ] DEBUG -- : TFTP: /var/lib/tftpboot/grub/efidefault created successfully
       I, [2017-03-21T10:37:48.173420 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:37:48 -0700] "POST /tftp/PXEGrub/create_default HTTP/1.1" 200 - 0.0022
       D, [2017-03-21T10:37:48.174514 ] DEBUG -- : close: 15.162.41.254:41726
       D, [2017-03-21T10:39:03.757215 ] DEBUG -- : accept: 15.162.41.254:41728
       D, [2017-03-21T10:39:03.761496 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:39:03.762625 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:39:03.763465 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:39:03 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0010
       D, [2017-03-21T10:39:03.804248 ] DEBUG -- : close: 15.162.41.254:41728
       D, [2017-03-21T10:39:03.869513 ] DEBUG -- : accept: 15.162.41.254:41730
       D, [2017-03-21T10:39:03.873779 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:39:03.874762 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:39:03.875205 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:39:03.875528 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:39:03.877220 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T10:39:03.877292 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T10:39:03.877680 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:39:03 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0031
       D, [2017-03-21T10:39:03.919230 ] DEBUG -- : close: 15.162.41.254:41730
       D, [2017-03-21T10:39:03.976172 ] DEBUG -- : accept: 15.162.41.254:41732
       D, [2017-03-21T10:39:03.979939 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:39:03.980967 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:39:03.981315 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:39:03.981550 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:39:03.981763 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T10:39:03.981835 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T10:39:03.982248 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:39:03 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0015
       D, [2017-03-21T10:39:04.023046 ] DEBUG -- : close: 15.162.41.254:41732
       D, [2017-03-21T10:39:04.262756 ] DEBUG -- : accept: 15.162.41.254:41734
       D, [2017-03-21T10:39:04.266479 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:39:04.267799 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:39:04.268120 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:39:04.268292 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T10:39:04.272408 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T10:39:04.272599 ] DEBUG -- : nil
       D, [2017-03-21T10:39:04.272708 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T10:39:04.272775 ] DEBUG -- : nil
       D, [2017-03-21T10:39:04.272879 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T10:39:04.272963 ] DEBUG -- : nil
       D, [2017-03-21T10:39:04.273041 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T10:39:04.273101 ] DEBUG -- : nil
       D, [2017-03-21T10:39:04.273362 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T10:39:04.273451 ] DEBUG -- : nil
       D, [2017-03-21T10:39:04.273528 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T10:39:04.273588 ] DEBUG -- : nil
       E, [2017-03-21T10:39:04.309277 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T10:39:04.310220 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T10:39:04.310308 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T10:39:04.311202 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:39:04 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0439
       D, [2017-03-21T10:39:04.352214 ] DEBUG -- : close: 15.162.41.254:41734
       D, [2017-03-21T10:44:00.146721 ] DEBUG -- : accept: 15.162.41.254:41738
       D, [2017-03-21T10:44:00.151962 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:44:00.153724 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:44:00.154278 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:44:00.154667 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:44:00.155301 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:44:00 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0020
       D, [2017-03-21T10:44:00.195656 ] DEBUG -- : close: 15.162.41.254:41738
       D, [2017-03-21T10:44:00.454134 ] DEBUG -- : accept: 15.162.41.254:41740
       D, [2017-03-21T10:44:00.458131 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:44:00.459317 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:44:00.459692 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:44:00.459961 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:44:00.460557 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:44:00 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0014
       D, [2017-03-21T10:44:00.502224 ] DEBUG -- : close: 15.162.41.254:41740
       D, [2017-03-21T10:44:08.647163 ] DEBUG -- : accept: 15.162.41.254:41742
       D, [2017-03-21T10:44:08.651698 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:44:08.652901 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:44:08.653303 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:44:08.653541 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:44:08.654136 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:44:08 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0015
       D, [2017-03-21T10:44:08.694558 ] DEBUG -- : close: 15.162.41.254:41742
       D, [2017-03-21T10:44:08.940577 ] DEBUG -- : accept: 15.162.41.254:41744
       D, [2017-03-21T10:44:08.944269 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:44:08.945367 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:44:08.945725 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:44:08.945971 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:44:08.946533 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:44:08 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0013
       D, [2017-03-21T10:44:08.987526 ] DEBUG -- : close: 15.162.41.254:41744
       D, [2017-03-21T10:44:15.796752 ] DEBUG -- : accept: 15.162.41.254:41746
       D, [2017-03-21T10:44:15.800723 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:44:15.801932 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:44:15.802310 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:44:15.802536 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:44:15.803099 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:44:15 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0014
       D, [2017-03-21T10:44:15.844247 ] DEBUG -- : close: 15.162.41.254:41746
       D, [2017-03-21T10:44:16.098772 ] DEBUG -- : accept: 15.162.41.254:41748
       D, [2017-03-21T10:44:16.102946 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:44:16.104011 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:44:16.104375 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:44:16.104620 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:44:16.105171 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:44:16 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0014
       D, [2017-03-21T10:44:16.146244 ] DEBUG -- : close: 15.162.41.254:41748
       I, [2017-03-21T10:49:08.769184 ]  INFO -- : going to shutdown ...
       I, [2017-03-21T10:49:08.769453 ]  INFO -- : WEBrick::HTTPServer#start done.
       D, [2017-03-21T10:49:09.300819 ] DEBUG -- : 'dns' settings: 'dns_ttl': 86400 (default), 'enabled': https, 'use_provider': dns_nsupdate (default)
       D, [2017-03-21T10:49:09.303431 ] DEBUG -- : 'tftp' settings: 'enabled': https, 'tftp_servername': 15.162.41.254, 'tftproot': /var/lib/tftpboot (default)
       D, [2017-03-21T10:49:09.307940 ] DEBUG -- : 'dhcp' settings: 'enabled': true, 'server': 15.162.33.7, 'subnets': [] (default), 'use_provider': dhcp_isc (default)
       D, [2017-03-21T10:49:09.310217 ] DEBUG -- : 'puppetca' settings: 'enabled': https, 'puppetdir': /etc/puppetlabs/puppet, 'ssldir': /etc/puppetlabs/puppet/ssl
       D, [2017-03-21T10:49:09.314460 ] DEBUG -- : 'puppet' settings: 'enabled': https, 'puppet_version': 4.9.4, 'use_provider': [:puppet_proxy_puppet_api]
       D, [2017-03-21T10:49:09.317013 ] DEBUG -- : 'logs' settings: 'enabled': https
       D, [2017-03-21T10:49:09.317431 ] DEBUG -- : Providers ['dns_nsupdate'] are going to be configured for 'dns'
       D, [2017-03-21T10:49:09.317521 ] DEBUG -- : Providers ['dhcp_isc'] are going to be configured for 'dhcp'
       D, [2017-03-21T10:49:09.317592 ] DEBUG -- : Providers ['puppet_proxy_puppet_api'] are going to be configured for 'puppet'
       D, [2017-03-21T10:49:09.320626 ] DEBUG -- : 'dns_nsupdate' settings: 'dns_key': /etc/rndc.key, 'dns_server': 127.0.0.1, 'dns_ttl': 86400, 'use_provider': dns_nsupdate
       D, [2017-03-21T10:49:09.333596 ] DEBUG -- : 'dhcp_isc' settings: 'config': /etc/dhcp/dhcpd.conf (default), 'key_name': omapi_key, 'key_secret': U6lPliIIXZ+tA+kOcIo51X18dCB0Bkse8ZZa3nncvmrwIBPk0NWMLqXEMH91JuVVR1lvbbESBzr4lB/+WDUGvA==, 'leases': /var/lib/dhcpd/dhcpd.leases (default), 'leases_file_observer': inotify_leases_file_observer, 'omapi_port': 7911, 'server': 15.162.33.7, 'subnets': [], 'use_provider': dhcp_isc
       D, [2017-03-21T10:49:09.407233 ] DEBUG -- : 'puppet_proxy_puppet_api' settings: 'api_timeout': 30 (default), 'classes_retriever': apiv3, 'environments_retriever': apiv3, 'puppet_ssl_ca': /etc/puppetlabs/puppet/ssl/certs/ca.pem, 'puppet_ssl_cert': /etc/puppetlabs/puppet/ssl/certs/set-chef-vm.3pardata.com.pem, 'puppet_ssl_key': /etc/puppetlabs/puppet/ssl/private_keys/set-chef-vm.3pardata.com.pem, 'puppet_url': https://set-chef-vm.3pardata.com:8140, 'puppet_version': 4.9.4, 'use_provider': [:puppet_proxy_puppet_api]
       I, [2017-03-21T10:49:09.408158 ]  INFO -- : Successfully initialized 'foreman_proxy'
       I, [2017-03-21T10:49:09.408268 ]  INFO -- : Successfully initialized 'dns_nsupdate'
       I, [2017-03-21T10:49:09.408328 ]  INFO -- : Successfully initialized 'dns'
       I, [2017-03-21T10:49:09.408380 ]  INFO -- : Successfully initialized 'tftp'
       D, [2017-03-21T10:49:09.426777 ] DEBUG -- : Added a subnet: 15.162.32.0
       I, [2017-03-21T10:49:09.427270 ]  INFO -- : Successfully initialized 'dhcp_isc'
       I, [2017-03-21T10:49:09.427717 ]  INFO -- : Successfully initialized 'dhcp'
       I, [2017-03-21T10:49:09.427789 ]  INFO -- : Successfully initialized 'puppetca'
       I, [2017-03-21T10:49:09.427914 ]  INFO -- : Started puppet class cache initialization
       I, [2017-03-21T10:49:09.435973 ]  INFO -- : Successfully initialized 'puppet_proxy_puppet_api'
       I, [2017-03-21T10:49:09.436114 ]  INFO -- : Successfully initialized 'puppet'
       D, [2017-03-21T10:49:09.436202 ] DEBUG -- : Log buffer API initialized, available capacity: 2000/1000
       I, [2017-03-21T10:49:09.436261 ]  INFO -- : Successfully initialized 'logs'
       I, [2017-03-21T10:49:09.449945 ]  INFO -- : WEBrick 1.3.1
       I, [2017-03-21T10:49:09.450059 ]  INFO -- : ruby 2.0.0 (2015-12-16) [x86_64-linux]
       D, [2017-03-21T10:49:09.450273 ] DEBUG -- : TCPServer.new(0.0.0.0, 8443)
       D, [2017-03-21T10:49:09.450385 ] DEBUG -- : TCPServer.new(::, 8443)
       W, [2017-03-21T10:49:09.450490 ]  WARN -- : TCPServer Error: Address already in use - bind(2)
       I, [2017-03-21T10:49:09.451325 ]  INFO -- :
       Certificate:
           Data:
               Version: 3 (0x2)
               Serial Number: 2 (0x2)
           Signature Algorithm: sha256WithRSAEncryption
               Issuer: CN=Puppet CA: set-chef-vm.3pardata.com
               Validity
                   Not Before: Mar 13 02:35:43 2017 GMT
                   Not After : Mar 13 02:35:43 2022 GMT
               Subject: CN=set-chef-vm.3pardata.com
               Subject Public Key Info:
                   Public Key Algorithm: rsaEncryption
                       Public-Key: (4096 bit)
                       Modulus:
 :ba:6f:6c:e2:eb:f2:e0:66:1e:16:94:7c:b5:32:
 a:20:ce:c5:1f:3b:ac:3a:55:b1:93:06:11:a6:a9:
 c:d7:25:c5:96:ab:a8:b6:67:53:11:4e:8a:b7:7f:
                           cc:88:6d:48:17:c4:06:df:74:17:b1:e8:18:4f:8f:
 :f3:26:b6:8f:d3:5d:1e:f9:25:d4:ab:1c:ea:14:
 :7c:55:57:3e:b3:c0:aa:4d:63:67:17:58:8b:56:
                           d0:60:76:7b:3f:31:7a:b5:8a:58:64:fe:05:43:7f:
 f:a6:67:1c:b3:2e:9b:72:1f:62:57:1a:21:8a:71:
                           cf:27:fd:c6:a2:2d:29:e3:3a:64:f9:a4:36:aa:79:
 a:32:c6:5e:1d:5c:7e:2a:bb:ed:a9:b9:37:0b:da:
                           a8:7f:4c:5d:82:90:9e:58:aa:c7:de:16:c5:85:47:
 :74:8c:2e:1a:a7:b7:e8:cd:af:ea:76:06:55:a6:
 :cb:91:d2:74:41:6b:9f:db:7b:85:a2:21:4a:2e:
 :51:b9:64:f3:ad:c4:a2:5b:4f:75:d0:e2:c0:da:
                           dd:23:9f:66:4e:9f:07:e4:0c:14:04:27:a2:69:da:
                           f6:bf:35:15:8a:53:91:59:de:21:d8:4c:bc:37:d5:
 c:55:c6:a4:1e:30:68:33:ff:9b:39:79:76:09:b2:
 :91:5a:08:a7:42:f7:c5:ee:aa:cd:13:f5:f3:d6:
                           c0:00:d6:8a:49:ba:47:70:3a:a5:00:d8:93:34:aa:
                           b9:6b:56:18:a3:f5:90:42:79:58:0c:a6:33:f0:dc:
 b:5f:f4:d1:56:05:16:92:20:2a:5e:80:79:ed:20:
 c:4d:07:b9:8d:cb:a7:ca:64:bf:2d:2f:48:2d:42:
 :35:0c:01:c3:56:c7:4a:0b:fa:55:27:8a:11:9a:
 :fa:f9:53:0d:49:aa:39:d1:90:10:09:f7:6f:64:
                           ec:30:6a:dd:a0:d3:85:68:27:36:34:96:33:eb:7e:
                           b6:f2:ff:b4:8d:77:11:16:12:c4:a5:37:95:34:a2:
 c:a1:01:d1:27:ea:79:6f:9a:e3:02:a3:56:fc:18:
 :c0:8c:1b:a9:0c:fe:12:1c:d0:08:cb:03:b5:9d:
 b:d6:ec:8b:82:fd:29:42:ab:a1:8c:b2:c0:c0:02:
 :9c:14:86:7d:92:9f:a6:4f:3d:e1:c5:2c:54:01:
 a:94:ee:53:47:62:e6:e8:b5:91:21:91:3c:4d:7f:
                           a3:b4:61:f2:37:90:f8:8f:96:ad:e3:75:cf:cd:2c:
 :e6:fc:a6:61:12:74:1e:04:bd:0f:5c:43:77:ec:
 :b8:23:38:dc:6a:03:c0:aa:b7:6e:a3:3d:68:18:
 :9e:bd
                       Exponent: 65537 (0x10001)
               X509v3 extensions:
                   Netscape Comment:
                       .(Puppet Ruby/OpenSSL Internal Certificate
                   X509v3 Subject Alternative Name:
                       DNS:puppet, DNS:puppet.3pardata.com, DNS:set-chef-vm.3pardata.com
                   X509v3 Key Usage: critical
                       Digital Signature, Key Encipherment
                   X509v3 Extended Key Usage: critical
                       TLS Web Server Authentication, TLS Web Client Authentication
                   X509v3 Basic Constraints: critical
                       CA:FALSE
                   X509v3 Subject Key Identifier:
                       C3:8D:BA:8B:C4:21:6B:09:42:64:E9:5A:9C:45:36:1C:C8:FB:08:23
                   X509v3 Authority Key Identifier:
                       keyid:7C:D8:BB:B9:DE:5F:58:60:4E:6A:FF:36:DE:91:A8:37:EF:89:61:37
           Signature Algorithm: sha256WithRSAEncryption
 e:0e:4a:33:60:67:c9:0e:f8:48:64:e1:ce:81:b4:f6:a4:f5:
                f0:3c:d2:d3:e7:11:4f:bc:a4:8c:bd:e7:90:20:6e:3f:d4:5f:
 e:7e:83:00:62:20:2f:13:be:26:0f:98:7e:38:fe:dc:88:d6:
                a2:07:04:02:3c:b5:96:7c:a8:68:3d:80:04:f7:b1:0d:7f:7d:
 e:0f:e5:ef:d8:0f:6a:15:6b:98:53:ea:f8:8d:15:69:10:5a:
 :33:39:49:a5:ef:89:c3:c9:65:52:0f:18:7b:3c:4e:19:5d:
                b5:2d:15:83:34:12:1d:a9:5a:7c:cf:d3:af:e7:71:97:a2:48:
                bc:66:87:e9:32:30:bc:dc:10:24:6e:52:bf:8c:1b:da:bb:35:
 a:7a:e3:e3:e1:ff:78:4a:01:ab:75:1d:d8:17:53:ba:67:14:
 :be:c9:53:bb:36:f8:89:96:e7:1e:bc:db:69:4a:fd:33:31:
 :f8:27:59:01:81:3b:6e:70:d6:58:59:8f:3e:79:56:66:1f:
 :75:49:30:2b:af:ac:0d:0a:ac:8a:1b:0d:ff:c5:aa:f5:50:
 b:a0:03:e2:80:be:5b:8b:20:1b:0d:1d:89:40:7e:46:c5:39:
 :57:99:89:ad:9f:0e:86:7f:33:ed:56:a7:0d:70:fb:6f:4c:
                c4:6d:74:22:2a:ed:66:15:b2:89:46:03:69:b8:a4:11:17:87:
 c:49:de:33:ba:d5:8a:7b:49:7a:96:2e:8a:85:28:b5:96:91:
 :83:0a:21:55:0b:90:a9:60:30:85:7f:cd:ef:7b:b8:f1:02:
 :ba:e5:55:28:c5:e0:9d:52:a8:7d:98:eb:81:38:5d:a7:2f:
 :00:0c:b8:b8:31:1e:7e:4a:66:ac:fa:21:25:26:68:e4:23:
 c:63:d3:8e:8f:80:61:1a:c0:c0:52:cd:30:4c:36:58:fd:97:
 e:88:65:81:6e:be:d8:56:03:39:e8:3a:8c:85:3c:80:30:6c:
                b0:ef:cb:00:f6:7f:da:ed:8b:58:75:38:2a:cc:f1:52:d2:0d:
                a2:29:3f:2d:b3:46:92:b7:fb:18:e5:21:cd:0b:2d:87:d7:ad:
 f:e6:81:e6:5a:13:b4:f5:fc:5f:5b:d6:05:df:78:db:1a:a3:
 :46:7d:bf:e9:9a:cc:c9:66:d7:ee:f1:64:09:89:b3:e9:74:
 c:9a:dd:85:91:63:92:c9:58:49:31:38:cf:53:5c:99:19:e8:
 :90:d8:45:60:cf:a8:84:c8:c4:a8:d4:f8:dd:54:9e:d7:34:
 :5a:4c:73:38:d1:f5:37:f0:63:ec:bb:a8:5a:82:84:e8:e3:
 :51:f0:ed:4d:a5:e3:1f
       D, [2017-03-21T10:49:09.453552 ] DEBUG -- : Rack::Handler::WEBrick is mounted on /.
       I, [2017-03-21T10:49:09.453717 ]  INFO -- : WEBrick::HTTPServer#start: pid=30367 port=8443
       D, [2017-03-21T10:49:09.587558 ] DEBUG -- : Initializing puppet class cache for 'production' environment
       D, [2017-03-21T10:49:09.588221 ] DEBUG -- : Initializing puppet class cache for 'common' environment
       D, [2017-03-21T10:49:09.588526 ] DEBUG -- : Initializing puppet class cache for 'development' environment
       I, [2017-03-21T10:49:09.850506 ]  INFO -- : Finished puppet class cache initialization
       D, [2017-03-21T10:49:43.299594 ] DEBUG -- : accept: 15.162.41.254:41768
       D, [2017-03-21T10:49:43.305270 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:49:43.317517 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:49:43.318099 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:49:43.318458 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:49:43.323565 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:49:43 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0064
       D, [2017-03-21T10:49:43.365361 ] DEBUG -- : close: 15.162.41.254:41768
       D, [2017-03-21T10:49:43.643904 ] DEBUG -- : accept: 15.162.41.254:41770
       D, [2017-03-21T10:49:43.648099 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:49:43.649361 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:49:43.649782 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:49:43.650075 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:49:43.650964 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:49:43 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0018
       D, [2017-03-21T10:49:43.692220 ] DEBUG -- : close: 15.162.41.254:41770
       D, [2017-03-21T10:49:52.117876 ] DEBUG -- : accept: 15.162.41.254:41772
       D, [2017-03-21T10:49:52.121812 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:49:52.123011 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:49:52.123396 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:49:52.123632 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:49:52.124185 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:49:52 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0014
       D, [2017-03-21T10:49:52.165266 ] DEBUG -- : close: 15.162.41.254:41772
       D, [2017-03-21T10:49:52.465622 ] DEBUG -- : accept: 15.162.41.254:41774
       D, [2017-03-21T10:49:52.469727 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:49:52.470809 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:49:52.471229 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:49:52.471446 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T10:49:52.471969 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:49:52 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0013
       D, [2017-03-21T10:49:52.513219 ] DEBUG -- : close: 15.162.41.254:41774
       D, [2017-03-21T10:51:07.578974 ] DEBUG -- : accept: 15.162.41.254:41776
       D, [2017-03-21T10:51:07.587069 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:07.591087 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:51:07.592025 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:07 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0012
       D, [2017-03-21T10:51:07.634154 ] DEBUG -- : close: 15.162.41.254:41776
       D, [2017-03-21T10:51:07.701615 ] DEBUG -- : accept: 15.162.41.254:41778
       D, [2017-03-21T10:51:07.706043 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:07.707298 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:51:07.707693 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:51:07.708063 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:51:07.708653 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T10:51:07.708727 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T10:51:07.709214 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:07 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0022
       D, [2017-03-21T10:51:07.750916 ] DEBUG -- : close: 15.162.41.254:41778
       D, [2017-03-21T10:51:07.811684 ] DEBUG -- : accept: 15.162.41.254:41780
       D, [2017-03-21T10:51:07.815818 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:07.816898 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:51:07.817277 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:51:07.817551 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:51:07.817768 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T10:51:07.817822 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T10:51:07.818244 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:07 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0016
       D, [2017-03-21T10:51:07.868142 ] DEBUG -- : close: 15.162.41.254:41780
       D, [2017-03-21T10:51:07.985910 ] DEBUG -- : accept: 15.162.41.254:41782
       D, [2017-03-21T10:51:07.989514 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:07.993261 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:51:07.993686 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:51:07.993964 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T10:51:08.010050 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T10:51:08.010191 ] DEBUG -- : nil
       D, [2017-03-21T10:51:08.010285 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T10:51:08.010341 ] DEBUG -- : nil
       D, [2017-03-21T10:51:08.010403 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T10:51:08.010449 ] DEBUG -- : nil
       D, [2017-03-21T10:51:08.010502 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T10:51:08.010547 ] DEBUG -- : nil
       D, [2017-03-21T10:51:08.010808 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T10:51:08.010921 ] DEBUG -- : nil
       D, [2017-03-21T10:51:08.010994 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T10:51:08.011042 ] DEBUG -- : nil
       E, [2017-03-21T10:51:08.045936 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T10:51:08.046393 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T10:51:08.046444 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T10:51:08.047209 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:08 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0568
       D, [2017-03-21T10:51:08.090899 ] DEBUG -- : close: 15.162.41.254:41782
       D, [2017-03-21T10:51:29.140970 ] DEBUG -- : accept: 15.162.41.254:41786
       D, [2017-03-21T10:51:29.145435 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:29.146929 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:51:29.147769 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:29 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0012
       D, [2017-03-21T10:51:29.189239 ] DEBUG -- : close: 15.162.41.254:41786
       D, [2017-03-21T10:51:29.254185 ] DEBUG -- : accept: 15.162.41.254:41788
       D, [2017-03-21T10:51:29.258033 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:29.259204 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:51:29.259630 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:51:29.259998 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:51:29.260285 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T10:51:29.260343 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T10:51:29.260741 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:29 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0018
       D, [2017-03-21T10:51:29.302677 ] DEBUG -- : close: 15.162.41.254:41788
       D, [2017-03-21T10:51:29.359364 ] DEBUG -- : accept: 15.162.41.254:41790
       D, [2017-03-21T10:51:29.363408 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:29.364370 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:51:29.364717 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:51:29.365002 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:51:29.365238 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T10:51:29.365295 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T10:51:29.365646 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:29 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0014
       D, [2017-03-21T10:51:29.407216 ] DEBUG -- : close: 15.162.41.254:41790
       D, [2017-03-21T10:51:40.611768 ] DEBUG -- : accept: 15.162.41.254:41792
       D, [2017-03-21T10:51:40.616108 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:40.617379 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T10:51:40.618210 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:40 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0011
       D, [2017-03-21T10:51:40.659264 ] DEBUG -- : close: 15.162.41.254:41792
       D, [2017-03-21T10:51:40.724057 ] DEBUG -- : accept: 15.162.41.254:41794
       D, [2017-03-21T10:51:40.727971 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:40.729029 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:51:40.729446 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:51:40.729746 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:51:40.730062 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T10:51:40.730133 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T10:51:40.730541 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:40 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0017
       D, [2017-03-21T10:51:40.772190 ] DEBUG -- : close: 15.162.41.254:41794
       D, [2017-03-21T10:51:40.831027 ] DEBUG -- : accept: 15.162.41.254:41796
       D, [2017-03-21T10:51:40.834973 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:40.835937 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:51:40.836297 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:51:40.836553 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T10:51:40.836759 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T10:51:40.836813 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T10:51:40.837230 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:40 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0015
       D, [2017-03-21T10:51:40.878206 ] DEBUG -- : close: 15.162.41.254:41796
       D, [2017-03-21T10:51:41.025965 ] DEBUG -- : accept: 15.162.41.254:41798
       D, [2017-03-21T10:51:41.029800 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T10:51:41.056309 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T10:51:41.056769 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T10:51:41.057052 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T10:51:41.062139 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T10:51:41.062336 ] DEBUG -- : nil
       D, [2017-03-21T10:51:41.062452 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T10:51:41.062522 ] DEBUG -- : nil
       D, [2017-03-21T10:51:41.063987 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T10:51:41.064066 ] DEBUG -- : nil
       D, [2017-03-21T10:51:41.064141 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T10:51:41.064205 ] DEBUG -- : nil
       D, [2017-03-21T10:51:41.064502 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T10:51:41.064580 ] DEBUG -- : nil
       D, [2017-03-21T10:51:41.064649 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T10:51:41.064715 ] DEBUG -- : nil
       E, [2017-03-21T10:51:41.096250 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T10:51:41.096814 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T10:51:41.096923 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T10:51:41.098008 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:10:51:41 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0422
       D, [2017-03-21T10:51:41.142355 ] DEBUG -- : close: 15.162.41.254:41798
       I, [2017-03-21T10:51:47.984747 ]  INFO -- : going to shutdown ...
       I, [2017-03-21T10:51:47.984988 ]  INFO -- : WEBrick::HTTPServer#start done.
       D, [2017-03-21T10:53:08.009714 ] DEBUG -- : 'dns' settings: 'dns_ttl': 86400 (default), 'enabled': https, 'use_provider': dns_nsupdate (default)
       D, [2017-03-21T10:53:08.013725 ] DEBUG -- : 'tftp' settings: 'enabled': https, 'tftp_servername': 15.162.41.254, 'tftproot': /var/lib/tftpboot (default)
       D, [2017-03-21T10:53:08.032349 ] DEBUG -- : 'dhcp' settings: 'enabled': true, 'server': 15.162.33.7, 'subnets': [] (default), 'use_provider': dhcp_isc (default)
       D, [2017-03-21T10:53:08.034344 ] DEBUG -- : 'puppetca' settings: 'enabled': https, 'puppetdir': /etc/puppetlabs/puppet, 'ssldir': /etc/puppetlabs/puppet/ssl
       D, [2017-03-21T10:53:08.044746 ] DEBUG -- : 'puppet' settings: 'enabled': https, 'puppet_version': 4.9.4, 'use_provider': [:puppet_proxy_puppet_api]
       D, [2017-03-21T10:53:08.052939 ] DEBUG -- : 'logs' settings: 'enabled': https
       D, [2017-03-21T10:53:08.053324 ] DEBUG -- : Providers ['dns_nsupdate'] are going to be configured for 'dns'
       D, [2017-03-21T10:53:08.053403 ] DEBUG -- : Providers ['dhcp_isc'] are going to be configured for 'dhcp'
       D, [2017-03-21T10:53:08.053462 ] DEBUG -- : Providers ['puppet_proxy_puppet_api'] are going to be configured for 'puppet'
       D, [2017-03-21T10:53:08.058941 ] DEBUG -- : 'dns_nsupdate' settings: 'dns_key': /etc/rndc.key, 'dns_server': 127.0.0.1, 'dns_ttl': 86400, 'use_provider': dns_nsupdate
       D, [2017-03-21T10:53:08.092281 ] DEBUG -- : 'dhcp_isc' settings: 'config': /etc/dhcp/dhcpd.conf (default), 'key_name': omapi_key, 'key_secret': U6lPliIIXZ+tA+kOcIo51X18dCB0Bkse8ZZa3nncvmrwIBPk0NWMLqXEMH91JuVVR1lvbbESBzr4lB/+WDUGvA==, 'leases': /var/lib/dhcpd/dhcpd.leases (default), 'leases_file_observer': inotify_leases_file_observer, 'omapi_port': 7911, 'server': 15.162.33.7, 'subnets': [], 'use_provider': dhcp_isc
       D, [2017-03-21T10:53:08.507859 ] DEBUG -- : 'puppet_proxy_puppet_api' settings: 'api_timeout': 30 (default), 'classes_retriever': apiv3, 'environments_retriever': apiv3, 'puppet_ssl_ca': /etc/puppetlabs/puppet/ssl/certs/ca.pem, 'puppet_ssl_cert': /etc/puppetlabs/puppet/ssl/certs/set-chef-vm.3pardata.com.pem, 'puppet_ssl_key': /etc/puppetlabs/puppet/ssl/private_keys/set-chef-vm.3pardata.com.pem, 'puppet_url': https://set-chef-vm.3pardata.com:8140, 'puppet_version': 4.9.4, 'use_provider': [:puppet_proxy_puppet_api]
       I, [2017-03-21T10:53:08.508783 ]  INFO -- : Successfully initialized 'foreman_proxy'
       I, [2017-03-21T10:53:08.508908 ]  INFO -- : Successfully initialized 'dns_nsupdate'
       I, [2017-03-21T10:53:08.508983 ]  INFO -- : Successfully initialized 'dns'
       I, [2017-03-21T10:53:08.509034 ]  INFO -- : Successfully initialized 'tftp'
       D, [2017-03-21T10:53:08.686633 ] DEBUG -- : Added a subnet: 15.162.32.0
       I, [2017-03-21T10:53:08.687113 ]  INFO -- : Successfully initialized 'dhcp_isc'
       I, [2017-03-21T10:53:08.687226 ]  INFO -- : Successfully initialized 'dhcp'
       I, [2017-03-21T10:53:08.687300 ]  INFO -- : Successfully initialized 'puppetca'
       I, [2017-03-21T10:53:08.687409 ]  INFO -- : Started puppet class cache initialization
       I, [2017-03-21T10:53:08.721939 ]  INFO -- : Successfully initialized 'puppet_proxy_puppet_api'
       I, [2017-03-21T10:53:08.722098 ]  INFO -- : Successfully initialized 'puppet'
       D, [2017-03-21T10:53:08.722179 ] DEBUG -- : Log buffer API initialized, available capacity: 2000/1000
       I, [2017-03-21T10:53:08.722223 ]  INFO -- : Successfully initialized 'logs'
       E, [2017-03-21T10:53:08.735650 ] ERROR -- : Failed to initialize puppet class cache, will use lazy initialization instead
       I, [2017-03-21T10:53:08.752018 ]  INFO -- : WEBrick 1.3.1
       I, [2017-03-21T10:53:08.752111 ]  INFO -- : ruby 2.0.0 (2015-12-16) [x86_64-linux]
       D, [2017-03-21T10:53:08.752308 ] DEBUG -- : TCPServer.new(0.0.0.0, 8443)
       D, [2017-03-21T10:53:08.752404 ] DEBUG -- : TCPServer.new(::, 8443)
       W, [2017-03-21T10:53:08.752497 ]  WARN -- : TCPServer Error: Address already in use - bind(2)
       I, [2017-03-21T10:53:08.753407 ]  INFO -- :
       Certificate:
           Data:
               Version: 3 (0x2)
               Serial Number: 2 (0x2)
           Signature Algorithm: sha256WithRSAEncryption
               Issuer: CN=Puppet CA: set-chef-vm.3pardata.com
               Validity
                   Not Before: Mar 13 02:35:43 2017 GMT
                   Not After : Mar 13 02:35:43 2022 GMT
               Subject: CN=set-chef-vm.3pardata.com
               Subject Public Key Info:
                   Public Key Algorithm: rsaEncryption
                       Public-Key: (4096 bit)
                       Modulus:
 :ba:6f:6c:e2:eb:f2:e0:66:1e:16:94:7c:b5:32:
 a:20:ce:c5:1f:3b:ac:3a:55:b1:93:06:11:a6:a9:
 c:d7:25:c5:96:ab:a8:b6:67:53:11:4e:8a:b7:7f:
                           cc:88:6d:48:17:c4:06:df:74:17:b1:e8:18:4f:8f:
 :f3:26:b6:8f:d3:5d:1e:f9:25:d4:ab:1c:ea:14:
 :7c:55:57:3e:b3:c0:aa:4d:63:67:17:58:8b:56:
                           d0:60:76:7b:3f:31:7a:b5:8a:58:64:fe:05:43:7f:
 f:a6:67:1c:b3:2e:9b:72:1f:62:57:1a:21:8a:71:
                           cf:27:fd:c6:a2:2d:29:e3:3a:64:f9:a4:36:aa:79:
 a:32:c6:5e:1d:5c:7e:2a:bb:ed:a9:b9:37:0b:da:
                           a8:7f:4c:5d:82:90:9e:58:aa:c7:de:16:c5:85:47:
 :74:8c:2e:1a:a7:b7:e8:cd:af:ea:76:06:55:a6:
 :cb:91:d2:74:41:6b:9f:db:7b:85:a2:21:4a:2e:
 :51:b9:64:f3:ad:c4:a2:5b:4f:75:d0:e2:c0:da:
                           dd:23:9f:66:4e:9f:07:e4:0c:14:04:27:a2:69:da:
                           f6:bf:35:15:8a:53:91:59:de:21:d8:4c:bc:37:d5:
 c:55:c6:a4:1e:30:68:33:ff:9b:39:79:76:09:b2:
 :91:5a:08:a7:42:f7:c5:ee:aa:cd:13:f5:f3:d6:
                           c0:00:d6:8a:49:ba:47:70:3a:a5:00:d8:93:34:aa:
                           b9:6b:56:18:a3:f5:90:42:79:58:0c:a6:33:f0:dc:
 b:5f:f4:d1:56:05:16:92:20:2a:5e:80:79:ed:20:
 c:4d:07:b9:8d:cb:a7:ca:64:bf:2d:2f:48:2d:42:
 :35:0c:01:c3:56:c7:4a:0b:fa:55:27:8a:11:9a:
 :fa:f9:53:0d:49:aa:39:d1:90:10:09:f7:6f:64:
                           ec:30:6a:dd:a0:d3:85:68:27:36:34:96:33:eb:7e:
                           b6:f2:ff:b4:8d:77:11:16:12:c4:a5:37:95:34:a2:
 c:a1:01:d1:27:ea:79:6f:9a:e3:02:a3:56:fc:18:
 :c0:8c:1b:a9:0c:fe:12:1c:d0:08:cb:03:b5:9d:
 b:d6:ec:8b:82:fd:29:42:ab:a1:8c:b2:c0:c0:02:
 :9c:14:86:7d:92:9f:a6:4f:3d:e1:c5:2c:54:01:
 a:94:ee:53:47:62:e6:e8:b5:91:21:91:3c:4d:7f:
                           a3:b4:61:f2:37:90:f8:8f:96:ad:e3:75:cf:cd:2c:
 :e6:fc:a6:61:12:74:1e:04:bd:0f:5c:43:77:ec:
 :b8:23:38:dc:6a:03:c0:aa:b7:6e:a3:3d:68:18:
 :9e:bd
                       Exponent: 65537 (0x10001)
               X509v3 extensions:
                   Netscape Comment:
                       .(Puppet Ruby/OpenSSL Internal Certificate
                   X509v3 Subject Alternative Name:
                       DNS:puppet, DNS:puppet.3pardata.com, DNS:set-chef-vm.3pardata.com
                   X509v3 Key Usage: critical
                       Digital Signature, Key Encipherment
                   X509v3 Extended Key Usage: critical
                       TLS Web Server Authentication, TLS Web Client Authentication
                   X509v3 Basic Constraints: critical
                       CA:FALSE
                   X509v3 Subject Key Identifier:
                       C3:8D:BA:8B:C4:21:6B:09:42:64:E9:5A:9C:45:36:1C:C8:FB:08:23
                   X509v3 Authority Key Identifier:
                       keyid:7C:D8:BB:B9:DE:5F:58:60:4E:6A:FF:36:DE:91:A8:37:EF:89:61:37
           Signature Algorithm: sha256WithRSAEncryption
 e:0e:4a:33:60:67:c9:0e:f8:48:64:e1:ce:81:b4:f6:a4:f5:
                f0:3c:d2:d3:e7:11:4f:bc:a4:8c:bd:e7:90:20:6e:3f:d4:5f:
 e:7e:83:00:62:20:2f:13:be:26:0f:98:7e:38:fe:dc:88:d6:
                a2:07:04:02:3c:b5:96:7c:a8:68:3d:80:04:f7:b1:0d:7f:7d:
 e:0f:e5:ef:d8:0f:6a:15:6b:98:53:ea:f8:8d:15:69:10:5a:
 :33:39:49:a5:ef:89:c3:c9:65:52:0f:18:7b:3c:4e:19:5d:
                b5:2d:15:83:34:12:1d:a9:5a:7c:cf:d3:af:e7:71:97:a2:48:
                bc:66:87:e9:32:30:bc:dc:10:24:6e:52:bf:8c:1b:da:bb:35:
 a:7a:e3:e3:e1:ff:78:4a:01:ab:75:1d:d8:17:53:ba:67:14:
 :be:c9:53:bb:36:f8:89:96:e7:1e:bc:db:69:4a:fd:33:31:
 :f8:27:59:01:81:3b:6e:70:d6:58:59:8f:3e:79:56:66:1f:
 :75:49:30:2b:af:ac:0d:0a:ac:8a:1b:0d:ff:c5:aa:f5:50:
 b:a0:03:e2:80:be:5b:8b:20:1b:0d:1d:89:40:7e:46:c5:39:
 :57:99:89:ad:9f:0e:86:7f:33:ed:56:a7:0d:70:fb:6f:4c:
                c4:6d:74:22:2a:ed:66:15:b2:89:46:03:69:b8:a4:11:17:87:
 c:49:de:33:ba:d5:8a:7b:49:7a:96:2e:8a:85:28:b5:96:91:
 :83:0a:21:55:0b:90:a9:60:30:85:7f:cd:ef:7b:b8:f1:02:
 :ba:e5:55:28:c5:e0:9d:52:a8:7d:98:eb:81:38:5d:a7:2f:
 :00:0c:b8:b8:31:1e:7e:4a:66:ac:fa:21:25:26:68:e4:23:
 c:63:d3:8e:8f:80:61:1a:c0:c0:52:cd:30:4c:36:58:fd:97:
 e:88:65:81:6e:be:d8:56:03:39:e8:3a:8c:85:3c:80:30:6c:
                b0:ef:cb:00:f6:7f:da:ed:8b:58:75:38:2a:cc:f1:52:d2:0d:
                a2:29:3f:2d:b3:46:92:b7:fb:18:e5:21:cd:0b:2d:87:d7:ad:
 f:e6:81:e6:5a:13:b4:f5:fc:5f:5b:d6:05:df:78:db:1a:a3:
 :46:7d:bf:e9:9a:cc:c9:66:d7:ee:f1:64:09:89:b3:e9:74:
 c:9a:dd:85:91:63:92:c9:58:49:31:38:cf:53:5c:99:19:e8:
 :90:d8:45:60:cf:a8:84:c8:c4:a8:d4:f8:dd:54:9e:d7:34:
 :5a:4c:73:38:d1:f5:37:f0:63:ec:bb:a8:5a:82:84:e8:e3:
 :51:f0:ed:4d:a5:e3:1f
       D, [2017-03-21T10:53:08.765905 ] DEBUG -- : Rack::Handler::WEBrick is mounted on /.
       I, [2017-03-21T10:53:08.766041 ]  INFO -- : WEBrick::HTTPServer#start: pid=2303 port=8443
       D, [2017-03-21T11:00:16.603635 ] DEBUG -- : accept: 15.162.41.254:60262
       D, [2017-03-21T11:00:16.631315 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:00:16.672562 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T11:00:16.693083 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:00:16 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0209
       D, [2017-03-21T11:00:16.735588 ] DEBUG -- : close: 15.162.41.254:60262
       D, [2017-03-21T11:00:16.801916 ] DEBUG -- : accept: 15.162.41.254:60264
       D, [2017-03-21T11:00:16.806531 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:00:16.810251 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:00:16.810665 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:00:16.811117 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:00:16.811787 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T11:00:16.811893 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T11:00:16.812323 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:00:16 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0023
       D, [2017-03-21T11:00:16.853779 ] DEBUG -- : close: 15.162.41.254:60264
       D, [2017-03-21T11:00:16.913000 ] DEBUG -- : accept: 15.162.41.254:60266
       D, [2017-03-21T11:00:16.917271 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:00:16.918225 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:00:16.918565 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:00:16.918839 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:00:16.919097 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T11:00:16.919155 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T11:00:16.919541 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:00:16 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0014
       D, [2017-03-21T11:00:16.971099 ] DEBUG -- : close: 15.162.41.254:60266
       D, [2017-03-21T11:00:17.225374 ] DEBUG -- : accept: 15.162.41.254:60268
       D, [2017-03-21T11:00:17.229897 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:00:17.239355 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:00:17.239876 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:00:17.240200 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T11:00:17.244665 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T11:00:17.244881 ] DEBUG -- : nil
       D, [2017-03-21T11:00:17.245006 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T11:00:17.245069 ] DEBUG -- : nil
       D, [2017-03-21T11:00:17.245160 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T11:00:17.245216 ] DEBUG -- : nil
       D, [2017-03-21T11:00:17.245278 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T11:00:17.245329 ] DEBUG -- : nil
       D, [2017-03-21T11:00:17.245674 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T11:00:17.245766 ] DEBUG -- : nil
       D, [2017-03-21T11:00:17.245890 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T11:00:17.245965 ] DEBUG -- : nil
       E, [2017-03-21T11:00:17.293476 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T11:00:17.294098 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T11:00:17.294182 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T11:00:17.295193 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:00:17 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0642
       D, [2017-03-21T11:00:17.336359 ] DEBUG -- : close: 15.162.41.254:60268
       D, [2017-03-21T11:00:33.958296 ] DEBUG -- : caught :moved_to event on /var/lib/dhcpd/dhcpd.leases.
       D, [2017-03-21T11:00:33.961256 ] DEBUG -- : Added a subnet: 15.162.32.0
       D, [2017-03-21T11:01:06.605039 ] DEBUG -- : accept: 15.162.41.254:60272
       D, [2017-03-21T11:01:06.610379 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:01:06.612241 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T11:01:06.613230 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:01:06 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0015
       D, [2017-03-21T11:01:06.653584 ] DEBUG -- : close: 15.162.41.254:60272
       D, [2017-03-21T11:01:06.719972 ] DEBUG -- : accept: 15.162.41.254:60274
       D, [2017-03-21T11:01:06.723762 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:01:06.724839 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:01:06.725292 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:01:06.725638 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:01:06.725937 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T11:01:06.726008 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T11:01:06.726443 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:01:06 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0018
       D, [2017-03-21T11:01:06.767205 ] DEBUG -- : close: 15.162.41.254:60274
       D, [2017-03-21T11:01:06.827318 ] DEBUG -- : accept: 15.162.41.254:60276
       D, [2017-03-21T11:01:06.831209 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:01:06.832257 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:01:06.832609 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:01:06.832910 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:01:06.833146 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T11:01:06.833202 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T11:01:06.833607 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:01:06 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0015
       D, [2017-03-21T11:01:06.875180 ] DEBUG -- : close: 15.162.41.254:60276
       D, [2017-03-21T11:01:17.803182 ] DEBUG -- : accept: 15.162.41.254:60278
       D, [2017-03-21T11:01:17.807482 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:01:17.808781 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       I, [2017-03-21T11:01:17.809655 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:01:17 -0700] "GET /tftp/serverName HTTP/1.1" 200 30 0.0012
       D, [2017-03-21T11:01:17.855013 ] DEBUG -- : close: 15.162.41.254:60278
       D, [2017-03-21T11:01:17.918703 ] DEBUG -- : accept: 15.162.41.254:60280
       D, [2017-03-21T11:01:17.922619 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:01:17.923735 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:01:17.924160 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:01:17.924467 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:01:17.924702 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T11:01:17.924757 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T11:01:17.925202 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:01:17 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0017
       D, [2017-03-21T11:01:17.966199 ] DEBUG -- : close: 15.162.41.254:60280
       D, [2017-03-21T11:01:18.023647 ] DEBUG -- : accept: 15.162.41.254:60282
       D, [2017-03-21T11:01:18.027465 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:01:18.028492 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:01:18.028877 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:01:18.029169 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:01:18.029387 ] ERROR -- : DHCP record 15.162.32.0/15.162.41.241 not found
       D, [2017-03-21T11:01:18.029443 ] DEBUG -- : DHCP record 15.162.32.0/15.162.41.241 not found
       I, [2017-03-21T11:01:18.029830 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:01:18 -0700] "GET /dhcp/15.162.32.0/15.162.41.241 HTTP/1.1" 404 47 0.0015
       D, [2017-03-21T11:01:18.071265 ] DEBUG -- : close: 15.162.41.254:60282
       D, [2017-03-21T11:01:18.225904 ] DEBUG -- : accept: 15.162.41.254:60284
       D, [2017-03-21T11:01:18.230380 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:01:18.232108 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:01:18.232499 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:01:18.232729 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T11:01:18.236858 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T11:01:18.237039 ] DEBUG -- : nil
       D, [2017-03-21T11:01:18.237171 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T11:01:18.237239 ] DEBUG -- : nil
       D, [2017-03-21T11:01:18.237346 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T11:01:18.237415 ] DEBUG -- : nil
       D, [2017-03-21T11:01:18.237486 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T11:01:18.237542 ] DEBUG -- : nil
       D, [2017-03-21T11:01:18.237825 ] DEBUG -- : omshell: executed - set statements = "filename = \"pxelinux.0\"; next-server = 0f:a2:29:fe; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T11:01:18.237955 ] DEBUG -- : nil
       D, [2017-03-21T11:01:18.238041 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T11:01:18.238100 ] DEBUG -- : nil
       E, [2017-03-21T11:01:18.273804 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = "pxelinux.0"; next-server = 0f:a2:29:fe; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T11:01:18.274362 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T11:01:18.274436 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T11:01:18.275311 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:01:18 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0438
       D, [2017-03-21T11:01:18.316218 ] DEBUG -- : close: 15.162.41.254:60284
       D, [2017-03-21T11:06:03.947009 ] DEBUG -- : accept: 15.162.41.254:60288
       D, [2017-03-21T11:06:03.951977 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       I, [2017-03-21T11:06:03.957459 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:06:03 -0700] "GET /version HTTP/1.1" 200 133 0.0011
       D, [2017-03-21T11:06:03.999269 ] DEBUG -- : close: 15.162.41.254:60288
       D, [2017-03-21T11:06:10.472668 ] DEBUG -- : accept: 15.162.41.254:60290
       D, [2017-03-21T11:06:10.477450 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:06:10.478712 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:06:10.479203 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:06:10.479516 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T11:06:10.480198 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:06:10 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0017
       D, [2017-03-21T11:06:10.521242 ] DEBUG -- : close: 15.162.41.254:60290
       D, [2017-03-21T11:06:10.820716 ] DEBUG -- : accept: 15.162.41.254:60292
       D, [2017-03-21T11:06:10.824575 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:06:10.825690 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:06:10.826083 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:06:10.826317 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       I, [2017-03-21T11:06:10.826840 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:06:10 -0700] "GET /dhcp/15.162.32.0 HTTP/1.1" 200 31 0.0013
       D, [2017-03-21T11:06:10.868199 ] DEBUG -- : close: 15.162.41.254:60292
       D, [2017-03-21T11:06:14.678016 ] DEBUG -- : accept: 15.162.41.254:60294
       D, [2017-03-21T11:06:14.682668 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:06:14.683899 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:06:14.684288 ] DEBUG -- : Loading subnets for 15.162.33.7
       I, [2017-03-21T11:06:14.685209 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:06:14 -0700] "GET /dhcp/15.162.32.0/ HTTP/1.1" 404 27 0.0015
       D, [2017-03-21T11:06:14.726296 ] DEBUG -- : close: 15.162.41.254:60294
       D, [2017-03-21T11:06:14.784345 ] DEBUG -- : accept: 15.162.41.254:60296
       D, [2017-03-21T11:06:14.788453 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:06:14.789458 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:06:14.789797 ] DEBUG -- : Loading subnets for 15.162.33.7
       I, [2017-03-21T11:06:14.790415 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:06:14 -0700] "GET /dhcp/15.162.32.0/ HTTP/1.1" 404 27 0.0011
       D, [2017-03-21T11:06:14.831177 ] DEBUG -- : close: 15.162.41.254:60296
       D, [2017-03-21T11:06:14.889522 ] DEBUG -- : accept: 15.162.41.254:60298
       D, [2017-03-21T11:06:14.893626 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:06:14.894570 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:06:14.894940 ] DEBUG -- : Loading subnets for 15.162.33.7
       I, [2017-03-21T11:06:14.895461 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:06:14 -0700] "GET /dhcp/15.162.32.0/ HTTP/1.1" 404 27 0.0010
       D, [2017-03-21T11:06:14.935689 ] DEBUG -- : close: 15.162.41.254:60298
       D, [2017-03-21T11:06:14.999409 ] DEBUG -- : accept: 15.162.41.254:60300
       D, [2017-03-21T11:06:15.004048 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:06:15.005062 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:06:15.005435 ] DEBUG -- : Loading subnets for 15.162.33.7
       I, [2017-03-21T11:06:15.006048 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:06:15 -0700] "GET /dhcp/15.162.32.0/ HTTP/1.1" 404 27 0.0012
       D, [2017-03-21T11:06:15.047143 ] DEBUG -- : close: 15.162.41.254:60300
       D, [2017-03-21T11:08:15.100826 ] DEBUG -- : accept: 15.162.41.254:60302
       D, [2017-03-21T11:08:15.106177 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:08:15.108012 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:08:15.108397 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:08:15.108709 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       D, [2017-03-21T11:08:15.114106 ] DEBUG -- : omshell: executed - set name = "dl380pg8-127.3pardata.com"
       D, [2017-03-21T11:08:15.114289 ] DEBUG -- : nil
       D, [2017-03-21T11:08:15.114393 ] DEBUG -- : omshell: executed - set ip-address = 15.162.41.241
       D, [2017-03-21T11:08:15.114456 ] DEBUG -- : nil
       D, [2017-03-21T11:08:15.114525 ] DEBUG -- : omshell: executed - set hardware-address = 2c:44:fd:94:c1:d0
       D, [2017-03-21T11:08:15.114578 ] DEBUG -- : nil
       D, [2017-03-21T11:08:15.114638 ] DEBUG -- : omshell: executed - set hardware-type = 1
       D, [2017-03-21T11:08:15.114688 ] DEBUG -- : nil
       W, [2017-03-21T11:08:15.120382 ]  WARN -- : Failed to resolve IP address for 0f:a2:29:fe
       D, [2017-03-21T11:08:15.123336 ] DEBUG -- : omshell: executed - set statements = "filename = \"\"; next-server = \"0f:a2:29:fe\"; option host-name = \"dl380pg8-127.3pardata.com\";"
       D, [2017-03-21T11:08:15.123452 ] DEBUG -- : nil
       D, [2017-03-21T11:08:15.123532 ] DEBUG -- : omshell: executed - create
       D, [2017-03-21T11:08:15.123588 ] DEBUG -- : nil
       E, [2017-03-21T11:08:15.153688 ] ERROR -- : Omshell failed:
       > > > > obj: <null>
       , > obj: host
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , > obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = ""; next-server = "0f:a2:29:fe"; option host-name = "dl380pg8-127.3pardata.com";"
       , > can't open object: already exists
       , obj: host
       , name = "dl380pg8-127.3pardata.com"
       , ip-address = 0f:a2:29:f1
       , hardware-address = 2c:44:fd:94:c1:d0
       , hardware-type = 1
       , statements = "filename = ""; next-server = "0f:a2:29:fe"; option host-name = "dl380pg8-127.3pardata.com";"
       , >
       E, [2017-03-21T11:08:15.154210 ] ERROR -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists
       D, [2017-03-21T11:08:15.154288 ] DEBUG -- : Failed to add DHCP reservation for dl380pg8-127.3pardata.com (15.162.41.241 / 2c:44:fd:94:c1:d0): Entry already exists (Proxy::DHCP::Error)
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:99:in `report'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:82:in `om_disconnect'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:56:in `om_add_record'
       /usr/share/foreman-proxy/modules/dhcp_isc/dhcp_isc_main.rb:32:in `add_record'
       /usr/share/foreman-proxy/modules/dhcp/dhcp_api.rb:88:in `block in <class:DhcpApi>'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1611:in `block in compile!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `[]'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (3 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:994:in `route_eval'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:975:in `block (2 levels) in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1015:in `block in process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1013:in `process_route'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:973:in `block in route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `each'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:972:in `route!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1085:in `block in dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1082:in `dispatch!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `block in call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `block in invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `catch'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1067:in `invoke'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:907:in `call!'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:895:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/methodoverride.rb:22:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/commonlogger.rb:33:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:219:in `call'
       /usr/share/foreman-proxy/lib/proxy/log.rb:109:in `call'
       /usr/share/foreman-proxy/lib/proxy/request_id_middleware.rb:9:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/xss_header.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/path_traversal.rb:16:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/json_csrf.rb:18:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/base.rb:49:in `call'
       /usr/share/gems/gems/rack-protection-1.5.3/lib/rack/protection/frame_options.rb:31:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/nulllogger.rb:9:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/head.rb:13:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/show_exceptions.rb:25:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:182:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:2013:in `call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `block in call'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1787:in `synchronize'
       /usr/share/gems/gems/sinatra-1.4.7/lib/sinatra/base.rb:1487:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:66:in `block in call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `each'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/urlmap.rb:50:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/builder.rb:153:in `call'
       /usr/share/gems/gems/rack-1.6.4/lib/rack/handler/webrick.rb:88:in `service'
       /usr/share/ruby/webrick/httpserver.rb:138:in `service'
       /usr/share/ruby/webrick/httpserver.rb:94:in `run'
       /usr/share/ruby/webrick/server.rb:295:in `block in start_thread'
       I, [2017-03-21T11:08:15.155188 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:08:15 -0700] "POST /dhcp/15.162.32.0 HTTP/1.1" 400 118 0.0478
       D, [2017-03-21T11:08:15.195814 ] DEBUG -- : close: 15.162.41.254:60302
       D, [2017-03-21T11:08:15.261778 ] DEBUG -- : accept: 15.162.41.254:60306
       D, [2017-03-21T11:08:15.265755 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:08:15.267015 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:08:15.267396 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:08:15.267697 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:08:15.267972 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T11:08:15.268041 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T11:08:15.268421 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:08:15 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0017
       D, [2017-03-21T11:08:15.309210 ] DEBUG -- : close: 15.162.41.254:60306
       D, [2017-03-21T11:08:15.366755 ] DEBUG -- : accept: 15.162.41.254:60308
       D, [2017-03-21T11:08:15.370830 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:08:15.371824 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:08:15.372222 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:08:15.372510 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:08:15.372727 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T11:08:15.372783 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T11:08:15.373177 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:08:15 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0015
       D, [2017-03-21T11:08:15.414173 ] DEBUG -- : close: 15.162.41.254:60308
       D, [2017-03-21T11:08:15.472431 ] DEBUG -- : accept: 15.162.41.254:60310
       D, [2017-03-21T11:08:15.476444 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:08:15.477448 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:08:15.477784 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:08:15.478059 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:08:15.478277 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T11:08:15.478334 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T11:08:15.478738 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:08:15 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0015
       D, [2017-03-21T11:08:15.520204 ] DEBUG -- : close: 15.162.41.254:60310
       D, [2017-03-21T11:08:15.582050 ] DEBUG -- : accept: 15.162.41.254:60312
       D, [2017-03-21T11:08:15.585789 ] DEBUG -- : Rack::Handler::WEBrick is invoked.
       D, [2017-03-21T11:08:15.586726 ] DEBUG -- : verifying remote client 15.162.41.254 against trusted_hosts ["set-chef-vm.3pardata.com"]
       D, [2017-03-21T11:08:15.587093 ] DEBUG -- : Loading subnets for 15.162.33.7
       D, [2017-03-21T11:08:15.587368 ] DEBUG -- : Loading subnet data for 15.162.32.0/255.255.224.0
       E, [2017-03-21T11:08:15.587637 ] ERROR -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       D, [2017-03-21T11:08:15.587697 ] DEBUG -- : DHCP record 15.162.32.0/2c:44:fd:94:c1:d0 not found
       I, [2017-03-21T11:08:15.588063 ]  INFO -- : 15.162.41.254 - - [21/Mar/2017:11:08:15 -0700] "GET /dhcp/15.162.32.0/2c:44:fd:94:c1:d0 HTTP/1.1" 404 51 0.0015
       D, [2017-03-21T11:08:15.628753 ] DEBUG -- : close: 15.162.41.254:60312

(1-1/1)

Project

General

Profile

Foreman » Smart Proxy

Bug #18970 » proxy.log