Bug #9857

Internal server error when registering discovered host in Foreman 1.8

Added by Tom Verdaat over 2 years ago. Updated over 2 years ago.

Status:Closed
Priority:High
Assigned To:-
Category:Discovery plugin
Target version:Plugin 3.0.0
Difficulty: Pull request:https://github.com/theforeman/foreman_discovery/pull/193
Bugzilla link:
Story points-
Velocity based estimate-

Description

Using Foreman 1.8 RC1 and discovery image 2.1 I'm getting the following error:

2015-03-21 15:12:55 [I] Started POST "/api/v2/discovered_hosts/facts" for 10.9.168.59 at 2015-03-21 15:12:55 +0100
2015-03-21 15:12:55 [I] Processing by Api::V2::DiscoveredHostsController#facts as JSON
2015-03-21 15:12:55 [I]   Parameters: {"facts"=>"[FILTERED]", "apiv"=>"v2", "discovered_host"=>{"facts"=>"[FILTERED]"}}
2015-03-21 15:12:55 [I] Import facts for 'p0015174df244' completed. Added: 104, Updated: 0, Deleted 0 facts
2015-03-21 15:12:55 [E] address family must be specified (ArgumentError)
/usr/lib/ruby/1.9.1/ipaddr.rb:460:in `initialize'
/usr/share/foreman/app/models/subnet.rb:97:in `new'
/usr/share/foreman/app/models/subnet.rb:97:in `block in subnet_for'
/usr/share/foreman/app/models/subnet.rb:97:in `each'
/usr/share/foreman/app/models/subnet.rb:97:in `subnet_for'
/usr/share/foreman/vendor/ruby/1.9.1/gems/foreman_discovery-3.0.0/app/models/host/discovered.rb:110:in `populate_fields_from_facts'
/usr/share/foreman/app/models/host/base.rb:106:in `import_facts'
/usr/share/foreman/vendor/ruby/1.9.1/gems/foreman_discovery-3.0.0/app/models/host/discovered.rb:90:in `import_facts'
/usr/share/foreman/vendor/ruby/1.9.1/gems/foreman_discovery-3.0.0/app/models/host/discovered.rb:82:in `import_host_and_facts'
/usr/share/foreman/vendor/ruby/1.9.1/gems/foreman_discovery-3.0.0/app/controllers/api/v2/discovered_hosts_controller.rb:100:in `block in facts'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/connection_adapters/abstract/database_statements.rb:192:in `transaction'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/transactions.rb:208:in `transaction'
/usr/share/foreman/app/models/host.rb:15:in `method_missing'
/usr/share/foreman/vendor/ruby/1.9.1/gems/foreman_discovery-3.0.0/app/controllers/api/v2/discovered_hosts_controller.rb:99:in `facts'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/implicit_render.rb:4:in `send_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/base.rb:167:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/rendering.rb:10:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/callbacks.rb:18:in `block in process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:518:in `block (5 levels) in _run__290186017224470252__process_action__600673319205174767__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7618'
/usr/share/foreman/app/controllers/api/v2/base_controller.rb:151:in `disable_json_root'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7618'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:517:in `block (4 levels) in _run__290186017224470252__process_action__600673319205174767__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7617'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:326:in `around'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:310:in `_callback_around_2141'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7617'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:505:in `block (3 levels) in _run__290186017224470252__process_action__600673319205174767__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7616'
/usr/share/foreman/app/controllers/concerns/application_shared.rb:13:in `set_timezone'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7616'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:504:in `block (2 levels) in _run__290186017224470252__process_action__600673319205174767__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7615'
/usr/share/foreman/app/models/concerns/foreman/thread_session.rb:32:in `clear_thread'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7615'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:448:in `block in _run__290186017224470252__process_action__600673319205174767__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:215:in `block in _conditional_callback_around_7614'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:326:in `around'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:310:in `_callback_around_13'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:214:in `_conditional_callback_around_7614'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:414:in `_run__290186017224470252__process_action__600673319205174767__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_process_action_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/callbacks.rb:17:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/rescue.rb:29:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/instrumentation.rb:30:in `block in process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/notifications.rb:123:in `block in instrument'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/notifications/instrumenter.rb:20:in `instrument'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/notifications.rb:123:in `instrument'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/instrumentation.rb:29:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/params_wrapper.rb:207:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/railties/controller_runtime.rb:18:in `process_action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/base.rb:121:in `process'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/abstract_controller/rendering.rb:45:in `process'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal.rb:203:in `dispatch'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal/rack_delegation.rb:14:in `dispatch'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_controller/metal.rb:246:in `block in action'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:73:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:73:in `dispatch'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:36:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/routing/mapper.rb:43:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:68:in `block in call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `each'
/usr/share/foreman/vendor/ruby/1.9.1/gems/journey-1.0.4/lib/journey/router.rb:56:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/routing/route_set.rb:608:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/apipie-rails-0.2.6/lib/apipie/extractor/recorder.rb:97:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/apipie-rails-0.2.6/lib/apipie/middleware/checksum_in_headers.rb:27:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/best_standards_support.rb:17:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/etag.rb:23:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/conditionalget.rb:35:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/head.rb:14:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/params_parser.rb:21:in `call'
/usr/share/foreman/lib/middleware/catch_json_parse_errors.rb:9:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/flash.rb:242:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:210:in `context'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/session/abstract/id.rb:205:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/cookies.rb:341:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/query_cache.rb:64:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activerecord-3.2.21/lib/active_record/connection_adapters/abstract/connection_pool.rb:479:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `_run__3446009319613529455__call__1766336569595475298__callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:405:in `__run_callback'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:385:in `_run_call_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/callbacks.rb:81:in `run_callbacks'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/callbacks.rb:27:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/remote_ip.rb:31:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/debug_exceptions.rb:16:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/rack/logger.rb:32:in `call_app'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/rack/logger.rb:16:in `block in call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/tagged_logging.rb:22:in `tagged'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/rack/logger.rb:16:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/request_id.rb:22:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/methodoverride.rb:21:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/runtime.rb:17:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/activesupport-3.2.21/lib/active_support/cache/strategy/local_cache.rb:72:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/lock.rb:15:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/actionpack-3.2.21/lib/action_dispatch/middleware/static.rb:83:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:136:in `forward'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:143:in `pass'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:155:in `invalidate'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:71:in `call!'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-cache-1.2/lib/rack/cache/context.rb:51:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/engine.rb:484:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/application.rb:231:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/railties-3.2.21/lib/rails/railtie/configurable.rb:30:in `method_missing'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/builder.rb:134:in `call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/urlmap.rb:64:in `block in call'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/urlmap.rb:49:in `each'
/usr/share/foreman/vendor/ruby/1.9.1/gems/rack-1.4.5/lib/rack/urlmap.rb:49:in `call'
/usr/lib/ruby/vendor_ruby/phusion_passenger/rack/thread_handler_extension.rb:77:in `process_request'
/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler/thread_handler.rb:142:in `accept_and_process_next_request'
/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler/thread_handler.rb:110:in `main_loop'
/usr/lib/ruby/vendor_ruby/phusion_passenger/request_handler.rb:448:in `block (3 levels) in start_threads'
2015-03-21 15:12:55 [I]   Rendered api/v2/errors/standard_error.json.rabl within api/v2/layouts/error_layout (0.8ms)
2015-03-21 15:12:55 [I] Completed 500 Internal Server Error in 614.6ms (Views: 2.1ms | ActiveRecord: 92.1ms)

Discovery-debug information:

[root@fdi ~]# discovery-debug 
* PROCESSES *
  PID TTY      STAT   TIME COMMAND
    1 ?        Ss     0:01 /usr/lib/systemd/systemd --switched-root --system --deserialize 21
    2 ?        S      0:00 [kthreadd]
    3 ?        S      0:00 [ksoftirqd/0]
    4 ?        S      0:00 [kworker/0:0]
    5 ?        S<     0:00 [kworker/0:0H]
    7 ?        S      0:00 [migration/0]
    8 ?        S      0:00 [rcu_bh]
    9 ?        S      0:00 [rcuob/0]
   10 ?        S      0:00 [rcuob/1]
   11 ?        S      0:00 [rcuob/2]
   12 ?        S      0:00 [rcuob/3]
   13 ?        S      0:00 [rcuob/4]
   14 ?        S      0:00 [rcuob/5]
   15 ?        S      0:00 [rcuob/6]
   16 ?        S      0:00 [rcuob/7]
   17 ?        S      0:00 [rcu_sched]
   18 ?        S      0:00 [rcuos/0]
   19 ?        S      0:00 [rcuos/1]
   20 ?        S      0:00 [rcuos/2]
   21 ?        S      0:00 [rcuos/3]
   22 ?        S      0:00 [rcuos/4]
   23 ?        S      0:00 [rcuos/5]
   24 ?        S      0:00 [rcuos/6]
   25 ?        S      0:00 [rcuos/7]
   26 ?        S      0:00 [watchdog/0]
   27 ?        S      0:00 [watchdog/1]
   28 ?        S      0:00 [migration/1]
   29 ?        S      0:00 [ksoftirqd/1]
   30 ?        S      0:00 [kworker/1:0]
   31 ?        S<     0:00 [kworker/1:0H]
   32 ?        S      0:00 [watchdog/2]
   33 ?        S      0:00 [migration/2]
   34 ?        S      0:00 [ksoftirqd/2]
   36 ?        S<     0:00 [kworker/2:0H]
   37 ?        S      0:00 [watchdog/3]
   38 ?        S      0:00 [migration/3]
   39 ?        S      0:00 [ksoftirqd/3]
   40 ?        S      0:00 [kworker/3:0]
   41 ?        S<     0:00 [kworker/3:0H]
   42 ?        S      0:00 [watchdog/4]
   43 ?        S      0:00 [migration/4]
   44 ?        S      0:00 [ksoftirqd/4]
   45 ?        S      0:00 [kworker/4:0]
   46 ?        S<     0:00 [kworker/4:0H]
   47 ?        S      0:00 [watchdog/5]
   48 ?        S      0:00 [migration/5]
   49 ?        S      0:00 [ksoftirqd/5]
   51 ?        S<     0:00 [kworker/5:0H]
   52 ?        S      0:00 [watchdog/6]
   53 ?        S      0:00 [migration/6]
   54 ?        S      0:00 [ksoftirqd/6]
   56 ?        S<     0:00 [kworker/6:0H]
   57 ?        S      0:00 [watchdog/7]
   58 ?        S      0:00 [migration/7]
   59 ?        S      0:00 [ksoftirqd/7]
   61 ?        S<     0:00 [kworker/7:0H]
   62 ?        S<     0:00 [khelper]
   63 ?        S      0:00 [kdevtmpfs]
   64 ?        S<     0:00 [netns]
   65 ?        S<     0:00 [writeback]
   66 ?        S<     0:00 [kintegrityd]
   67 ?        S<     0:00 [bioset]
   68 ?        S<     0:00 [kblockd]
   69 ?        S      0:00 [khubd]
   70 ?        S<     0:00 [md]
   71 ?        S      0:00 [kworker/0:1]
   72 ?        S      0:00 [kworker/1:1]
   74 ?        S      0:00 [kworker/3:1]
   76 ?        S      0:00 [kworker/5:1]
   77 ?        S      0:00 [kworker/6:1]
   78 ?        S      0:00 [kworker/7:1]
   80 ?        S      0:00 [kswapd0]
   81 ?        SN     0:00 [ksmd]
   82 ?        SN     0:00 [khugepaged]
   83 ?        S      0:00 [fsnotify_mark]
   84 ?        S<     0:00 [crypto]
   93 ?        S<     0:00 [kthrotld]
   96 ?        S<     0:00 [kmpath_rdacd]
   97 ?        S<     0:00 [kpsmoused]
  117 ?        S<     0:00 [deferwq]
  137 ?        S      0:00 [kauditd]
  254 ?        S<     0:00 [ata_sff]
  255 ?        S      0:00 [scsi_eh_0]
  256 ?        S<     0:00 [scsi_tmf_0]
  257 ?        S      0:00 [scsi_eh_1]
  258 ?        S<     0:00 [scsi_tmf_1]
  277 ?        S      0:00 [kworker/5:2]
  279 ?        S      0:00 [kworker/6:2]
  281 ?        S      0:00 [kworker/7:2]
  284 ?        S      0:00 [kworker/3:2]
  285 ?        S      0:00 [kworker/4:2]
  292 ?        S<     0:00 [loop0]
  332 ?        S<     0:00 [loop1]
  337 ?        S<     0:00 [loop2]
  341 ?        S<     0:00 [loop3]
  346 ?        S<     0:04 [loop4]
  356 ?        S<     0:00 [loop5]
  360 ?        S<     0:00 [kdmflush]
  363 ?        S<     0:00 [dm_bufio_cache]
  364 ?        S<     0:00 [ksnaphd]
  365 ?        S<     0:00 [kcopyd]
  366 ?        S<     0:00 [bioset]
  367 ?        S<     0:00 [bioset]
  368 ?        S<     0:00 [bioset]
  374 ?        S<     0:00 [kdmflush]
  375 ?        S<     0:00 [bioset]
  383 ?        S<     0:00 [kdmflush]
  384 ?        S<     0:00 [ksnaphd]
  385 ?        S<     0:00 [kcopyd]
  386 ?        S<     0:00 [bioset]
  387 ?        S<     0:00 [bioset]
  389 ?        S<     0:00 [bioset]
  422 ?        S<     0:00 [loop6]
  425 ?        S      0:00 [jbd2/loop6-8]
  426 ?        S<     0:00 [ext4-dio-unwrit]
  461 ?        Ss     0:00 /usr/lib/systemd/systemd-journald
  490 ?        Ss     0:00 /usr/lib/systemd/systemd-udevd
  502 ?        Ssl    0:00 /usr/sbin/NetworkManager --no-daemon
  505 ?        Ss     0:00 avahi-daemon: running [fdi.local]
  508 ?        Ss     0:00 /usr/lib/systemd/systemd-logind
  509 ?        Ss     0:00 /bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation
  513 ?        Ss     0:00 /usr/sbin/acpid
  539 ?        S      0:00 avahi-daemon: chroot helper
  542 ?        S<     0:00 [edac-poller]
  543 ?        SN     0:04 [kipmi0]
  544 ?        S      0:00 [scsi_eh_2]
  545 ?        S<     0:00 [scsi_tmf_2]
  546 ?        S<     0:00 [mlx4]
  547 ?        S<     0:00 [kvm-irqfd-clean]
  556 ?        Ssl    0:00 /usr/lib/polkit-1/polkitd --no-debug
  657 ?        S<     0:00 [ib_addr]
  658 ?        S<     0:00 [ib_mcast]
  659 ?        S<     0:00 [mlx4_ib]
  660 ?        S<     0:00 [mlx4_ib_mcg]
  661 ?        S<     0:00 [ib_mad1]
  662 ?        S<     0:00 [ib_mad2]
  672 ?        Ss     0:00 /usr/sbin/sshd -D
  673 ?        S      0:00 [kworker/2:2]
  694 ?        Sl     0:00 ruby /usr/share/foreman-proxy/bin/smart-proxy
  697 ?        S      0:00 /sbin/dhclient -d -sf /usr/libexec/nm-dhcp-helper -pf /var/run/dhclient-enp16s0f0.pid -lf /var/lib/NetworkManager/dhclient-b7749f2e-ce55-4c80-977d-26959a7e9153-enp16s0f0.lease -cf /var/lib/NetworkManager/dhclient-enp16s0f0.conf enp16s0f0
  699 ?        Ssl    0:12 ruby /usr/bin/discovery-register
  807 ?        S      0:00 [kworker/2:3]
 1320 ?        Ss     0:00 login -- root
 1322 tty2     Ss+    0:00 -bash
 4198 ?        S      0:00 [kworker/u16:1]
 8487 ?        S      0:00 [kworker/u16:0]
10492 ?        Ss     0:00 sshd: root@pts/0
10494 pts/0    Ss     0:00 -bash
10599 pts/0    S+     0:00 /bin/bash /usr/bin/discovery-debug
10600 pts/0    R+     0:00 ps axww
* JOURNAL (last 500 lines) *
(...)
Mar 21 12:57:25 fdi discovery-register[699]: Fact cache invalid, reloading to foreman
Mar 21 12:57:25 fdi discovery-register[699]: Parsing kernel line: initrd=boot/fdi-image/initrd0.img rootflags=loop root=live:/fdi.iso rootfstype=auto ro rd.live.image acpi=force rd.luks=0 rd.md=0 rd.dm=0 rd.lvm=0 rd.bootif=0 rd.neednet=0 nomodeset proxy.url=https://master.test.REDACTED.COM proxy.type=foreman fdi.ssh=1 fdi.rootpw=changeme BOOTIF=01-00-15-17-4d-f2-44 BOOT_IMAGE=boot/fdi-image/vmlinuz0
Mar 21 12:57:25 fdi discovery-register[699]: Discovered by URL: https://master.test.REDACTED.COM
Mar 21 12:57:25 fdi discovery-register[699]: Registering host with Foreman (https://master.test.REDACTED.COM)
Mar 21 12:57:26 fdi discovery-register[699]: Response from Foreman 500: {
Mar 21 12:57:26 fdi discovery-register[699]: "error": {"message":"address family must be specified"}
Mar 21 12:57:26 fdi discovery-register[699]: }
* PROXY (LEGACY) LOG *
tail: cannot open '/var/log/foreman-proxy/proxy.log' for reading: No such file or directory
* KERNEL COMMAND LINE *
initrd=boot/fdi-image/initrd0.img rootflags=loop root=live:/fdi.iso rootfstype=auto ro rd.live.image acpi=force rd.luks=0 rd.md=0 rd.dm=0 rd.lvm=0 rd.bootif=0 rd.neednet=0 nomodeset proxy.url=https://master.test.REDACTED.COM proxy.type=foreman fdi.ssh=1 fdi.rootpw=changeme BOOTIF=01-00-15-17-4d-f2-44 BOOT_IMAGE=boot/fdi-image/vmlinuz0 
* NETWORK CONFIGURATION *
DEVICE=lo
IPADDR=127.0.0.1
NETMASK=255.0.0.0
NETWORK=127.0.0.0
# If you're having problems with gated making 127.0.0.0/8 a martian,
# you can change this to something else (255.255.255.255, for example)
BROADCAST=127.255.255.255
ONBOOT=yes
NAME=loopback

* FACTER *
architecture => x86_64
auto_negotitation_enp16s0f0 => true
auto_negotitation_enp16s0f1 => true
auto_negotitation_enp17s0f0 => true
auto_negotitation_enp17s0f1 => true
bios_release_date => 10/30/2010
bios_vendor => Dell Inc.
bios_version => 2.7.0
blockdevice_sda_model => PERC 5/i
blockdevice_sda_size => 119453777920
blockdevice_sda_vendor => DELL
blockdevice_sr0_model => CD-ROM GCR-8240N
blockdevice_sr0_size => 1073741312
blockdevice_sr0_vendor => HL-DT-ST
blockdevices => sda,sr0
boardmanufacturer => Dell Inc.
boardproductname => 0UR033
boardserialnumber => ..CN6970277A2638.
dhcp_servers => {"system"=>"10.9.0.1", "enp16s0f0"=>"10.9.0.1"}
discovery_bootif => 00:15:17:4d:f2:44
discovery_bootip => 10.9.168.59
discovery_release => 20150114.1
discovery_version => 2.1.0
domain => test.REDACTED.COM
duplex_enp16s0f0 => full
duplex_enp16s0f1 => unknown! (255)
duplex_enp17s0f0 => unknown! (255)
duplex_enp17s0f1 => unknown! (255)
facterversion => 2.2.0
filesystems => ext2,ext3,ext4,iso9660,squashfs
fqdn => fdi.test.REDACTED.COM
gid => root
hardwareisa => x86_64
hardwaremodel => x86_64
hostname => fdi
id => root
interfaces => enp16s0f0,enp16s0f1,enp17s0f0,enp17s0f1,lo
ipaddress => 10.9.168.59
ipaddress_enp16s0f0 => 10.9.168.59
ipaddress_lo => 127.0.0.1
ipmi_enabled => true
ipmi_gateway => 0.0.0.0
ipmi_ipaddress => 10.14.60.33
ipmi_ipaddress_source => DHCP Address
ipmi_macaddress => 00:1c:23:bb:11:44
ipmi_subnet_mask => 255.255.0.0
is_virtual => false
kernel => Linux
kernelmajversion => 3.10
kernelrelease => 3.10.0-123.13.2.el7.x86_64
kernelversion => 3.10.0
lib => /usr/share/fdi/facts/
link_enp16s0f0 => true
link_enp16s0f1 => false
link_enp17s0f0 => false
link_enp17s0f1 => false
link_lo => true
macaddress => 00:15:17:4d:f2:44
macaddress_enp16s0f0 => 00:15:17:4d:f2:44
macaddress_enp16s0f1 => 00:15:17:4d:f2:45
macaddress_enp17s0f0 => 00:15:17:4d:f2:46
macaddress_enp17s0f1 => 00:15:17:4d:f2:47
manufacturer => Dell Inc.
memoryfree => 15.37 GB
memoryfree_mb => 15738.26
memorysize => 15.67 GB
memorysize_mb => 16044.43
mtu_enp16s0f0 => 1500
mtu_enp16s0f1 => 1500
mtu_enp17s0f0 => 1500
mtu_enp17s0f1 => 1500
mtu_lo => 65536
netmask => 255.255.0.0
netmask_enp16s0f0 => 255.255.0.0
netmask_lo => 255.0.0.0
network_enp16s0f0 => 10.9.0.0
network_lo => 127.0.0.0
operatingsystem => CentOS
operatingsystemmajrelease => 7
operatingsystemrelease => 7.0.1406
os => {"name"=>"CentOS", "family"=>"RedHat", "release"=>{"major"=>"7", "minor"=>"0", "full"=>"7.0.1406"}}
osfamily => RedHat
partitions => {"sda1"=>{"uuid"=>"569c98da-12f1-4380-b9e6-62a18b714552", "size"=>"497664", "filesystem"=>"ext4"}, "sda2"=>{"uuid"=>"be21a473-fd13-4be3-af05-3f4f9d8a5f9a", "size"=>"232806400", "filesystem"=>"ext4"}}
path => /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/opt/extension/bin:/root/bin
physicalprocessorcount => 2
port_enp16s0f0 => Twisted Pair
port_enp16s0f1 => Twisted Pair
port_enp17s0f0 => Twisted Pair
port_enp17s0f1 => Twisted Pair
processor0 => Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz
processor1 => Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz
processor2 => Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz
processor3 => Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz
processor4 => Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz
processor5 => Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz
processor6 => Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz
processor7 => Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz
processorcount => 8
processors => {"models"=>["Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz", "Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz", "Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz", "Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz", "Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz", "Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz", "Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz", "Intel(R) Xeon(R) CPU           E5345  @ 2.33GHz"], "count"=>8, "physicalcount"=>2}
productname => PowerEdge 1950
ps => ps -ef
rubysitedir => /usr/local/share/ruby/site_ruby/
rubyversion => 2.0.0
selinux => false
serialnumber => H6MH23J
speed_enp16s0f0 => 1000
sshecdsakey => AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBNGanN0He78JaeI9W7lfQlJOv34qKRIECwLpOSkV2e0iUKlLqwrletinjbKmdMjDHZmRUiTxUGBhw/GfJlj3ktQ=
sshfp_ecdsa => SSHFP 3 1 ae971cf090d8bd478de8d33e5669f1a69627c9a5
SSHFP 3 2 ca34f05b34091ba0a464e58e552c262a77b32ad8f4b229b26828690541fa432e
sshfp_rsa => SSHFP 1 1 f944287a8b5b04b5161e56bfcad45c2d31f892f1
SSHFP 1 2 d6f45f2b02635846741a0ddccce184c79e316e52b95410f8179eabed7202f69d
sshrsakey => AAAAB3NzaC1yc2EAAAADAQABAAABAQCi0++4mVnPywPTZqnPXL/9UDTGVu7SWAojl1b9LJbDR95Xhn8YjrWSAIww5Lofg+nHEIwY/GWEoKNVoz+ake1R/0CBnoG5QiqUWekHsSqQ1j3jHvc9S/tcu1CzMFnQVAfEzgobYeE+s2K8XCy+MFMoTMiQWzFRcFmk8i2m+ieqoxr+HWDfdh5l4DFZeyNG0eSifSTlCNO3xqdAZTHe5ZpK631dEAxhzcpHxyuUB3QXNaip4SXv0Fgn5b1h2iNgL/zMxPexw6mge9bXX7AZtpIRus+KcVefNwsNwnmsI0qBXKf5LFELiwe8ysv1v9ughichELW9ixnwie2qOVheFAbh
swapfree => 0.00 MB
swapfree_mb => 0.00
swapsize => 0.00 MB
swapsize_mb => 0.00
system_uptime => {"seconds"=>2997, "hours"=>0, "days"=>0, "uptime"=>"0:49 hours"}
timezone => UTC
type => Rack Mount Chassis
uniqueid => 090a3ba8
uptime => 0:49 hours
uptime_days => 0
uptime_hours => 0
uptime_seconds => 2997
uuid => 44454C4C-3600-104D-8048-C8C04F32334A
virtual => physical
wol_enp16s0f0 => true
wol_enp16s0f1 => false
wol_enp17s0f0 => false
wol_enp17s0f1 => false
* IP ADDRESSES *
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN 
    link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
    inet 127.0.0.1/8 scope host lo
       valid_lft forever preferred_lft forever
    inet6 ::1/128 scope host 
       valid_lft forever preferred_lft forever
2: enp16s0f0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP qlen 1000
    link/ether 00:15:17:4d:f2:44 brd ff:ff:ff:ff:ff:ff
    inet 10.9.168.59/16 brd 10.9.255.255 scope global dynamic enp16s0f0
       valid_lft 1725019sec preferred_lft 1725019sec
    inet6 fe80::215:17ff:fe4d:f244/64 scope link 
       valid_lft forever preferred_lft forever
3: enp16s0f1: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc pfifo_fast state DOWN qlen 1000
    link/ether 00:15:17:4d:f2:45 brd ff:ff:ff:ff:ff:ff
4: enp17s0f0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc pfifo_fast state DOWN qlen 1000
    link/ether 00:15:17:4d:f2:46 brd ff:ff:ff:ff:ff:ff
5: enp17s0f1: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc pfifo_fast state DOWN qlen 1000
    link/ether 00:15:17:4d:f2:47 brd ff:ff:ff:ff:ff:ff
* ROUTES *
default via 10.9.0.1 dev enp16s0f0  proto static  metric 1024 
10.9.0.0/16 dev enp16s0f0  proto kernel  scope link  src 10.9.168.59 
* DHCP LEASES *
lease {
  interface "enp16s0f0";
  fixed-address 10.9.168.59;
  filename "pxelinux.0";
  option subnet-mask 255.255.0.0;
  option routers 10.9.0.1;
  option dhcp-lease-time 1728000;
  option dhcp-message-type 5;
  option domain-name-servers 10.9.0.1;
  option dhcp-server-identifier 10.9.0.1;
  option domain-name "test.REDACTED.COM";
  renew 2 2015/03/31 12:44:31;
  rebind 3 2015/04/08 01:23:44;
  expire 5 2015/04/10 13:23:44;
}
* DNS *
# Generated by NetworkManager
domain test.REDACTED.COM
search test.REDACTED.COM
nameserver 10.9.0.1

broken_foreman_discovery.log (110 KB) Rob Greenwood, 06/16/2015 12:22 PM


Related issues

Related to Discovery - Bug #10004: Discovery of a host with already known IP address fails w... Closed 04/02/2015
Related to Discovery - Bug #10852: Discovery image discovery_bootif doesn't match boot inter... Closed 06/17/2015

Associated revisions

Revision 4c018bc1
Added by Lukas Zapletal over 2 years ago

Fixes #9857 - facter extensions now compatible with 1.8+

History

#1 Updated by Dominic Cleal over 2 years ago

  • Project changed from Foreman to Discovery
  • Release deleted (1.8.0)

The error seems to indicate that a nil IP address is being passed from facts into the host's primary interface IP field, which is then being passed into the subnet search.

#2 Updated by Lukas Zapletal over 2 years ago

Hello, what is your discovery_fact setting?

#3 Updated by Tom Verdaat over 2 years ago

the default: discovery_bootif

#4 Updated by Lukas Zapletal over 2 years ago

Interested in Foreman Discovery version as well.

#5 Updated by Lukas Zapletal over 2 years ago

  • Category set to Discovery plugin
  • Priority changed from Normal to High
  • Target version set to Plugin 2.0.3

I have just realized that discovery extensions are not effective on 1.8. I don't know yet if this is expected behavior or not: https://github.com/theforeman/foreman_discovery/blob/develop/app/lib/puppet_fact_parser_extensions.rb

Marek can you elaborate please?

#6 Updated by Lukas Zapletal over 2 years ago

  • Target version changed from Plugin 2.0.3 to Plugin 3.0.0

Tom, thanks for the report. I am unable to reproduce. I wonder what is different in your environment. Primary interface should have been detected correctly. Can you run foreman-rake console and issue:

::Host::Discovered.last.primary_interface
::Host::Discovered.last.primary_interface.ip

Instead of "last" you can use "find(ID)". Paste the output please. Ty.

#7 Updated by Lukas Zapletal over 2 years ago

This might be interesting as well:

::Host::Discovered.last.interfaces

#8 Updated by Lukas Zapletal over 2 years ago

One last thing, please pastebin or verbosely copy this (or send me directly to lzap|redhatdotcom):

FACTERLIB=/usr/share/fdi/facts/ facter --json

#9 Updated by Lukas Zapletal over 2 years ago

  • Status changed from New to Need more information

Hello, I have just filed a new bug (#10004) - when a host is discovered with an IP that is already present in Foreman, we error out with this weird error instead of proper error message "IP address already in use" or similar. Can you verify that the IP address the discovered host was trying to register with is already defined as an existing host in Foreman?

We currently do not allow multiple hosts with same IPs.

#10 Updated by Lukas Zapletal over 2 years ago

  • Related to Bug #10004: Discovery of a host with already known IP address fails with: address family must be specified added

#11 Updated by Lukas Zapletal over 2 years ago

Ok it looks like app/lib/puppet_fact_parser_extensions.rb is not used at all. This has nothing to do with #10004. Fact importer in Core is broken in 1.8 or something. Need to investigate.

#12 Updated by Rob Greenwood over 2 years ago

Debug log attached

#13 Updated by The Foreman Bot over 2 years ago

  • Status changed from Need more information to Ready For Testing
  • Pull request https://github.com/theforeman/foreman_discovery/pull/193 added

#14 Updated by Lukas Zapletal over 2 years ago

We have a workaround now, set 'ignore_puppet_facts_for_provisioning' setting to false until 3.0.1 plugin is released!

#15 Updated by Lukas Zapletal over 2 years ago

  • Related to Bug #10852: Discovery image discovery_bootif doesn't match boot interface and can't discover host added

#16 Updated by Anonymous over 2 years ago

  • Status changed from Ready For Testing to Closed
  • % Done changed from 0 to 100

#17 Updated by Byron Miller over 2 years ago

I'm having same issue of foreman discovery booting, but posting same error when calling home. This happens on anything with more than one interface where the more than one interface gets DHCP and isn't the first interface.

#18 Updated by tony zheng over 2 years ago

can someone tell me where is the 'ignore_puppet_facts_for_provisioning' setting? i cannot find it. i am on foreman 1.8.2 RHEL 6.

thanks,

#19 Updated by Dominic Cleal over 2 years ago

tony zheng wrote:

can someone tell me where is the 'ignore_puppet_facts_for_provisioning' setting? i cannot find it. i am on foreman 1.8.2 RHEL 6.

thanks,

Administer > Settings > Provisioning.

#20 Updated by Simon Wydooghe over 2 years ago

I've set 'ignore_puppet_facts_for_provisioning' setting to false, but it doesn't seem to affect the problem. I've confirmed the hosts with this problem have the same IP as other hosts that were built using Foreman a few days ago. We build a lot of hosts locally and then they get shipped off to remote locations where they get a new IP address in whatever LAN they end up. But for now, all these hosts are still stored on-site and Foreman doesn't want to accept newly built hosts that got the new DHCP lease. Any ideas? Foreman 1.8.2 and Discovery 3.0.0.

Foreman log:

2015-08-16 17:26:54 [I] Started POST "/api/v2/discovered_hosts/facts" for 10.30.6.56 at 2015-08-16 17:26:54 +0200
2015-08-16 17:26:54 [I] Processing by Api::V2::DiscoveredHostsController#facts as JSON
2015-08-16 17:26:54 [I]   Parameters: {"facts"=>"[FILTERED]", "apiv"=>"v2", "discovered_host"=>{"facts"=>"[FILTERED]"}}
2015-08-16 17:26:54 [I] Import facts for 'mac3417eb6a0416' completed. Added: 80, Updated: 0, Deleted 0 facts
2015-08-16 17:26:54 [W] Saving enp1s0 NIC for host mac3417eb6a0416 failed, skipping because:
2015-08-16 17:26:54 [W]  IP address has already been taken
2015-08-16 17:26:54 [E] address family must be specified (ArgumentError)

#21 Updated by Lukas Zapletal over 2 years ago

I'd suggest to modify your reservation pool outside lease pool but still inside the subnet range. Described at the end of this chapter: http://theforeman.org/manuals/1.8/#3.2.3InstallationScenarios

#22 Updated by Simon Wydooghe over 2 years ago

Cheers, thank you for your help, Lukas! Sorry for the late update, needed to hurry along a bit so I created a new large subnet two days ago just for provisioning. This seemed to work.

Looking forward to trying out Discovery 4.0.0!

Also available in: Atom PDF