Bug 1340092

Summary: SmartState failing on RHEV 3.6 - undefined method `attributes' for nil:NilClass
Product: Red Hat CloudForms Management Engine Reporter: ncatling
Component: SmartState AnalysisAssignee: Hui Song <hsong>
Status: CLOSED DEFERRED QA Contact: Satyajit Bulage <sbulage>
Severity: high Docs Contact:
Priority: high    
Version: 5.5.0CC: cpelland, dajohnso, hsong, jhardy, joesmit, ncatling, obarenbo, roliveri, sbulage
Target Milestone: GAKeywords: ZStream
Target Release: 5.7.0   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard: rhev:smartstate
Fixed In Version: Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of:
: 1343219 1343220 (view as bug list) Environment:
Last Closed: 2016-09-06 15:13:59 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Bug Depends On:    
Bug Blocks: 1343219, 1343220    

Description ncatling 2016-05-26 12:39:20 UTC 
Description of problem:

SmartState failing on RHEV 3.6

ERROR -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Vm#perform_metadata_scan): undefined method `attributes' for nil:NilClass
DEBUG -- : /var/www/miq/vmdb/gems/pending/MiqVm/MiqRhevmVm.rb:28:in `getCfg'
/var/www/miq/vmdb/gems/pending/MiqVm/MiqVm.rb:48:in `initialize'
/var/www/miq/vmdb/app/models/manageiq/providers/redhat/infra_manager/vm_or_template_shared/scanning.rb:16:in `new'



Version-Release number of selected component (if applicable):
CFME 5.5.4.1
Red Hat Enterprise Virtualization Manager Version: 3.6.5.3-0.1.el6

How reproducible:

Steps to Reproduce:
1. Initiate SSA
2. Failure present in UI task/evm.log
3.

Actual results:


Expected results:


Additional info:

Stack trace:

[----] D, [2016-05-26T12:44:43.581788 #10378:ca998c] DEBUG -- : MiqVm::initialize: setting @ost.miqRhevmVm = NilClass
[----] E, [2016-05-26T12:44:43.581875 #10378:ca998c] ERROR -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Vm#perform_metadata_scan): undefined method `attributes' for nil:NilClass
[----] D, [2016-05-26T12:44:43.581960 #10378:ca998c] DEBUG -- : /var/www/miq/vmdb/gems/pending/MiqVm/MiqRhevmVm.rb:28:in `getCfg'
/var/www/miq/vmdb/gems/pending/MiqVm/MiqVm.rb:48:in `initialize'
/var/www/miq/vmdb/app/models/manageiq/providers/redhat/infra_manager/vm_or_template_shared/scanning.rb:16:in `new'
/var/www/miq/vmdb/app/models/manageiq/providers/redhat/infra_manager/vm_or_template_shared/scanning.rb:16:in `perform_metadata_scan'
/var/www/miq/vmdb/app/models/miq_server/server_smart_proxy.rb:123:in `scan_metadata'
/var/www/miq/vmdb/app/models/miq_queue.rb:345:in `block in deliver'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:89:in `block in timeout'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:34:in `block in catch'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:34:in `catch'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:34:in `catch'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:104:in `timeout'
/var/www/miq/vmdb/app/models/miq_queue.rb:341:in `deliver'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:106:in `deliver_queue_message'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:134:in `deliver_message'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:151:in `block in do_work'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:145:in `loop'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:145:in `do_work'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:334:in `block in do_work_loop'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:331:in `loop'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:331:in `do_work_loop'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:153:in `run'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:128:in `start'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:29:in `start_worker'
/var/www/miq/vmdb/lib/workers/bin/worker.rb:2:in `<top (required)>'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/runner.rb:60:in `load'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/runner.rb:60:in `<top (required)>'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/commands_tasks.rb:123:in `require'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/commands_tasks.rb:123:in `require_command!'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/commands_tasks.rb:90:in `runner'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/commands_tasks.rb:39:in `run_command!'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands.rb:17:in `<top (required)>'
/var/www/miq/vmdb/bin/rails:4:in `require'
/var/www/miq/vmdb/bin/rails:4:in `<main>'
[----] E, [2016-05-26T12:44:43.582019 #10378:ca998c] ERROR -- : MIQ(MiqServer#scan_metadata) undefined method `attributes' for nil:NilClass
[----] D, [2016-05-26T12:44:43.582060 #10378:ca998c] DEBUG -- : MIQ(MiqServer#scan_metadata) /var/www/miq/vmdb/gems/pending/MiqVm/MiqRhevmVm.rb:28:in `getCfg'
/var/www/miq/vmdb/gems/pending/MiqVm/MiqVm.rb:48:in `initialize'
/var/www/miq/vmdb/app/models/manageiq/providers/redhat/infra_manager/vm_or_template_shared/scanning.rb:16:in `new'
/var/www/miq/vmdb/app/models/manageiq/providers/redhat/infra_manager/vm_or_template_shared/scanning.rb:16:in `perform_metadata_scan'
/var/www/miq/vmdb/app/models/miq_server/server_smart_proxy.rb:123:in `scan_metadata'
/var/www/miq/vmdb/app/models/miq_queue.rb:345:in `block in deliver'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:89:in `block in timeout'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:34:in `block in catch'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:34:in `catch'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:34:in `catch'
/opt/rh/rh-ruby22/root/usr/share/ruby/timeout.rb:104:in `timeout'
/var/www/miq/vmdb/app/models/miq_queue.rb:341:in `deliver'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:106:in `deliver_queue_message'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:134:in `deliver_message'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:151:in `block in do_work'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:145:in `loop'
/var/www/miq/vmdb/app/models/miq_queue_worker_base/runner.rb:145:in `do_work'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:334:in `block in do_work_loop'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:331:in `loop'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:331:in `do_work_loop'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:153:in `run'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:128:in `start'
/var/www/miq/vmdb/app/models/miq_worker/runner.rb:29:in `start_worker'
/var/www/miq/vmdb/lib/workers/bin/worker.rb:2:in `<top (required)>'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/runner.rb:60:in `load'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/runner.rb:60:in `<top (required)>'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/commands_tasks.rb:123:in `require'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/commands_tasks.rb:123:in `require_command!'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/commands_tasks.rb:90:in `runner'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands/commands_tasks.rb:39:in `run_command!'
/opt/rh/cfme-gemset/gems/railties-4.2.6/lib/rails/commands.rb:17:in `<top (required)>'
/var/www/miq/vmdb/bin/rails:4:in `require'
/var/www/miq/vmdb/bin/rails:4:in `<main>'
[----] I, [2016-05-26T12:44:43.586673 #10378:ca998c]  INFO -- : MIQ(VmScan#scanning) .
[----] I, [2016-05-26T12:44:43.595282 #3568:b35998]  INFO -- : <AutomationEngine> Following Relationship [miqaedb:/System/event_handlers/event_enforce_policy#create]
[----] I, [2016-05-26T12:44:43.603507 #3568:b35998]  INFO -- : <AutomationEngine> Updated namespace [miqaedb:/System/event_handlers/event_enforce_policy#create  ManageIQ/System]
[----] I, [2016-05-26T12:44:43.606499 #10378:ca998c]  INFO -- : <AutomationEngine> MiqAeEvent.build_evm_event >> event=<"vm_scan_abort"> inputs=<{:vm=>#<ManageIQ::Providers::Redhat::InfraManager::Vm id: 1000000048659, vendor: "redhat", format: nil, version: nil, name: "test001", description: nil, location: "deda434b-3e90-4495-abd1-e77a77b097c7.ovf", config_xml: nil, autostart: nil, host_id: nil, last_sync_on: nil, created_on: "2016-05-26 11:44:12", updated_on: "2016-05-26 11:44:28", storage_id: 1000000000019, guid: "2ab7e244-2337-11e6-a579-001a4a05005c", ems_id: 1000000000002, last_scan_on: nil, last_scan_attempt_on: "2016-05-26 11:44:28", uid_ems: "deda434b-3e90-4495-abd1-e77a77b097c7", retires_on: nil, retired: nil, boot_time: nil, tools_status: nil, standby_action: nil, power_state: "off", state_changed_on: "2016-05-26 11:44:12", previous_state: nil, connection_state: "connected", last_perf_capture_on: nil, registered: nil, busy: nil, smart: nil, memory_reserve: nil, memory_reserve_expand: nil, memory_limit: nil, memory_shares: nil, memory_shares_level: nil, cpu_reserve: nil, cpu_reserve_expand: nil, cpu_limit: nil, cpu_shares: nil, cpu_shares_level: nil, cpu_affinity: nil, ems_created_on: nil, template: false, evm_owner_id: nil, ems_ref_obj: "--- \"/api/vms/deda434b-3e90-4495-abd1-e77a77b097c7...", miq_group_id: 1000000000001, linked_clone: nil, fault_tolerance: nil, type: "ManageIQ::Providers::Redhat::InfraManager::Vm", ems_ref: "/api/vms/deda434b-3e90-4495-abd1-e77a77b097c7", ems_cluster_id: 1000000000004, retirement_warn: nil, retirement_last_warn: nil, vnc_port: nil, flavor_id: nil, availability_zone_id: nil, cloud: false, retirement_state: nil, cloud_network_id: nil, cloud_subnet_id: nil, cloud_tenant_id: nil, raw_power_state: "down", publicly_available: nil, orchestration_stack_id: nil, retirement_requester: nil, tenant_id: 1000000000001, resource_group_id: nil>, :host=>nil, "MiqEvent::miq_event"=>1000000071674, :miq_event_id=>1000000071674, "EventStream::event_stream"=>1000000071674, :event_stream_id=>1000000071674}>
[----] I, [2016-05-26T12:44:43.612668 #10378:ca998c]  INFO -- : MIQ(User#current_group=) User: [admin] Assigning Role: [EvmRole-tenant_administrator] from Group: [Tenant My Company access]
[----] I, [2016-05-26T12:44:43.621980 #3568:b35998]  INFO -- : <AutomationEngine> Updated namespace [System/event_handlers/event_enforce_policy  ManageIQ/System]
[----] I, [2016-05-26T12:44:43.630560 #10378:ca998c]  INFO -- : MIQ(MiqQueue.put) Message id: [1000000582014],  id: [], Zone: [RHEV-DEV02], Role: [automate], Server: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqAeEngine.deliver], Timeout: [3600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: [{:object_type=>"ManageIQ::Providers::Redhat::InfraManager::Vm", :object_id=>1000000048659, :attrs=>{:event_type=>"vm_scan_abort", "VmOrTemplate::vm"=>1000000048659, :vm_id=>1000000048659, :host=>nil, "MiqEvent::miq_event"=>1000000071674, :miq_event_id=>1000000071674, "EventStream::event_stream"=>1000000071674, :event_stream_id=>1000000071674}, :instance_name=>"Event", :user_id=>1000000000001, :miq_group_id=>1000000000001, :tenant_id=>1000000000001, :automate_message=>nil}]
[----] E, [2016-05-26T12:44:43.630646 #10378:ca998c] ERROR -- : MIQ(VmScan#process_abort) job aborting, undefined method `attributes' for nil:NilClass
Comment 24 Satyajit Bulage 2016-08-04 09:06:48 UTC 
Hello Hui,

Unfortunately, that appliance is not available now but iscsi lun was attached to it.

Thanks,
Satyajit Bulage
Comment 25 Hui Song 2016-08-04 13:36:23 UTC 
(In reply to Satyajit Bulage from comment #24)
> Hello Hui,
> 
> Unfortunately, that appliance is not available now but iscsi lun was
> attached to it.
> 
> Thanks,
> Satyajit Bulage

That's ok, I just want to confirm it's the reason.
Thanks,
Comment 26 Hui Song 2016-09-06 15:13:59 UTC 
Issue closed because it cannot be reproduced in both DEV and QE side. Feel free to reopen if it happens again.