Description of problem:

During initial RHEVM 3.5 discovery/cluster fleecing, saw error in log


Version-Release number of selected component (if applicable):

5.4.0.0.19.20150410165622_ad23806

How reproducible:

Uncertain

Steps to Reproduce:
1. Discover VM
2.
3.

Actual results:

Errors (below)

Expected results:

No error

Additional info:

evm.log excerpts:

[----] I, [2015-04-13T07:50:17.162023 #2523:e23eac]  INFO -- : MIQ(MiqQueue.put)        Message id: [5370],  id: [], Zone: [default], Role: [ems_inventory], Server: [], Ident: [ems_1], Target id: [], Instance id: [], Task id: [], Command: [EmsRefresh.refresh], Timeout: [7200], Priority: [100], State: [ready], Deliver On: [], Data: [], Args: [[["VmRedhat", 64]]]
[...]
[----] I, [2015-04-13T07:50:17.752933 #2523:e23eac]  INFO -- : MIQ(MiqQueue.merge)      Message id: [5370] updated with following: {:queue_name=>"ems_1", :state=>"ready", :zone=>"default", :class_name=>"EmsRefresh", :method_name=>"refresh", :role=>"ems_inventory", :args=>[[["VmRedhat", 64], ["EmsRedhat", 1]]], :msg_timeout=>7200, :task_id=>nil}
[----] I, [2015-04-13T07:50:17.753263 #2523:e23eac]  INFO -- : MIQ(MiqQueue.merge)      Message id: [5370],  id: [], Zone: [default], Role: [ems_inventory], Server: [], Ident: [ems_1], Target id: [], Instance id: [], Task id: [], Command: [EmsRefresh.refresh], Timeout: [7200], Priority: [100], State: [ready], Deliver On: [], Data: [], Args: [[["VmRedhat", 64], ["EmsRedhat", 1]]], Requeued
[...]
[----] I, [2015-04-13T07:50:23.278274 #47708:329eac]  INFO -- : MIQ(MiqQueue.get_via_drb) Message id: [5370], MiqWorker id: [54], Zone: [default], Role: [ems_inventory], Server: [], Ident: [ems_1], Tar
get id: [], Instance id: [], Task id: [], Command: [EmsRefresh.refresh], Timeout: [7200], Priority: [100], State: [dequeue], Deliver On: [], Data: [], Args: [[["VmRedhat", 64], ["EmsRedhat", 1]]], Dequ
eued in: [6.12905883] seconds
[----] I, [2015-04-13T07:50:23.278496 #47708:329eac]  INFO -- : MIQ(MiqQueue.deliver)    Message id: [5370], Delivering...
[----] E, [2015-04-13T07:50:23.287609 #47708:329eac] ERROR -- : MIQ(MiqQueue.deliver)    Message id: [5370], Error: [undefined method `emstype' for nil:NilClass]
[----] E, [2015-04-13T07:50:23.287837 #47708:329eac] ERROR -- : [NoMethodError]: undefined method `emstype' for nil:NilClass  Method:[rescue in deliver]
[----] E, [2015-04-13T07:50:23.287981 #47708:329eac] ERROR -- : /var/www/miq/vmdb/app/models/ems_refresh.rb:73:in `block in refresh'
/var/www/miq/vmdb/app/models/ems_refresh.rb:67:in `each'
/var/www/miq/vmdb/app/models/ems_refresh.rb:67:in `group_by'
/var/www/miq/vmdb/app/models/ems_refresh.rb:67:in `refresh'
/var/www/miq/vmdb/app/models/miq_queue.rb:356:in `block in deliver'
/opt/rh/ruby200/root/usr/share/ruby/timeout.rb:66:in `timeout'
/var/www/miq/vmdb/app/models/miq_queue.rb:352:in `deliver'
/var/www/miq/vmdb/lib/workers/queue_worker_base.rb:107:in `deliver_queue_message'
/var/www/miq/vmdb/lib/workers/queue_worker_base.rb:135:in `deliver_message'
/var/www/miq/vmdb/lib/workers/queue_worker_base.rb:152:in `block in do_work'
/var/www/miq/vmdb/lib/workers/queue_worker_base.rb:146:in `loop'
/var/www/miq/vmdb/lib/workers/queue_worker_base.rb:146:in `do_work'
/var/www/miq/vmdb/lib/workers/worker_base.rb:323:in `block in do_work_loop'
/var/www/miq/vmdb/lib/workers/worker_base.rb:320:in `loop'
/var/www/miq/vmdb/lib/workers/worker_base.rb:320:in `do_work_loop'
/var/www/miq/vmdb/lib/workers/worker_base.rb:141:in `run'
/var/www/miq/vmdb/lib/workers/worker_base.rb:122:in `start'
/var/www/miq/vmdb/lib/workers/worker_base.rb:23:in `start_worker'
/var/www/miq/vmdb/lib/workers/bin/worker.rb:3:in `<top (required)>'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands/runner.rb:52:in `eval'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands/runner.rb:52:in `<top (required)>'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:64:in `require'
/opt/rh/cfme-gemset/bundler/gems/rails-8f014fba21f9/railties/lib/rails/commands.rb:64:in `<top (required)>'
script/rails:6:in `require'
script/rails:6:in `<main>'
[----] I, [2015-04-13T07:50:23.288322 #47708:329eac]  INFO -- : MIQ(MiqQueue.delivered)  Message id: [5370], State: [error], Delivered in [0.009827711] seconds
[----] I, [2015-04-13T07:50:24.459906 #2551:104fe9c]  INFO -- : MIQ(ScheduleWorker.do_work) Number of scheduled items to be processed: 5.

Will try to update with reproducer