ems refresh timings

ems_refresh.txt

# on searching for ':ems_refresh=>'
...
...

[----] I, [2017-06-08T05:12:36.116510 #4392:ee3130]  INFO -- : MIQ(ManageIQ::Providers::Openshift::ContainerManager::Refresher#refresh) 
EMS: [ocp new], id: [1] Refreshing targets for EMS...Complete - Timings {:server_dequeue=>0.003165721893310547, :collect_inventory_for_targets=>0.013364315032958984, :parse_legacy_inventory=>93.21573758125305, 
:parse_targeted_inventory=>93.21653270721436, :save_inventory=>485.05302691459656, :ems_refresh=>578.2846529483795}

[----] I, [2017-06-08T05:42:20.890895 #4392:ee3130]  INFO -- : MIQ(ManageIQ::Providers::Openshift::ContainerManager::Refresher#refresh) 
EMS: [ocp new], id: [1] Refreshing targets for EMS...Complete - Timings {:server_dequeue=>0.003165721893310547, :collect_inventory_for_targets=>0.01921844482421875, :parse_legacy_inventory=>127.7628972530365, :
parse_targeted_inventory=>127.76371574401855, :save_inventory=>678.6209514141083, :ems_refresh=>806.4065256118774}

[----] I, [2017-06-08T05:57:38.434290 #4392:ee3130]  INFO -- : MIQ(ManageIQ::Providers::Openshift::ContainerManager::Refresher#refresh) 
EMS: [ocp new], id: [1] Refreshing targets for EMS...Complete - Timings 
{:server_dequeue=>0.003165721893310547, 
:collect_inventory_for_targets=>0.021965503692626953, 
:parse_legacy_inventory=>145.39008927345276,
 :parse_targeted_inventory=>145.3909351825714, 
 :save_inventory=>785.0217490196228, 
 :ems_refresh=>930.4375877380371}

[----] I, [2017-06-08T06:12:45.964359 #4392:ee3130]  INFO -- : MIQ(ManageIQ::Providers::Openshift::ContainerManager::Refresher#refresh) EMS: [ocp new], id: [1] 
Refreshing targets for EMS...Complete - Timings 
{:server_dequeue=>0.003165721893310547, 
:collect_inventory_for_targets=>0.024770021438598633, 
:parse_legacy_inventory=>165.14575099945068, 
:parse_targeted_inventory=>165.14660954475403, 
:save_inventory=>889.3919661045074, 
:ems_refresh=>1054.5666847229004}

...
...

========
# on searching for 'EmsRefresh.refresh'
...
...

[----] I, [2017-06-08T04:13:45.175404 #4392:ee3130]  INFO -- : MIQ(MiqQueue.put) Message id: [7232361],  id: [], Zone: [Test], 
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: [[["ManageIQ::Providers::Openshift::ContainerManager", 1]]]

[----] I, [2017-06-08T04:13:55.498724 #4392:ee3130]  INFO -- : MIQ(MiqQueue#deliver) Message id: [7232361], Delivering...

[----] I, [2017-06-08T04:15:47.975934 #4392:ee3130]  INFO -- : MIQ(MiqQueue#delivered) Message id: [7232361], 
State: [ok], Delivered in [112.477222319] seconds

[----] W, [2017-06-08T04:15:47.976612 #4392:ee3130]  WARN -- : MIQ(MiqQueue#m_callback) Message id: [7232361], Instance: [MiqTask], does not respond to Method: [queue_callback], skipping
...
...