Skip to content

Instantly share code, notes, and snippets.

@agrare
Created September 25, 2018 19:22
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save agrare/503feb7ca065e1ad99bd1a2e63217bff to your computer and use it in GitHub Desktop.
Save agrare/503feb7ca065e1ad99bd1a2e63217bff to your computer and use it in GitHub Desktop.
[----] I, [2018-09-25T15:22:07.832907 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) Refreshing all targets...
[----] I, [2018-09-25T15:22:07.832972 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) EMS: [env-rhv42-01-mgr], id: [1] Refreshing targets for EMS...
[----] I, [2018-09-25T15:22:07.833055 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) EMS: [env-rhv42-01-mgr], id: [1] ManageIQ::Providers::Redhat::InfraManager [env-rhv42-01-mgr] id [1]
[----] I, [2018-09-25T15:22:07.833137 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:08.020885 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#collect_inventory_for_targets) Filtering inventory for ManageIQ::Providers::Redhat::InfraManager [env-rhv42-01-mgr] id: [1]...
[----] I, [2018-09-25T15:22:08.023077 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:08.099409 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:08.164601 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#collect_inventory_for_targets) Filtering inventory...Complete
[----] I, [2018-09-25T15:22:08.164970 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:08.239696 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh_targets_for_ems) EMS: [env-rhv42-01-mgr], id: [1] Refreshing target ManageIQ::Providers::Redhat::InfraManager [env-rhv42-01-mgr] id [1]...
[----] D, [2018-09-25T15:22:08.239758 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#parse_targeted_inventory) EMS: [env-rhv42-01-mgr], id: [1] Parsing inventory...
[----] I, [2018-09-25T15:22:08.239948 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] D, [2018-09-25T15:22:09.497910 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#parse_targeted_inventory) EMS: [env-rhv42-01-mgr], id: [1] Parsing inventory...Complete
[----] D, [2018-09-25T15:22:09.498445 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveInventory.save_inventory) EMS: [env-rhv42-01-mgr], id: [1] Scanning Inventory Collections...Start
[----] D, [2018-09-25T15:22:09.501552 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveInventory.save_inventory) EMS: [env-rhv42-01-mgr], id: [1] Scanning Inventory Collections...Complete
[----] I, [2018-09-25T15:22:09.501606 #4134:2ab97e09cf60] INFO -- : MIQ(ManagerRefresh::SaveInventory.save_inventory) EMS: [env-rhv42-01-mgr], id: [1] Saving EMS Inventory...
[----] D, [2018-09-25T15:22:09.506569 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr...
[----] D, [2018-09-25T15:22:09.506959 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Topological sorting of manager env-rhv42-01-mgr resulted in these layers processable in parallel:
digraph {
subgraph cluster_0 { label = "Layer 0";
ems_clusters; // InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id]
resource_pools; // InventoryCollection:<ResourcePool>, blacklist: [ems_children]
miq_templates; // InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Template>, blacklist: [genealogy_parent]
storages; // InventoryCollection:<Storage>
vm_and_template_ems_custom_fields; // InventoryCollection:<CustomAttribute>
lans; // InventoryCollection:<Lan>
datacenters; // InventoryCollection:<Datacenter>, strategy: local_db_find_missing_references
vm_folders; // InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references
host_folders; // InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references
root_folders; // InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references
}
subgraph cluster_1 { label = "Layer 1";
hosts; // InventoryCollection:<Host>
switches; // InventoryCollection:<Switch>
}
subgraph cluster_2 { label = "Layer 2";
vms; // InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Vm>, blacklist: [genealogy_parent]
host_hardwares; // InventoryCollection:<Hardware>
host_operating_systems; // InventoryCollection:<OperatingSystem>
host_storages; // InventoryCollection:<HostStorage>
host_switches; // InventoryCollection:<HostSwitch>
}
subgraph cluster_3 { label = "Layer 3";
host_networks; // InventoryCollection:<Network>
operating_systems; // InventoryCollection:<OperatingSystem>
snapshots; // InventoryCollection:<Snapshot>
ems_folder_children; // InventoryCollection:<ems_folder_children>
ems_cluster_children; // InventoryCollection:<ems_cluster_children>
hardwares; // InventoryCollection:<Hardware>
}
subgraph cluster_4 { label = "Layer 4";
networks; // InventoryCollection:<Network>
snapshot_patent; // InventoryCollection:<snapshot_patent>
disks; // InventoryCollection:<Disk>
}
subgraph cluster_5 { label = "Layer 5";
guest_devices; // InventoryCollection:<GuestDevice>
}
// edges:
ems_clusters -> hosts;
hosts -> vms;
ems_clusters -> vms;
storages -> vms;
hardwares -> disks;
storages -> disks;
vms -> disks;
host_hardwares -> guest_devices;
hardwares -> guest_devices;
switches -> guest_devices;
host_networks -> guest_devices;
networks -> guest_devices;
lans -> guest_devices;
vms -> hardwares;
miq_templates -> hardwares;
hosts -> host_hardwares;
host_hardwares -> host_networks;
hosts -> host_operating_systems;
hosts -> host_storages;
storages -> host_storages;
hosts -> host_switches;
switches -> host_switches;
hardwares -> networks;
vms -> operating_systems;
vms -> snapshots;
lans -> switches;
ems_clusters -> ems_folder_children;
datacenters -> ems_folder_children;
vms -> ems_folder_children;
miq_templates -> ems_folder_children;
vms -> ems_cluster_children;
ems_clusters -> ems_cluster_children;
snapshots -> snapshot_patent;
}
[----] D, [2018-09-25T15:22:09.507052 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 0
[----] D, [2018-09-25T15:22:09.507140 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id] of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.510109 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id] of size 1...
[----] W, [2018-09-25T15:22:09.512754 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id]', record: '#<EmsCluster:0x000055730824ada8>', duplicate_index: '/api/clusters/f6413d8e-acb5-11e8-b5bd-00163e7eb420'
[----] D, [2018-09-25T15:22:09.540277 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id], created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.540353 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id], for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.540447 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ResourcePool>, blacklist: [ems_children] of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.543821 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<ResourcePool>, blacklist: [ems_children] of size 1...
[----] D, [2018-09-25T15:22:09.549013 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<ResourcePool>, blacklist: [ems_children], created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.549078 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ResourcePool>, blacklist: [ems_children], for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.549182 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Template>, blacklist: [genealogy_parent] of size 0 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.552764 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Template>, blacklist: [genealogy_parent] of size 0...
[----] D, [2018-09-25T15:22:09.557871 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Template>, blacklist: [genealogy_parent], created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.557935 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Template>, blacklist: [genealogy_parent], for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.557994 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Storage> of size 5 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.560918 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Storage> of size 5...
[----] W, [2018-09-25T15:22:09.582906 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<Storage>', record: 'env-rhv42-01-nfs-data', duplicate_index: 'cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com:/home/rhv/data'
[----] W, [2018-09-25T15:22:09.598478 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<Storage>', record: 'env-rhv42-01-nfs-export', duplicate_index: 'cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com:/home/rhv/export'
[----] W, [2018-09-25T15:22:09.613761 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<Storage>', record: 'env-rhv42-01-nfs-iso', duplicate_index: 'cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com:/home/rhv/iso'
[----] W, [2018-09-25T15:22:09.626650 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<Storage>', record: 'hosted_storage', duplicate_index: '10.8.196.27:/home/rhv/engine'
[----] W, [2018-09-25T15:22:09.642331 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<Storage>', record: 'ibm-ds3512-envrhv42-01-iscsi-data', duplicate_index: '360080e50002e5a1a000047ff5ab21b9b'
[----] D, [2018-09-25T15:22:09.666163 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Storage>, created=0, updated=5, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.666326 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Storage>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.666459 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<CustomAttribute> of size 0 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.669612 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<CustomAttribute> of size 0...
[----] D, [2018-09-25T15:22:09.674973 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<CustomAttribute>, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.675073 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<CustomAttribute>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.675157 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Lan> of size 3 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.677847 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Lan> of size 3...
[----] D, [2018-09-25T15:22:09.694090 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Lan>, created=2, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.694164 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Lan>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.694234 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Datacenter>, strategy: local_db_find_missing_references of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.697582 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Datacenter>, strategy: local_db_find_missing_references of size 1...
[----] D, [2018-09-25T15:22:09.702228 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Datacenter>, strategy: local_db_find_missing_references, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.702458 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Datacenter>, strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.702651 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.706603 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references of size 1...
[----] D, [2018-09-25T15:22:09.709765 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.709842 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.709897 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.711582 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references of size 1...
[----] D, [2018-09-25T15:22:09.713452 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.713534 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.713611 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.715276 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references of size 1...
[----] D, [2018-09-25T15:22:09.717203 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.717292 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.717337 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 0
[----] D, [2018-09-25T15:22:09.717380 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 1
[----] D, [2018-09-25T15:22:09.717445 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Host> of size 2 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.719862 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Host> of size 2...
[----] I, [2018-09-25T15:22:09.745065 #4134:2ab97e09cf60] INFO -- : MIQ(MiqQueue.put) Message id: [33], id: [], Zone: [], 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::Host", :object_id=>1, :attrs=>{:event_type=>"host_add_to_cluster", "Host::host"=>1, :host_id=>1, "EmsCluster::ems_cluster"=>1, :ems_cluster_id=>1, "MiqEvent::miq_event"=>13, :miq_event_id=>13, "EventStream::event_stream"=>13, :event_stream_id=>13}, :instance_name=>"Event", :user_id=>2, :miq_group_id=>20, :tenant_id=>2, :automate_message=>nil}]
[----] I, [2018-09-25T15:22:09.745154 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Host#raise_cluster_event) Raised EVM Event: [host_add_to_cluster, host: cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com(1), cluster: Default(1)]
[----] D, [2018-09-25T15:22:09.755545 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Host>, created=0, updated=1, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.755627 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Host>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.755704 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Switch> of size 3 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.758316 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Switch> of size 3...
[----] D, [2018-09-25T15:22:09.778796 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Switch>, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.778860 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Switch>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.778921 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 1
[----] D, [2018-09-25T15:22:09.778965 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 2
[----] D, [2018-09-25T15:22:09.779045 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Vm>, blacklist: [genealogy_parent] of size 15 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.785328 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Vm>, blacklist: [genealogy_parent] of size 15...
[----] D, [2018-09-25T15:22:09.877109 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Vm>, blacklist: [genealogy_parent], created=1, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.877208 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ManageIQ::Providers::Redhat::InfraManager::Vm>, blacklist: [genealogy_parent], for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.877272 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Hardware> of size 2 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.879985 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Hardware> of size 2...
[----] D, [2018-09-25T15:22:09.884704 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Hardware>, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.884801 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Hardware>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.884900 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<OperatingSystem> of size 2 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.888488 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<OperatingSystem> of size 2...
[----] D, [2018-09-25T15:22:09.892947 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<OperatingSystem>, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.893016 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<OperatingSystem>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.893084 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<HostStorage> of size 10 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.895540 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<HostStorage> of size 10...
[----] W, [2018-09-25T15:22:09.899733 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<HostStorage>', record: '#<HostStorage:0x0000557306674cc8>', duplicate_index: '1__'
[----] W, [2018-09-25T15:22:09.900351 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<HostStorage>', record: '#<HostStorage:0x00005573066747a0>', duplicate_index: '1__'
[----] W, [2018-09-25T15:22:09.900954 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<HostStorage>', record: '#<HostStorage:0x0000557306674188>', duplicate_index: '1__'
[----] W, [2018-09-25T15:22:09.901577 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<HostStorage>', record: '#<HostStorage:0x0000557306673c38>', duplicate_index: '1__'
[----] D, [2018-09-25T15:22:09.908888 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<HostStorage>, created=0, updated=0, deleted=1...Complete
[----] D, [2018-09-25T15:22:09.909002 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<HostStorage>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.909093 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<HostSwitch> of size 4 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.911757 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<HostSwitch> of size 4...
[----] D, [2018-09-25T15:22:09.915976 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<HostSwitch>, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.916071 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<HostSwitch>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.916133 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 2
[----] D, [2018-09-25T15:22:09.916192 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 3
[----] D, [2018-09-25T15:22:09.916285 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Network> of size 6 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.919708 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Network> of size 6...
[----] W, [2018-09-25T15:22:09.924786 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<Network>', record: '#<Network:0x000055730618f398>', duplicate_index: '1__'
[----] W, [2018-09-25T15:22:09.926023 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<Network>', record: '#<Network:0x000055730618ed08>', duplicate_index: '1__10.8.196.27'
[----] W, [2018-09-25T15:22:09.926690 #4134:2ab97e09cf60] WARN -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#assert_unique_record) A duplicate record was detected and destroyed, inventory_collection: 'InventoryCollection:<Network>', record: '#<Network:0x000055730618e8f8>', duplicate_index: '1__192.168.33.23'
[----] D, [2018-09-25T15:22:09.939332 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Network>, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.939437 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Network>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.939543 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<OperatingSystem> of size 15 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.943052 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<OperatingSystem> of size 15...
[----] D, [2018-09-25T15:22:09.957986 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<OperatingSystem>, created=1, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:09.958088 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<OperatingSystem>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:09.958160 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Snapshot> of size 15 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:09.960957 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Snapshot> of size 15...
[----] I, [2018-09-25T15:22:10.018418 #4134:2ab97e09cf60] INFO -- : MIQ(MiqQueue.put) Message id: [34], id: [], Zone: [default], Role: [], Server: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqEvent.raise_evm_event], Timeout: [600], Priority: [100], State: [ready], Deliver On: [], Data: [], Args: [["ManageIQ::Providers::Redhat::InfraManager::Vm", 15], "vm_snapshot_complete", {"id"=>15, "uid"=>"5ecb143e-d97b-40d0-9230-c693aa1e7a24", "parent_uid"=>nil, "name"=>"Active VM", "description"=>"Active VM", "current"=>1, "total_size"=>nil, "filename"=>nil, "create_time"=>Tue, 25 Sep 2018 19:18:06 UTC +00:00, "disks"=>[], "created_on"=>Tue, 25 Sep 2018 19:22:10 UTC +00:00, "updated_on"=>Tue, 25 Sep 2018 19:22:10 UTC +00:00, "parent_id"=>nil, "vm_or_template_id"=>15, "uid_ems"=>"5ecb143e-d97b-40d0-9230-c693aa1e7a24", "ems_ref_obj"=>nil, "ems_ref"=>nil}]
[----] D, [2018-09-25T15:22:10.024836 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Snapshot>, created=1, updated=14, deleted=0...Complete
[----] D, [2018-09-25T15:22:10.024914 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Snapshot>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:10.024972 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ems_folder_children> of size 0 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:10.025021 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ems_folder_children> using a custom save block
[----] D, [2018-09-25T15:22:10.061629 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ems_folder_children>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:10.061688 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ems_cluster_children> of size 0 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:10.061734 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ems_cluster_children> using a custom save block
[----] D, [2018-09-25T15:22:10.087754 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ems_cluster_children>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:10.087836 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Hardware> of size 15 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:10.090939 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Hardware> of size 15...
[----] D, [2018-09-25T15:22:10.108153 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Hardware>, created=1, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:10.108240 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Hardware>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:10.108309 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 3
[----] D, [2018-09-25T15:22:10.108361 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 4
[----] D, [2018-09-25T15:22:10.108448 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Network> of size 15 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:10.111240 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Network> of size 15...
[----] D, [2018-09-25T15:22:10.126981 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Network>, created=1, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:10.127058 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Network>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:10.127124 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<snapshot_patent> of size 0 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:10.127172 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<snapshot_patent> using a custom save block
[----] D, [2018-09-25T15:22:10.160969 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<snapshot_patent>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:10.161040 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Disk> of size 17 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:10.164673 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Disk> of size 17...
[----] D, [2018-09-25T15:22:10.192441 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Disk>, created=1, updated=2, deleted=0...Complete
[----] D, [2018-09-25T15:22:10.192546 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Disk>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:10.192638 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 4
[----] D, [2018-09-25T15:22:10.192711 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 5
[----] D, [2018-09-25T15:22:10.192809 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<GuestDevice> of size 24 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:10.211259 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<GuestDevice> of size 24...
[----] D, [2018-09-25T15:22:10.335087 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<GuestDevice>, created=10, updated=14, deleted=0...Complete
[----] D, [2018-09-25T15:22:10.335238 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<GuestDevice>, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:10.335331 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 5
[----] D, [2018-09-25T15:22:10.335414 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr...Complete
[----] I, [2018-09-25T15:22:10.335505 #4134:2ab97e09cf60] INFO -- : MIQ(ManagerRefresh::SaveInventory.save_inventory) EMS: [env-rhv42-01-mgr], id: [1] Saving EMS Inventory...Complete
[----] I, [2018-09-25T15:22:10.335744 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh_targets_for_ems) EMS: [env-rhv42-01-mgr], id: [1] Refreshing target ManageIQ::Providers::Redhat::InfraManager [env-rhv42-01-mgr] id [1]...Complete
[----] I, [2018-09-25T15:22:10.335902 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) EMS: [env-rhv42-01-mgr], id: [1] Refreshing targets for EMS...Complete - Timings {:collect_inventory_for_targets=>0.4065403938293457, :parse_inventory=>1.2580680847167969, :parse_targeted_inventory=>1.2582378387451172, :save_inventory=>0.8375582695007324, :ems_refresh=>2.502708673477173}
[----] I, [2018-09-25T15:22:10.354188 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#post_refresh) EMS: [env-rhv42-01-mgr], id: [1] Performing post-refresh operations for VmOrTemplate instances...
[----] I, [2018-09-25T15:22:10.366909 #4134:2ab97e09cf60] INFO -- : MIQ(MiqQueue.put) Message id: [35], id: [], Zone: [default], Role: [], Server: [], Ident: [generic], Target id: [], Instance id: [15], Task id: [], Command: [ManageIQ::Providers::Redhat::InfraManager::Vm.post_create_actions], Timeout: [600], Priority: [100], State: [ready], Deliver On: [], Data: [], Args: []
[----] I, [2018-09-25T15:22:10.381759 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#post_refresh) EMS: [env-rhv42-01-mgr], id: [1] Performing post-refresh operations for VmOrTemplate instances...Complete
[----] I, [2018-09-25T15:22:10.381824 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) Refreshing all targets...Complete
[----] I, [2018-09-25T15:22:36.683599 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) Refreshing all targets...
[----] I, [2018-09-25T15:22:36.683741 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) EMS: [env-rhv42-01-mgr], id: [1] Refreshing targets for EMS...
[----] I, [2018-09-25T15:22:36.683931 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) EMS: [env-rhv42-01-mgr], id: [1] ManagerRefresh::TargetCollection [Collection of 1 targets] id [Collection of targets with id: ["cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com"]]
[----] I, [2018-09-25T15:22:36.684122 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:36.843620 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#collect_inventory_for_targets) Filtering inventory for ManagerRefresh::TargetCollection [Collection of 1 targets] id: [Collection of targets with id: ["cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com"]]...
[----] I, [2018-09-25T15:22:36.851145 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:36.900900 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:36.978779 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.044540 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#collect_inventory_for_targets) Filtering inventory...Complete
[----] I, [2018-09-25T15:22:37.044722 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.117147 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh_targets_for_ems) EMS: [env-rhv42-01-mgr], id: [1] Refreshing target ManagerRefresh::TargetCollection [Collection of 11 targets] id [Collection of targets with id: ["cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com", {:ems_ref=>"/api/hosts/4457eac1-2d83-4b01-a30c-7068d66d6aad"}, {:ems_ref=>"f6413d8e-acb5-11e8-b5bd-00163e7eb420"}, {:ems_ref=>"bdc0e864-b98f-4c04-a8fc-e48f38d7f4d9"}, {:ems_ref=>"a33a13f6-70fc-4b04-857f-ef8aa696133d"}, {:ems_ref=>"ab29127c-c8b4-4b66-9433-103e3b4f98e0"}, {:ems_ref=>"bbae8ca4-4881-48f7-a13c-6aa6f67444f6"}, {:ems_ref=>"886cce9b-fa75-4a8b-bc42-10675792b3ba"}, {:ems_ref=>"00000000-0000-0000-0000-000000000009"}, {:ems_ref=>"db5bd84b-a143-47ec-8edb-9c46723d34b4"}, {:ems_ref=>"f6413d8e-acb5-11e8-b5bd-00163e7eb420"}]]...
[----] D, [2018-09-25T15:22:37.117220 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#parse_targeted_inventory) EMS: [env-rhv42-01-mgr], id: [1] Parsing inventory...
[----] I, [2018-09-25T15:22:37.117433 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.159020 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.434203 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.477434 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.576355 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.617441 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.662013 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.732621 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.774930 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] I, [2018-09-25T15:22:37.821752 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager#with_provider_connection) Connecting through ManageIQ::Providers::Redhat::InfraManager: [env-rhv42-01-mgr]
[----] D, [2018-09-25T15:22:37.822957 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#parse_targeted_inventory) EMS: [env-rhv42-01-mgr], id: [1] Parsing inventory...Complete
[----] D, [2018-09-25T15:22:37.823100 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveInventory.save_inventory) EMS: [env-rhv42-01-mgr], id: [1] Scanning Inventory Collections...Start
[----] D, [2018-09-25T15:22:37.824456 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveInventory.save_inventory) EMS: [env-rhv42-01-mgr], id: [1] Scanning Inventory Collections...Complete
[----] I, [2018-09-25T15:22:37.824568 #4134:2ab97e09cf60] INFO -- : MIQ(ManagerRefresh::SaveInventory.save_inventory) EMS: [env-rhv42-01-mgr], id: [1] Saving EMS Inventory...
[----] D, [2018-09-25T15:22:37.826838 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr...
[----] D, [2018-09-25T15:22:37.827142 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Topological sorting of manager env-rhv42-01-mgr resulted in these layers processable in parallel:
digraph {
subgraph cluster_0 { label = "Layer 0";
ems_clusters; // InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id], strategy: local_db_find_missing_references
resource_pools; // InventoryCollection:<ResourcePool>, blacklist: [ems_children], strategy: local_db_find_missing_references
storages; // InventoryCollection:<Storage>, strategy: local_db_find_missing_references
switches; // InventoryCollection:<Switch>, strategy: local_db_find_missing_references
snapshots; // InventoryCollection:<Snapshot>, strategy: local_db_find_references
lans; // InventoryCollection:<Lan>, strategy: local_db_find_references
custom_attributes; // InventoryCollection:<CustomAttribute>, strategy: local_db_find_references
networks; // InventoryCollection:<Network>, strategy: local_db_find_references
vm_and_template_ems_custom_fields; // InventoryCollection:<CustomAttribute>, strategy: local_db_find_references
datacenters; // InventoryCollection:<Datacenter>, strategy: local_db_find_references
root_folders; // InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_references
host_folders; // InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_references
vm_folders; // InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_references
ems_folder_children; // InventoryCollection:<ems_folder_children>, strategy: local_db_find_references
ems_cluster_children; // InventoryCollection:<ems_cluster_children>, strategy: local_db_find_references
ems_folders; // InventoryCollection:<EmsFolder>, blacklist: [ems_children], strategy: local_db_find_references
snapshot_patent; // InventoryCollection:<snapshot_patent>, strategy: local_db_find_references
}
subgraph cluster_1 { label = "Layer 1";
hosts; // InventoryCollection:<Host>, strategy: local_db_find_missing_references
}
subgraph cluster_2 { label = "Layer 2";
host_hardwares; // InventoryCollection:<Hardware>, strategy: local_db_find_missing_references
host_operating_systems; // InventoryCollection:<OperatingSystem>, strategy: local_db_find_missing_references
host_storages; // InventoryCollection:<HostStorage>, strategy: local_db_find_missing_references
host_switches; // InventoryCollection:<HostSwitch>, strategy: local_db_find_missing_references
}
subgraph cluster_3 { label = "Layer 3";
host_networks; // InventoryCollection:<Network>, strategy: local_db_find_missing_references
}
subgraph cluster_4 { label = "Layer 4";
guest_devices; // InventoryCollection:<GuestDevice>, strategy: local_db_find_references
}
// edges:
ems_clusters -> hosts;
hosts -> host_hardwares;
hosts -> host_operating_systems;
hosts -> host_storages;
storages -> host_storages;
hosts -> host_switches;
switches -> host_switches;
host_hardwares -> host_networks;
host_hardwares -> guest_devices;
switches -> guest_devices;
host_networks -> guest_devices;
}
[----] D, [2018-09-25T15:22:37.827215 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 0
[----] D, [2018-09-25T15:22:37.827270 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id], strategy: local_db_find_missing_references of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:37.830118 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id], strategy: local_db_find_missing_references of size 1...
[----] D, [2018-09-25T15:22:37.846725 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id], strategy: local_db_find_missing_references, created=1, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:37.846952 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<EmsCluster>, blacklist: [ems_children, datacenter_id], strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:37.847139 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ResourcePool>, blacklist: [ems_children], strategy: local_db_find_missing_references of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:37.851748 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<ResourcePool>, blacklist: [ems_children], strategy: local_db_find_missing_references of size 1...
[----] D, [2018-09-25T15:22:37.855602 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<ResourcePool>, blacklist: [ems_children], strategy: local_db_find_missing_references, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:37.855772 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<ResourcePool>, blacklist: [ems_children], strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:37.855901 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Storage>, strategy: local_db_find_missing_references of size 5 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:37.859288 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Storage>, strategy: local_db_find_missing_references of size 5...
[----] D, [2018-09-25T15:22:37.881468 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Storage>, strategy: local_db_find_missing_references, created=5, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:37.881579 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Storage>, strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:37.881653 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Switch>, strategy: local_db_find_missing_references of size 2 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:37.884460 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Switch>, strategy: local_db_find_missing_references of size 2...
[----] D, [2018-09-25T15:22:37.910278 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Switch>, strategy: local_db_find_missing_references, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:37.910353 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Switch>, strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:37.910428 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 0
[----] D, [2018-09-25T15:22:37.910472 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 1
[----] D, [2018-09-25T15:22:37.910535 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Host>, strategy: local_db_find_missing_references of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:37.914212 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Host>, strategy: local_db_find_missing_references of size 1...
[----] I, [2018-09-25T15:22:37.940208 #4134:2ab97e09cf60] INFO -- : MIQ(MiqQueue.put) Message id: [36], id: [], Zone: [], 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::Host", :object_id=>1, :attrs=>{:event_type=>"host_remove_from_cluster", "Host::host"=>1, :host_id=>1, "EmsCluster::ems_cluster"=>1, :ems_cluster_id=>1, "MiqEvent::miq_event"=>14, :miq_event_id=>14, "EventStream::event_stream"=>14, :event_stream_id=>14}, :instance_name=>"Event", :user_id=>2, :miq_group_id=>20, :tenant_id=>2, :automate_message=>nil}]
[----] I, [2018-09-25T15:22:37.940297 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Host#raise_cluster_event) Raised EVM Event: [host_remove_from_cluster, host: cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com(1), cluster: Default(1)]
[----] I, [2018-09-25T15:22:37.953646 #4134:2ab97e09cf60] INFO -- : MIQ(MiqQueue.put) Message id: [37], id: [], Zone: [], 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::Host", :object_id=>1, :attrs=>{:event_type=>"host_add_to_cluster", "Host::host"=>1, :host_id=>1, "EmsCluster::ems_cluster"=>6, :ems_cluster_id=>6, "MiqEvent::miq_event"=>15, :miq_event_id=>15, "EventStream::event_stream"=>15, :event_stream_id=>15}, :instance_name=>"Event", :user_id=>2, :miq_group_id=>20, :tenant_id=>2, :automate_message=>nil}]
[----] I, [2018-09-25T15:22:37.953760 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Host#raise_cluster_event) Raised EVM Event: [host_add_to_cluster, host: cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com(1), cluster: Default(6)]
[----] D, [2018-09-25T15:22:37.964322 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Host>, strategy: local_db_find_missing_references, created=0, updated=1, deleted=0...Complete
[----] D, [2018-09-25T15:22:37.964421 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Host>, strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:37.964493 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 1
[----] D, [2018-09-25T15:22:37.964562 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 2
[----] D, [2018-09-25T15:22:37.964647 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Hardware>, strategy: local_db_find_missing_references of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:37.968085 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Hardware>, strategy: local_db_find_missing_references of size 1...
[----] D, [2018-09-25T15:22:37.972847 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Hardware>, strategy: local_db_find_missing_references, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:37.972941 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Hardware>, strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:37.973021 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<OperatingSystem>, strategy: local_db_find_missing_references of size 1 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:37.975062 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<OperatingSystem>, strategy: local_db_find_missing_references of size 1...
[----] D, [2018-09-25T15:22:37.978106 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<OperatingSystem>, strategy: local_db_find_missing_references, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:37.978204 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<OperatingSystem>, strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:37.978291 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<HostStorage>, strategy: local_db_find_missing_references of size 5 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:37.980224 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<HostStorage>, strategy: local_db_find_missing_references of size 5...
[----] D, [2018-09-25T15:22:37.997258 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<HostStorage>, strategy: local_db_find_missing_references, created=5, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:37.997367 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<HostStorage>, strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:37.997461 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<HostSwitch>, strategy: local_db_find_missing_references of size 2 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:37.999787 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<HostSwitch>, strategy: local_db_find_missing_references of size 2...
[----] D, [2018-09-25T15:22:38.003040 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<HostSwitch>, strategy: local_db_find_missing_references, created=0, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:38.003133 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<HostSwitch>, strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:38.003191 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 2
[----] D, [2018-09-25T15:22:38.003238 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 3
[----] D, [2018-09-25T15:22:38.003298 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Network>, strategy: local_db_find_missing_references of size 3 to the database, for the manager: 'env-rhv42-01-mgr'...
[----] D, [2018-09-25T15:22:38.005364 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Network>, strategy: local_db_find_missing_references of size 3...
[----] D, [2018-09-25T15:22:38.020918 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::Saver::Default#save!) Processing InventoryCollection:<Network>, strategy: local_db_find_missing_references, created=3, updated=0, deleted=0...Complete
[----] D, [2018-09-25T15:22:38.021007 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_inventory_object_inventory) Saving collection InventoryCollection:<Network>, strategy: local_db_find_missing_references, for the manager: 'env-rhv42-01-mgr'...Complete
[----] D, [2018-09-25T15:22:38.021056 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 3
[----] D, [2018-09-25T15:22:38.021142 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr | Layer 4
[----] D, [2018-09-25T15:22:38.021192 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saved manager env-rhv42-01-mgr | Layer 4
[----] D, [2018-09-25T15:22:38.021235 #4134:2ab97e09cf60] DEBUG -- : MIQ(ManagerRefresh::SaveCollection::TopologicalSort.save_collections) Saving manager env-rhv42-01-mgr...Complete
[----] I, [2018-09-25T15:22:38.021287 #4134:2ab97e09cf60] INFO -- : MIQ(ManagerRefresh::SaveInventory.save_inventory) EMS: [env-rhv42-01-mgr], id: [1] Saving EMS Inventory...Complete
[----] I, [2018-09-25T15:22:38.021400 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh_targets_for_ems) EMS: [env-rhv42-01-mgr], id: [1] Refreshing target ManagerRefresh::TargetCollection [Collection of 11 targets] id [Collection of targets with id: ["cfme-smicro-628-b01.cfme2.lab.eng.rdu2.redhat.com", {:ems_ref=>"/api/hosts/4457eac1-2d83-4b01-a30c-7068d66d6aad"}, {:ems_ref=>"f6413d8e-acb5-11e8-b5bd-00163e7eb420"}, {:ems_ref=>"bdc0e864-b98f-4c04-a8fc-e48f38d7f4d9"}, {:ems_ref=>"a33a13f6-70fc-4b04-857f-ef8aa696133d"}, {:ems_ref=>"ab29127c-c8b4-4b66-9433-103e3b4f98e0"}, {:ems_ref=>"bbae8ca4-4881-48f7-a13c-6aa6f67444f6"}, {:ems_ref=>"886cce9b-fa75-4a8b-bc42-10675792b3ba"}, {:ems_ref=>"00000000-0000-0000-0000-000000000009"}, {:ems_ref=>"db5bd84b-a143-47ec-8edb-9c46723d34b4"}, {:ems_ref=>"f6413d8e-acb5-11e8-b5bd-00163e7eb420"}]]...Complete
[----] I, [2018-09-25T15:22:38.021496 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) EMS: [env-rhv42-01-mgr], id: [1] Refreshing targets for EMS...Complete - Timings {:collect_inventory_for_targets=>0.43295741081237793, :parse_inventory=>0.7056615352630615, :parse_targeted_inventory=>0.7058348655700684, :save_inventory=>0.19825983047485352, :ems_refresh=>1.3373925685882568}
[----] I, [2018-09-25T15:22:38.042782 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#post_refresh) EMS: [env-rhv42-01-mgr], id: [1] Performing post-refresh operations for VmOrTemplate instances...
[----] I, [2018-09-25T15:22:38.064513 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#post_refresh) EMS: [env-rhv42-01-mgr], id: [1] Performing post-refresh operations for VmOrTemplate instances...Complete
[----] I, [2018-09-25T15:22:38.064594 #4134:2ab97e09cf60] INFO -- : MIQ(ManageIQ::Providers::Redhat::InfraManager::Refresh::Strategies::Api4#refresh) Refreshing all targets...Complete
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment