Description of problem: Widget generation error for the restricted vm user is noticed in the evm.log file Version-Release number of selected component (if applicable): 5.4.0.0.12.20150312013923_aa9b093 How reproducible: 100% Steps to Reproduce: 1. As Admin, Navigate to Configure ==> Configuration ==> Access Control 2. Create a new role with "VM & Template Access Restriction" as "Only User or Group Owned" or "Only User Owned". Make sure all the module access is given in "Product Features (Editing)" i.e., Everything is checked 3. Create a new group with the above role 4. Create a new user with the above group 5. Now navigate to Infrastructure ==> virtual Machines ==> Choose any vm ==> Configuration ==> Set Ownership to the above created user 6. Login with the newly created user Actual results: Throws widget generation error is noticed in the evm.log file. Note: ====== This happens only for the first time. Expected results: Should not throw any widget related error in the evm.log file Additional info: evm.log: ======== [----] I, [2015-03-17T13:47:49.074324 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [Vendor and Guest OS Chart] ID: [3] for group: [ramesh-group] users: [ramesh]... [----] I, [2015-03-17T13:47:49.104864 #2271:45dea4] INFO -- : MIQ(Schedule.next_interval_time) sch_start_time: 2015-03-13 23:00:00 UTC [----] I, [2015-03-17T13:47:49.105370 #2271:45dea4] INFO -- : MIQ(Schedule.next_interval_time) next_time: 2015-03-17 18:00:00 UTC [----] I, [2015-03-17T13:47:49.178074 #2262:c23ea4] INFO -- : MIQ(User#current_group=) User: [ramesh] Assigning Role: [ramesh-role] from Group: [ramesh-group] [----] I, [2015-03-17T13:47:49.186276 #2271:45dea4] INFO -- : MIQ(Schedule.next_interval_time) sch_start_time: 2015-03-14 00:00:00 UTC [----] I, [2015-03-17T13:47:49.187005 #2271:45dea4] INFO -- : MIQ(Schedule.next_interval_time) next_time: 2015-03-18 00:00:00 UTC [----] I, [2015-03-17T13:47:49.214389 #2262:c23ea4] INFO -- : MIQ(MiqReport-build_create_results) Creating report results with hash: [{:name=>"Vendor and Guest OS", :userid=>"widget_id_3|ramesh|schedule", :report_source=>"Generated for widget", :db=>"Vm", :last_run_on=>2015-03-17 17:47:49 UTC, :last_accessed_on=>2015-03-17 17:47:49 UTC, :miq_report_id=>nil}] [----] I, [2015-03-17T13:47:49.304494 #2262:c23ea4] INFO -- : MIQ(MiqReport-build_create_results) Finished creating report result with id [7] for report id: [], name: [Vendor and Guest OS] [----] E, [2015-03-17T13:47:49.324397 #2262:c23ea4] ERROR -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [Vendor and Guest OS Chart] ID: [3] Failed for [User] [Ramesh A] with error: [NoMethodError] [undefined method `flatten' for nil:NilClass] [----] E, [2015-03-17T13:47:49.324850 #2262:c23ea4] ERROR -- : [NoMethodError]: undefined method `flatten' for nil:NilClass Method:[rescue in generate_one_content_for_user] [----] E, [2015-03-17T13:47:49.324988 #2262:c23ea4] ERROR -- : /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:456:in `instance_eval' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/support/filter.rb:21:in `each' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/support/filter.rb:21:in `block in flatten' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:170:in `call' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:170:in `_nested_structures' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:63:in `tag!' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:88:in `method_missing' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/support/filter.rb:20:in `flatten' (eval):1:in `block in setup_lnf' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:456:in `instance_eval' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:456:in `block in setup_lnf' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:450:in `each' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:450:in `setup_lnf' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:255:in `block in to_s' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:170:in `call' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:170:in `_nested_structures' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:63:in `tag!' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:88:in `method_missing' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:244:in `to_s' /var/www/miq/vmdb/lib/report_formatter/zgraph.rb:59:in `finalize_document' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:598:in `maybe' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:594:in `finalize' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:577:in `_run_' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:520:in `run' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:436:in `render' /var/www/miq/vmdb/app/models/miq_report/formatters/graph.rb:68:in `to_chart' /var/www/miq/vmdb/app/models/miq_widget/chart_content.rb:8:in `generate' /var/www/miq/vmdb/app/models/miq_widget.rb:345:in `generate_one_content_for_user' /var/www/miq/vmdb/app/models/miq_widget/content_generator.rb:27:in `block in determine_content' /var/www/miq/vmdb/app/models/miq_widget/content_generator.rb:27:in `collect' /var/www/miq/vmdb/app/models/miq_widget/content_generator.rb:27:in `determine_content' /var/www/miq/vmdb/app/models/miq_widget/content_generator.rb:6:in `generate' /var/www/miq/vmdb/app/models/miq_widget.rb:277:in `generate_content' /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:317:in `block in do_work_loop' /var/www/miq/vmdb/lib/workers/worker_base.rb:314:in `loop' /var/www/miq/vmdb/lib/workers/worker_base.rb:314:in `do_work_loop' /var/www/miq/vmdb/lib/workers/worker_base.rb:135:in `run' /var/www/miq/vmdb/lib/workers/worker_base.rb:123:in `start' /var/www/miq/vmdb/lib/workers/worker_base.rb:23:in `start_worker' /var/www/miq/vmdb/lib/workers/bin/worker.rb:5: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>' [----] E, [2015-03-17T13:47:49.325199 #2262:c23ea4] ERROR -- : MIQ(MiqWidget.generate_content) Widget: [Vendor and Guest OS Chart] ID: [3] Expected 1 contents, received 0 contents for ["ramesh"] [----] E, [2015-03-17T13:47:49.325610 #2262:c23ea4] ERROR -- : MIQ(MiqQueue.deliver) Message id: [23022], Error: [Expected 1 contents, received 0 contents for ["ramesh"]] [----] I, [2015-03-17T13:47:49.325976 #2262:c23ea4] INFO -- : MIQ(MiqQueue.delivered) Message id: [23022], State: [error], Delivered in [0.292428463] seconds [----] I, [2015-03-17T13:47:49.329132 #2262:c23ea4] INFO -- : MIQ(MiqQueue.m_callback) Message id: [23022], Invoking Callback with args: ["error", "Expected 1 contents, received 0 contents for [\"ramesh\"]", "nil"] [----] I, [2015-03-17T13:47:49.329313 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_content_complete_callback) Widget ID: [3], MiqTask ID: [12], Status: [error] [----] I, [2015-03-17T13:47:49.333236 #2262:c23ea4] INFO -- : MIQ(MiqTask.update_status) Task: [12] [Finished] [Error] [Widget Generation for 1 groups complete (1 in Error)] [----] I, [2015-03-17T13:47:49.396185 #2262:c23ea4] INFO -- : MIQ(MiqQueue.get_via_drb) Message id: [23023], MiqWorker id: [50], Zone: [], Role: [reporting], Server: [], Ident: [reporting], Target id: [], Instance id: [8], Task id: [], Command: [MiqWidget.generate_content], Timeout: [3600], Priority: [100], State: [dequeue], Deliver On: [], Data: [], Args: ["User", "ramesh-group", ["ramesh"], nil], Dequeued in: [2.348562516] seconds [----] I, [2015-03-17T13:47:49.396433 #2262:c23ea4] INFO -- : MIQ(MiqQueue.deliver) Message id: [23023], Delivering... [----] I, [2015-03-17T13:47:49.416177 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [EVM: Recently Discovered Hosts] ID: [8] for group: [ramesh-group] users: [ramesh]... [----] I, [2015-03-17T13:47:49.506807 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [EVM: Recently Discovered Hosts] ID: [8] for [ramesh-group] [ramesh]...Complete [----] I, [2015-03-17T13:47:49.507494 #2262:c23ea4] INFO -- : MIQ(MiqQueue.delivered) Message id: [23023], State: [ok], Delivered in [0.111046176] seconds [----] I, [2015-03-17T13:47:49.511879 #2262:c23ea4] INFO -- : MIQ(MiqQueue.m_callback) Message id: [23023], Invoking Callback with args: ["ok", "Message delivered successfully", "[#<MiqWidgetContent id: 7, miq_widget_id: 8, miq_report_result_id: nil, contents: \"<table class=\\\"style3\\\"><tbody><tr class=\\\"row0\\\" oncli...\", created_at: \"2015-03-17 17:47:49\", updated_at: \"2015-03-17 17:47:49\", timezone: \"UTC\", miq_group_id: 13, user_id: 2>]"] [----] I, [2015-03-17T13:47:49.512114 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_content_complete_callback) Widget ID: [8], MiqTask ID: [13], Status: [ok] [----] I, [2015-03-17T13:47:49.517354 #2262:c23ea4] INFO -- : MIQ(MiqTask.update_status) Task: [13] [Finished] [Ok] [Widget Generation for 1 groups complete] [----] I, [2015-03-17T13:47:49.602050 #2262:c23ea4] INFO -- : MIQ(MiqQueue.get_via_drb) Message id: [23024], MiqWorker id: [50], Zone: [], Role: [reporting], Server: [], Ident: [reporting], Target id: [], Instance id: [6], Task id: [], Command: [MiqWidget.generate_content], Timeout: [3600], Priority: [100], State: [dequeue], Deliver On: [], Data: [], Args: ["User", "ramesh-group", ["ramesh"], nil], Dequeued in: [2.446202736] seconds [----] I, [2015-03-17T13:47:49.602313 #2262:c23ea4] INFO -- : MIQ(MiqQueue.deliver) Message id: [23024], Delivering... [----] I, [2015-03-17T13:47:49.642730 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [Top Memory Consumers (weekly)] ID: [6] for group: [ramesh-group] users: [ramesh]... [----] I, [2015-03-17T13:47:49.886461 #2262:c23ea4] INFO -- : MIQ(MiqReport-build_create_results) Creating report results with hash: [{:name=>"Top Memory Consumers (weekly)", :userid=>"widget_id_6|ramesh|schedule", :report_source=>"Generated for widget", :db=>"VmPerformance", :last_run_on=>2015-03-17 17:47:49 UTC, :last_accessed_on=>2015-03-17 17:47:49 UTC, :miq_report_id=>nil}] [----] I, [2015-03-17T13:47:49.950785 #2262:c23ea4] INFO -- : MIQ(MiqReport-build_create_results) Finished creating report result with id [8] for report id: [], name: [Top Memory Consumers (weekly)] [----] I, [2015-03-17T13:47:49.975635 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [Top Memory Consumers (weekly)] ID: [6] for [ramesh-group] [ramesh]...Complete [----] I, [2015-03-17T13:47:49.976386 #2262:c23ea4] INFO -- : MIQ(MiqQueue.delivered) Message id: [23024], State: [ok], Delivered in [0.374067598] seconds [----] I, [2015-03-17T13:47:49.979530 #2262:c23ea4] INFO -- : MIQ(MiqQueue.m_callback) Message id: [23024], Invoking Callback with args: ["ok", "Message delivered successfully", "[#<MiqWidgetContent id: 8, miq_widget_id: 6, miq_report_result_id: 8, contents: \"<table class='style3'><thead><tr><th class='title'>...\", created_at: \"2015-03-17 17:47:49\", updated_at: \"2015-03-17 17:47:49\", timezone: \"UTC\", miq_group_id: 13, user_id: 2>]"] [----] I, [2015-03-17T13:47:49.979725 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_content_complete_callback) Widget ID: [6], MiqTask ID: [14], Status: [ok] [----] I, [2015-03-17T13:47:49.983985 #2262:c23ea4] INFO -- : MIQ(MiqTask.update_status) Task: [14] [Finished] [Ok] [Widget Generation for 1 groups complete] [----] I, [2015-03-17T13:47:50.057383 #2262:c23ea4] INFO -- : MIQ(MiqQueue.get_via_drb) Message id: [23025], MiqWorker id: [50], Zone: [], Role: [reporting], Server: [], Ident: [reporting], Target id: [], Instance id: [5], Task id: [], Command: [MiqWidget.generate_content], Timeout: [3600], Priority: [100], State: [dequeue], Deliver On: [], Data: [], Args: ["User", "ramesh-group", ["ramesh"], nil], Dequeued in: [2.787856328] seconds [----] I, [2015-03-17T13:47:50.057681 #2262:c23ea4] INFO -- : MIQ(MiqQueue.deliver) Message id: [23025], Delivering... [----] I, [2015-03-17T13:47:50.085489 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [Top CPU Consumers (weekly)] ID: [5] for group: [ramesh-group] users: [ramesh]... [----] I, [2015-03-17T13:47:50.122868 #2262:c23ea4] INFO -- : MIQ(MiqReport-build_create_results) Creating report results with hash: [{:name=>"Top CPU Consumers (weekly)", :userid=>"widget_id_5|ramesh|schedule", :report_source=>"Generated for widget", :db=>"VmPerformance", :last_run_on=>2015-03-17 17:47:50 UTC, :last_accessed_on=>2015-03-17 17:47:50 UTC, :miq_report_id=>nil}] [----] I, [2015-03-17T13:47:50.192224 #2262:c23ea4] INFO -- : MIQ(MiqReport-build_create_results) Finished creating report result with id [9] for report id: [], name: [Top CPU Consumers (weekly)] [----] I, [2015-03-17T13:47:50.210972 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [Top CPU Consumers (weekly)] ID: [5] for [ramesh-group] [ramesh]...Complete [----] I, [2015-03-17T13:47:50.211548 #2262:c23ea4] INFO -- : MIQ(MiqQueue.delivered) Message id: [23025], State: [ok], Delivered in [0.153866962] seconds [----] I, [2015-03-17T13:47:50.214083 #2262:c23ea4] INFO -- : MIQ(MiqQueue.m_callback) Message id: [23025], Invoking Callback with args: ["ok", "Message delivered successfully", "[#<MiqWidgetContent id: 9, miq_widget_id: 5, miq_report_result_id: 9, contents: \"<table class='style3'><thead><tr><th class='title'>...\", created_at: \"2015-03-17 17:47:50\", updated_at: \"2015-03-17 17:47:50\", timezone: \"UTC\", miq_group_id: 13, user_id: 2>]"] [----] I, [2015-03-17T13:47:50.214265 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_content_complete_callback) Widget ID: [5], MiqTask ID: [15], Status: [ok] [----] I, [2015-03-17T13:47:50.217380 #2262:c23ea4] INFO -- : MIQ(MiqTask.update_status) Task: [15] [Finished] [Ok] [Widget Generation for 1 groups complete] [----] I, [2015-03-17T13:47:50.284030 #2262:c23ea4] INFO -- : MIQ(MiqQueue.get_via_drb) Message id: [23026], MiqWorker id: [50], Zone: [], Role: [reporting], Server: [], Ident: [reporting], Target id: [], Instance id: [4], Task id: [], Command: [MiqWidget.generate_content], Timeout: [3600], Priority: [100], State: [dequeue], Deliver On: [], Data: [], Args: ["User", "ramesh-group", ["ramesh"], nil], Dequeued in: [2.889014336] seconds [----] I, [2015-03-17T13:47:50.284428 #2262:c23ea4] INFO -- : MIQ(MiqQueue.deliver) Message id: [23026], Delivering... [----] I, [2015-03-17T13:47:50.312807 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [Virtual Infrastructure Platforms] ID: [4] for group: [ramesh-group] users: [ramesh]... [----] I, [2015-03-17T13:47:50.336062 #2262:c23ea4] INFO -- : MIQ(User#current_group=) User: [ramesh] Assigning Role: [ramesh-role] from Group: [ramesh-group] [----] I, [2015-03-17T13:47:50.356336 #2262:c23ea4] INFO -- : MIQ(MiqReport-build_create_results) Creating report results with hash: [{:name=>"Virtual Infrastructure Platforms", :userid=>"widget_id_4|ramesh|schedule", :report_source=>"Generated for widget", :db=>"Host", :last_run_on=>2015-03-17 17:47:50 UTC, :last_accessed_on=>2015-03-17 17:47:50 UTC, :miq_report_id=>nil}] [----] I, [2015-03-17T13:47:51.015218 #2262:c23ea4] INFO -- : MIQ(MiqReport-build_create_results) Finished creating report result with id [10] for report id: [], name: [Virtual Infrastructure Platforms] [----] E, [2015-03-17T13:47:51.031200 #2262:c23ea4] ERROR -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [Virtual Infrastructure Platforms] ID: [4] Failed for [User] [Ramesh A] with error: [NoMethodError] [undefined method `flatten' for nil:NilClass] [----] E, [2015-03-17T13:47:51.031584 #2262:c23ea4] ERROR -- : [NoMethodError]: undefined method `flatten' for nil:NilClass Method:[rescue in generate_one_content_for_user] [----] E, [2015-03-17T13:47:51.031752 #2262:c23ea4] ERROR -- : /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:456:in `instance_eval' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/support/filter.rb:21:in `each' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/support/filter.rb:21:in `block in flatten' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:170:in `call' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:170:in `_nested_structures' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:63:in `tag!' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:88:in `method_missing' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/support/filter.rb:20:in `flatten' (eval):1:in `block in setup_lnf' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:456:in `instance_eval' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:456:in `block in setup_lnf' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:450:in `each' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:450:in `setup_lnf' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:255:in `block in to_s' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:170:in `call' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:170:in `_nested_structures' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:63:in `tag!' /opt/rh/cfme-gemset/gems/builder-3.0.4/lib/builder/xmlbase.rb:88:in `method_missing' /opt/rh/cfme-gemset/bundler/gems/ziya-597cc777c24f/lib/ziya/charts/base.rb:244:in `to_s' /var/www/miq/vmdb/lib/report_formatter/zgraph.rb:59:in `finalize_document' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:598:in `maybe' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:594:in `finalize' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:577:in `_run_' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:520:in `run' /opt/rh/cfme-gemset/bundler/gems/ruport-3ba69305f42e/lib/ruport/controller.rb:436:in `render' /var/www/miq/vmdb/app/models/miq_report/formatters/graph.rb:68:in `to_chart' /var/www/miq/vmdb/app/models/miq_widget/chart_content.rb:8:in `generate' /var/www/miq/vmdb/app/models/miq_widget.rb:345:in `generate_one_content_for_user' /var/www/miq/vmdb/app/models/miq_widget/content_generator.rb:27:in `block in determine_content' /var/www/miq/vmdb/app/models/miq_widget/content_generator.rb:27:in `collect' /var/www/miq/vmdb/app/models/miq_widget/content_generator.rb:27:in `determine_content' /var/www/miq/vmdb/app/models/miq_widget/content_generator.rb:6:in `generate' /var/www/miq/vmdb/app/models/miq_widget.rb:277:in `generate_content' /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:317:in `block in do_work_loop' /var/www/miq/vmdb/lib/workers/worker_base.rb:314:in `loop' /var/www/miq/vmdb/lib/workers/worker_base.rb:314:in `do_work_loop' /var/www/miq/vmdb/lib/workers/worker_base.rb:135:in `run' /var/www/miq/vmdb/lib/workers/worker_base.rb:123:in `start' /var/www/miq/vmdb/lib/workers/worker_base.rb:23:in `start_worker' /var/www/miq/vmdb/lib/workers/bin/worker.rb:5: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>' [----] E, [2015-03-17T13:47:51.031968 #2262:c23ea4] ERROR -- : MIQ(MiqWidget.generate_content) Widget: [Virtual Infrastructure Platforms] ID: [4] Expected 1 contents, received 0 contents for ["ramesh"] [----] E, [2015-03-17T13:47:51.032410 #2262:c23ea4] ERROR -- : MIQ(MiqQueue.deliver) Message id: [23026], Error: [Expected 1 contents, received 0 contents for ["ramesh"]] [----] I, [2015-03-17T13:47:51.032766 #2262:c23ea4] INFO -- : MIQ(MiqQueue.delivered) Message id: [23026], State: [error], Delivered in [0.748323716] seconds [----] I, [2015-03-17T13:47:51.035540 #2262:c23ea4] INFO -- : MIQ(MiqQueue.m_callback) Message id: [23026], Invoking Callback with args: ["error", "Expected 1 contents, received 0 contents for [\"ramesh\"]", "nil"] [----] I, [2015-03-17T13:47:51.035704 #2262:c23ea4] INFO -- : MIQ(MiqWidget.generate_content_complete_callback) Widget ID: [4], MiqTask ID: [16], Status: [error] [----] I, [2015-03-17T13:47:51.468663 #2268:a33ea0] INFO -- : MIQ(ScheduleWorker.do_work) Number of scheduled items to be processed: 1. [----] I, [2015-03-17T13:47:51.662997 #2262:c23ea4] INFO -- : MIQ(MiqTask.update_status) Task: [16] [Finished] [Error] [Widget Generation for 1 groups complete (1 in Error)] [----] I, [2015-03-17T13:47:51.672803 #2268:a33ea0] INFO -- : MIQ(MiqQueue.put) Message id: [23030], id: [], Zone: [default], Role: [smartstate], Server: [], Ident: [generic], Target id: [], Instance id: [], Task id: [job_dispatcher], Command: [JobProxyDispatcher.dispatch], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: [] [----] I, [2015-03-17T13:47:51.673685 #2259:401e9c] INFO -- : MIQ(MiqQueue.get_via_drb) Message id: [23027], MiqWorker id: [49], Zone: [], Role: [reporting], Server: [], Ident: [reporting], Target id: [], Instance id: [1], Task id: [], Command: [MiqWidget.generate_content], Timeout: [3600], Priority: [100], State: [dequeue], Deliver On: [], Data: [], Args: ["User", "ramesh-group", ["ramesh"], nil], Dequeued in: [4.186111462] seconds [----] I, [2015-03-17T13:47:51.673976 #2259:401e9c] INFO -- : MIQ(MiqQueue.deliver) Message id: [23027], Delivering... [----] I, [2015-03-17T13:47:51.712032 #2259:401e9c] INFO -- : MIQ(MiqWidget.generate_one_content_for_user) Widget: [Guest OS Information] ID: [1] for group: [ramesh-group] users: [ramesh]...