Attachment 1480531 Details for Bug 1624840 – evm.log

evm.log

evm.log (text/plain), 155.96 KB, created by Danylo Kholodov on 2018-09-03 12:20:31 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Danylo Kholodov

Created: 2018-09-03 12:20:31 UTC

Size: 155.96 KB

patch

obsolete

>[root@ibm-p9z-18-fsp log]# tail -1000 evm.log
>[----] I, [2018-09-03T08:05:15.468791 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:05:15.489993 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:05:15.518636 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:05:15.520627 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:05:15.534582 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:05:16.326544 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.007330179214477539, :server_dequeue=>0.00482940673828125, :worker_monitor=>1.0141699314117432, :worker_dequeue=>0.008124113082885742, :total_time=>1.0347790718078613}
>[----] I, [2018-09-03T08:05:17.256591 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:05:31.538208 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:05:31.559629 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:05:31.588468 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:05:31.590230 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:05:31.604161 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:05:32.271835 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:05:47.283136 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:05:47.366826 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:05:47 UTC]...
>[----] I, [2018-09-03T08:05:47.373949 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:05:47 UTC]...Complete
>[----] I, [2018-09-03T08:05:47.563613 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:05:47.584721 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:05:47.613102 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:05:47.615067 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:05:47.630183 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:02.298562 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 4.
>[----] I, [2018-09-03T08:06:02.304425 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22743],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:06:02.310035 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22744],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:06:03.528489 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:06:03.551118 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:06:03.594825 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:03.596911 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:03.613675 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:04.613633 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:06:04.616463 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:server_dequeue=>0.0038301944732666016, :worker_monitor=>1.2494215965270996, :worker_dequeue=>0.006122589111328125, :total_time=>1.2596843242645264}
>[----] I, [2018-09-03T08:06:05.069658 #12628:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22743], MiqWorker id: [5], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [2.767383483] seconds
>[----] I, [2018-09-03T08:06:05.069857 #12628:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22743], Delivering...
>[----] I, [2018-09-03T08:06:05.136740 #12628:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22743], State: [ok], Delivered in [0.066876192] seconds
>[----] I, [2018-09-03T08:06:05.551559 #3400:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22744], MiqWorker id: [34], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [3.24349235] seconds
>[----] I, [2018-09-03T08:06:05.551694 #3400:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22744], Delivering...
>[----] I, [2018-09-03T08:06:05.553693 #3400:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22744], State: [ok], Delivered in [0.001988966] seconds
>[----] I, [2018-09-03T08:06:17.323039 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 2.
>[----] I, [2018-09-03T08:06:17.330294 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22745],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [log_status], Command: [MiqServer.log_status], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:06:17.335112 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22746],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [log_status], Command: [MiqWorker.log_status_all], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:06:19.640463 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:06:19 UTC]...
>[----] I, [2018-09-03T08:06:19.648118 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:06:19 UTC]...Complete
>[----] I, [2018-09-03T08:06:19.820423 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:06:19.840517 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:06:19.867991 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:19.869959 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:19.884869 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:20.627753 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:06:21.189932 #12628:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22745], MiqWorker id: [5], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [log_status], Command: [MiqServer.log_status], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [3.862320215] seconds
>[----] I, [2018-09-03T08:06:21.190158 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqQueue#deliver) Message id: [22745], Delivering...
>[----] I, [2018-09-03T08:06:21.190919 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] System Status:
>[----] I, [2018-09-03T08:06:21.191030 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Active: 6226440192
>[----] I, [2018-09-03T08:06:21.191076 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Active(anon): 5798301696
>[----] I, [2018-09-03T08:06:21.191121 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Active(file): 428138496
>[----] I, [2018-09-03T08:06:21.191160 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     AnonHugePages: 822083584
>[----] I, [2018-09-03T08:06:21.191199 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     AnonPages: 5700079616
>[----] I, [2018-09-03T08:06:21.191258 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Bounce: 0
>[----] I, [2018-09-03T08:06:21.191313 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Buffers: 11735040
>[----] I, [2018-09-03T08:06:21.191401 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Cached: 916058112
>[----] I, [2018-09-03T08:06:21.191454 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     CmaFree: 0
>[----] I, [2018-09-03T08:06:21.191512 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     CmaTotal: 0
>[----] I, [2018-09-03T08:06:21.191557 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     CommitLimit: 14817452032
>[----] I, [2018-09-03T08:06:21.191597 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Committed_AS: 7855968256
>[----] I, [2018-09-03T08:06:21.191639 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     DirectMap1G: 8589934592
>[----] I, [2018-09-03T08:06:21.191694 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     DirectMap2M: 4169138176
>[----] I, [2018-09-03T08:06:21.191734 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     DirectMap4k: 125784064
>[----] I, [2018-09-03T08:06:21.191810 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Dirty: 57344
>[----] I, [2018-09-03T08:06:21.191874 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HardwareCorrupted: 0
>[----] I, [2018-09-03T08:06:21.191937 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HugePages_Free: 0
>[----] I, [2018-09-03T08:06:21.192011 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HugePages_Rsvd: 0
>[----] I, [2018-09-03T08:06:21.192055 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HugePages_Surp: 0
>[----] I, [2018-09-03T08:06:21.192131 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HugePages_Total: 0
>[----] I, [2018-09-03T08:06:21.192183 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Hugepagesize: 2097152
>[----] I, [2018-09-03T08:06:21.192233 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Inactive: 401432576
>[----] I, [2018-09-03T08:06:21.192270 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Inactive(anon): 51265536
>[----] I, [2018-09-03T08:06:21.192307 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Inactive(file): 350167040
>[----] I, [2018-09-03T08:06:21.192383 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     KernelStack: 6307840
>[----] I, [2018-09-03T08:06:21.192433 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Mapped: 181157888
>[----] I, [2018-09-03T08:06:21.192476 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     MemAvailable: 3917053952
>[----] I, [2018-09-03T08:06:21.192552 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     MemFree: 3308617728
>[----] I, [2018-09-03T08:06:21.192634 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     MemTotal: 10315952128
>[----] I, [2018-09-03T08:06:21.192710 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Mlocked: 0
>[----] I, [2018-09-03T08:06:21.192756 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     NFS_Unstable: 0
>[----] I, [2018-09-03T08:06:21.192854 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     PageTables: 42893312
>[----] I, [2018-09-03T08:06:21.192893 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SReclaimable: 130359296
>[----] I, [2018-09-03T08:06:21.192933 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SUnreclaim: 55611392
>[----] I, [2018-09-03T08:06:21.192981 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Shmem: 149487616
>[----] I, [2018-09-03T08:06:21.193030 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Slab: 185970688
>[----] I, [2018-09-03T08:06:21.193065 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SwapCached: 0
>[----] I, [2018-09-03T08:06:21.193100 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SwapFree: 9659478016
>[----] I, [2018-09-03T08:06:21.193137 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SwapTotal: 9659478016
>[----] I, [2018-09-03T08:06:21.193182 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Unevictable: 0
>[----] I, [2018-09-03T08:06:21.193218 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     VmallocChunk: 35184324902912
>[----] I, [2018-09-03T08:06:21.193254 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     VmallocTotal: 35184372087808
>[----] I, [2018-09-03T08:06:21.193289 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     VmallocUsed: 42315776
>[----] I, [2018-09-03T08:06:21.193368 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Writeback: 8192
>[----] I, [2018-09-03T08:06:21.193423 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     WritebackTmp: 0
>[----] I, [2018-09-03T08:06:21.231401 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] Disk Usage:
>[----] I, [2018-09-03T08:06:21.231568 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] Filesystem     Type        Total         Used    Available        %Used       iTotal        iUsed        iFree       %iUsed   Mounted on
>[----] I, [2018-09-03T08:06:21.234151 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_os    xfs      4.49 GB      2.15 GB      2.34 GB          48%      2359296       103664      2255632           5%            /
>[----] I, [2018-09-03T08:06:21.235985 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] devtmpfs     devtmpfs      4.79 GB      0 Bytes      4.79 GB           0%      1255943          427      1255516           1%         /dev
>[----] I, [2018-09-03T08:06:21.238253 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] tmpfs         tmpfs       4.8 GB        20 KB       4.8 GB           1%      1259271            1      1259270           1%     /dev/shm
>[----] I, [2018-09-03T08:06:21.240434 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/vda1       xfs      1010 MB       163 MB       851 MB          17%       524288          326       523962           1%        /boot
>[----] I, [2018-09-03T08:06:21.242579 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_home    xfs      1010 MB      32.2 MB       982 MB           4%       524288            9       524279           1%        /home
>[----] I, [2018-09-03T08:06:21.244746 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_tmp    xfs      1010 MB      33.2 MB       981 MB           4%       524288           19       524269           1%         /tmp
>[----] I, [2018-09-03T08:06:21.246842 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_var    xfs        12 GB       972 MB        11 GB           8%      6291456        74268      6217188           2%         /var
>[----] I, [2018-09-03T08:06:21.248904 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_var_log    xfs      1010 MB      44.3 MB       970 MB           5%       524288           72       524216           1%     /var/log
>[----] I, [2018-09-03T08:06:21.250886 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_log    xfs      9.99 GB      68.5 MB      9.92 GB           1%      5242880          103      5242777           1% /var/www/miq/vmdb/log
>[----] I, [2018-09-03T08:06:21.252912 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_var_log_audit    xfs       509 MB      28.5 MB       480 MB           6%       262144            4       262140           1% /var/log/audit
>[----] I, [2018-09-03T08:06:21.254930 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/vg_pg-lv_pg    xfs        40 GB       199 MB      39.8 GB           1%     20969472         3366     20966106           1% /var/opt/rh/rh-postgresql95/lib/pgsql
>[----] I, [2018-09-03T08:06:21.256818 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] MiqQueue count for state=["dequeue"] by zone and role: {"default"=>{"ems_inventory"=>34, nil=>1}}
>[----] I, [2018-09-03T08:06:21.256900 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] MiqQueue count for state=["ready"] by zone and role: {"default"=>{nil=>1}}
>[----] I, [2018-09-03T08:06:21.259908 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_status) [EVM Server (12371)] Process info: Memory Usage [330928128], Memory Size [678567936], Proportional Set Size: [249639000], Unique Set Size: [238688000], Memory % [3.21], CPU Time [2190363.0], CPU % [6.67], Priority [20]
>[----] I, [2018-09-03T08:06:21.260201 #12628:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqQueue#delivered) Message id: [22745], State: [ok], Delivered in [0.070037932] seconds
>[----] I, [2018-09-03T08:06:25.638055 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 1 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:06:26.295696 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22746], MiqWorker id: [4], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [log_status], Command: [MiqWorker.log_status_all], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [8.9628007] seconds
>[----] I, [2018-09-03T08:06:26.295891 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqQueue#deliver) Message id: [22746], Delivering...
>[----] I, [2018-09-03T08:06:26.303790 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(ManageIQ::Providers::Openstack::CloudManager::RefreshWorker#log_status) [Refresh Worker for Provider: oc_ci] Worker ID [174], PID [22951], GUID [627872c3-c2f8-4749-b96c-b4fce22f5b0d], Last Heartbeat [2018-09-03 12:06:18 UTC], Process Info: Memory Usage [417099776], Memory Size [770551808], Proportional Set Size: [350118000], Unique Set Size: [344432000], Memory % [4.04], CPU Time [1788.0], CPU % [0.01], Priority [27]
>[----] I, [2018-09-03T08:06:26.305799 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(ManageIQ::Providers::Openstack::InfraManager::RefreshWorker#log_status) [Refresh Worker for Provider: uc_ci] Worker ID [179], PID [26335], GUID [c3443cd1-2d22-4885-8c8b-f6d6b460cb9b], Last Heartbeat [2018-09-03 12:06:18 UTC], Process Info: Memory Usage [709791744], Memory Size [1074225152], Proportional Set Size: [635929000], Unique Set Size: [630220000], Memory % [6.88], CPU Time [19218.0], CPU % [0.06], Priority [27]
>[----] I, [2018-09-03T08:06:26.306240 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqEventHandler#log_status) [Event Handler] Worker ID [1], PID [12592], GUID [19b2974c-1417-4f38-b0cc-b0c23102e06f], Last Heartbeat [2018-09-03 12:06:19 UTC], Process Info: Memory Usage [279662592], Memory Size [593387520], Proportional Set Size: [216208000], Unique Set Size: [208564000], Memory % [2.71], CPU Time [18088.0], CPU % [0.06], Priority [27]
>[----] I, [2018-09-03T08:06:26.306740 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqPriorityWorker#log_status) [Priority Worker] Worker ID [4], PID [12620], GUID [1a978802-3f34-4e49-8c2c-5cbc880fd30d], Last Heartbeat [2018-09-03 12:06:20 UTC], Process Info: Memory Usage [419467264], Memory Size [741625856], Proportional Set Size: [368301000], Unique Set Size: [365256000], Memory % [4.07], CPU Time [80979.0], CPU % [0.25], Priority [21]
>[----] I, [2018-09-03T08:06:26.307054 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqPriorityWorker#log_status) [Priority Worker] Worker ID [5], PID [12628], GUID [1a44efbf-8bb3-474b-b704-1aa23064507c], Last Heartbeat [2018-09-03 12:06:20 UTC], Process Info: Memory Usage [412573696], Memory Size [734310400], Proportional Set Size: [361530000], Unique Set Size: [358480000], Memory % [4.0], CPU Time [80542.0], CPU % [0.25], Priority [21]
>[----] I, [2018-09-03T08:06:26.307398 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqReportingWorker#log_status) [Reporting Worker] Worker ID [6], PID [12638], GUID [e1630ba5-32af-4c32-add0-0348b9d74ce7], Last Heartbeat [2018-09-03 12:06:18 UTC], Process Info: Memory Usage [338317312], Memory Size [673439744], Proportional Set Size: [283148000], Unique Set Size: [279412000], Memory % [3.28], CPU Time [32907.0], CPU % [0.1], Priority [27]
>[----] I, [2018-09-03T08:06:26.307752 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqReportingWorker#log_status) [Reporting Worker] Worker ID [7], PID [12643], GUID [25718704-a0f9-4d97-b129-01e07fc4c850], Last Heartbeat [2018-09-03 12:06:18 UTC], Process Info: Memory Usage [334987264], Memory Size [667987968], Proportional Set Size: [279897000], Unique Set Size: [276148000], Memory % [3.25], CPU Time [32812.0], CPU % [0.1], Priority [27]
>[----] I, [2018-09-03T08:06:26.308128 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqScheduleWorker#log_status) [Schedule Worker] Worker ID [8], PID [12655], GUID [6084e328-e0e3-4e0f-af10-0102078b0b56], Last Heartbeat [2018-09-03 12:06:17 UTC], Process Info: Memory Usage [336531456], Memory Size [704098304], Proportional Set Size: [272404000], Unique Set Size: [264748000], Memory % [3.26], CPU Time [116572.0], CPU % [0.35], Priority [23]
>[----] I, [2018-09-03T08:06:26.308630 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqSmartProxyWorker#log_status) [Smart Proxy Worker] Worker ID [176], PID [25126], GUID [433e067c-3b69-4267-a1d2-568f06b09617], Last Heartbeat [2018-09-03 12:06:18 UTC], Process Info: Memory Usage [326848512], Memory Size [678567936], Proportional Set Size: [236765000], Unique Set Size: [214372000], Memory % [3.17], CPU Time [217.0], CPU % [0.0], Priority [30]
>[----] I, [2018-09-03T08:06:26.308939 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqUiWorker#log_status) [User Interface Worker] Worker ID [9], PID [12665], GUID [1adb4ec1-b9c2-4981-b098-35c5c9901377], Last Heartbeat [2018-09-03 12:06:10 UTC], Process Info: Memory Usage [511488000], Memory Size [843968512], Proportional Set Size: [463890000], Unique Set Size: [461208000], Memory % [4.96], CPU Time [70584.0], CPU % [0.21], Priority [21]
>[----] I, [2018-09-03T08:06:26.309220 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqWebServiceWorker#log_status) [Web Services Worker] Worker ID [10], PID [12675], GUID [edfdf440-1df5-4ee1-b5f7-3d33bf226a8d], Last Heartbeat [2018-09-03 12:06:19 UTC], Process Info: Memory Usage [381546496], Memory Size [710062080], Proportional Set Size: [329279000], Unique Set Size: [325792000], Memory % [3.7], CPU Time [169658.0], CPU % [0.52], Priority [21]
>[----] I, [2018-09-03T08:06:26.309584 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqGenericWorker#log_status) [Generic Worker] Worker ID [44], PID [11268], GUID [d811e623-25ad-4309-ad83-69a010b09b58], Last Heartbeat [2018-09-03 12:06:18 UTC], Process Info: Memory Usage [539717632], Memory Size [893906944], Proportional Set Size: [474344000], Unique Set Size: [469696000], Memory % [5.23], CPU Time [103461.0], CPU % [0.31], Priority [30]
>[----] I, [2018-09-03T08:06:26.309918 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqGenericWorker#log_status) [Generic Worker] Worker ID [34], PID [3400], GUID [fcd40051-8665-4189-88ad-e9a598aa6056], Last Heartbeat [2018-09-03 12:06:17 UTC], Process Info: Memory Usage [547262464], Memory Size [907116544], Proportional Set Size: [482753000], Unique Set Size: [478308000], Memory % [5.31], CPU Time [105769.0], CPU % [0.32], Priority [30]
>[----] I, [2018-09-03T08:06:26.310360 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqSmartProxyWorker#log_status) [Smart Proxy Worker] Worker ID [175], PID [25120], GUID [94ecd028-03ea-450f-8095-ea2cbb0105e4], Last Heartbeat [2018-09-03 12:06:18 UTC], Process Info: Memory Usage [326828032], Memory Size [678567936], Proportional Set Size: [236755000], Unique Set Size: [214344000], Memory % [3.17], CPU Time [222.0], CPU % [0.0], Priority [30]
>[----] I, [2018-09-03T08:06:26.310616 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqQueue#delivered) Message id: [22746], State: [ok], Delivered in [0.014726891] seconds
>[----] I, [2018-09-03T08:06:32.346143 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:06:35.812830 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:06:35.832990 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:06:35.861738 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:35.863609 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:35.878473 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:47.359318 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:06:51.633029 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:06:51 UTC]...
>[----] I, [2018-09-03T08:06:51.642405 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:06:51 UTC]...Complete
>[----] I, [2018-09-03T08:06:51.814738 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:06:51.837262 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:06:51.870179 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:51.872127 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:51.888119 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:06:52.737927 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.009633541107177734, :server_dequeue=>0.005228519439697266, :worker_monitor=>1.083810806274414, :worker_dequeue=>0.006044626235961914, :total_time=>1.1054253578186035}
>[----] I, [2018-09-03T08:06:57.752558 #12371:11e0f7c]  INFO -- : MiqServer: local=Y, master=Y, status= started, id=00001, pid=12371, guid=e36211b5-7ae2-46ad-946f-2552b357e219, name=EVM, zone=default, hostname=ibm-p9z-18-fsp.mgmt.pnr.lab.eng.rdu2.redhat.com, ipaddress=10.0.0.26, version=5.10.0.12, build=20180822200426_5f6e52b, active roles=automate:database_operations:database_owner:ems_inventory:ems_operations:event:reporting:scheduler:smartproxy:smartstate:user_interface:web_services
>[----] I, [2018-09-03T08:07:02.372028 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 4.
>[----] I, [2018-09-03T08:07:02.378207 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22747],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:07:02.384527 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22748],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:07:02.769064 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:07:03.426305 #12628:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22747], MiqWorker id: [5], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [1.050355957] seconds
>[----] I, [2018-09-03T08:07:03.426533 #12628:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22747], Delivering...
>[----] I, [2018-09-03T08:07:03.487393 #12628:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22747], State: [ok], Delivered in [0.060880735] seconds
>[----] I, [2018-09-03T08:07:03.784772 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22748], MiqWorker id: [44], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [1.402718606] seconds
>[----] I, [2018-09-03T08:07:03.784965 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22748], Delivering...
>[----] I, [2018-09-03T08:07:03.787334 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22748], State: [ok], Delivered in [0.002365337] seconds
>[----] I, [2018-09-03T08:07:07.981097 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:07:08.002450 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:07:08.031863 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:08.033714 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:08.048112 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:17.400159 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:07:23.764444 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:07:23 UTC]...
>[----] I, [2018-09-03T08:07:23.771155 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:07:23 UTC]...Complete
>[----] I, [2018-09-03T08:07:23.938387 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:07:23.961777 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:07:23.991830 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:23.993829 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:24.010574 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:32.413824 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:07:39.970963 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:07:39.993589 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:07:40.028224 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:40.030438 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:40.046250 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:47.426788 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:07:55.849015 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:07:55 UTC]...
>[----] I, [2018-09-03T08:07:55.856110 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:07:55 UTC]...Complete
>[----] I, [2018-09-03T08:07:56.025895 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:07:56.047466 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:07:56.075905 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:56.077872 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:07:56.092751 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:02.438874 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 4.
>[----] I, [2018-09-03T08:08:02.445025 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22749],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:08:02.449743 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22750],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:08:06.812750 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:08:06.884374 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22749], MiqWorker id: [44], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [4.441961466] seconds
>[----] I, [2018-09-03T08:08:06.884584 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22749], Delivering...
>[----] I, [2018-09-03T08:08:06.953572 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22749], State: [ok], Delivered in [0.068974049] seconds
>[----] I, [2018-09-03T08:08:06.962615 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22750], MiqWorker id: [44], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [4.5146052] seconds
>[----] I, [2018-09-03T08:08:06.962816 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22750], Delivering...
>[----] I, [2018-09-03T08:08:06.964627 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22750], State: [ok], Delivered in [0.00186376] seconds
>[----] I, [2018-09-03T08:08:11.996334 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:08:12.018959 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:08:12.049474 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:12.051629 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:12.068053 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:12.827999 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:server_dequeue=>0.00483250617980957, :worker_monitor=>0.9993505477905273, :worker_dequeue=>0.0071637630462646484, :total_time=>1.0118052959442139}
>[----] I, [2018-09-03T08:08:17.463421 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:08:27.851872 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:08:27 UTC]...
>[----] I, [2018-09-03T08:08:27.858278 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:08:27 UTC]...Complete
>[----] I, [2018-09-03T08:08:28.043895 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:08:28.065327 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:08:28.106417 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:28.108411 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:28.122679 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:32.476040 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:08:44.075386 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:08:44.097531 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:08:44.128482 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:44.130657 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:44.145423 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:08:47.486592 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:08:59.928207 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:08:59 UTC]...
>[----] I, [2018-09-03T08:08:59.935025 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:08:59 UTC]...Complete
>[----] I, [2018-09-03T08:09:00.118681 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:09:00.139477 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:09:00.168487 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:00.170302 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:00.184742 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:00.942393 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.007006645202636719, :server_dequeue=>0.0050048828125, :worker_monitor=>0.9956462383270264, :worker_dequeue=>0.006288290023803711, :total_time=>1.014268159866333}
>[----] I, [2018-09-03T08:09:02.500377 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 10.
>[----] I, [2018-09-03T08:09:02.512746 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22751],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:09:02.519971 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22752],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:09:05.951092 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:09:06.817321 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22751], MiqWorker id: [4], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [4.307426378] seconds
>[----] I, [2018-09-03T08:09:06.817519 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22751], Delivering...
>[----] I, [2018-09-03T08:09:06.885406 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22751], State: [ok], Delivered in [0.067873718] seconds
>[----] I, [2018-09-03T08:09:07.060898 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22752], MiqWorker id: [44], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [4.543612546] seconds
>[----] I, [2018-09-03T08:09:07.061116 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22752], Delivering...
>[----] I, [2018-09-03T08:09:07.067531 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22752], State: [ok], Delivered in [0.006384994] seconds
>[----] I, [2018-09-03T08:09:16.145347 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:09:16.172082 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:09:16.203778 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:16.205810 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:16.221222 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:17.535224 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 2.
>[----] I, [2018-09-03T08:09:17.543606 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22753],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [RetirementManager.check], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:09:21.895437 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 1 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:09:22.092329 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22753], MiqWorker id: [44], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [RetirementManager.check], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [4.5518867] seconds
>[----] I, [2018-09-03T08:09:22.092544 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22753], Delivering...
>[----] I, [2018-09-03T08:09:22.106093 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22753], State: [ok], Delivered in [0.013524566] seconds
>[----] I, [2018-09-03T08:09:31.911803 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:09:31 UTC]...
>[----] I, [2018-09-03T08:09:31.920664 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:09:31 UTC]...Complete
>[----] I, [2018-09-03T08:09:32.096544 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:09:32.116847 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:09:32.146359 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:32.148291 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:32.162281 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:32.565890 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 2.
>[----] I, [2018-09-03T08:09:32.572253 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22754],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [VmdbDatabaseConnection.log_statistics], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:09:32.912633 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 1 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:09:32.915400 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.009074926376342773, :server_dequeue=>0.00482487678527832, :worker_monitor=>0.9831531047821045, :worker_dequeue=>0.006356716156005859, :total_time=>1.0037665367126465}
>[----] I, [2018-09-03T08:09:34.160917 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22754], MiqWorker id: [44], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [VmdbDatabaseConnection.log_statistics], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [1.591181235] seconds
>[----] I, [2018-09-03T08:09:34.161122 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22754], Delivering...
>[----] I, [2018-09-03T08:09:34.236685 #11268:11e0f7c]  INFO -- : MIQ(VmdbDatabaseConnection.log_statistics) <<-ACTIVITY_STATS_CSV
>session_id,xact_start,last_request_start_time,command,task_state,login,application,request_id,net_address,host_name,client_port,wait_time_ms,blocked_by
>12493,,2018-09-03 12:09:32 UTC,"SELECT  id, lock_version, priority, role FROM ""miq_queue"" WHERE (    state = 'ready'
>    AND (zone IS NULL OR zone = 'default')
>    AND (role IS NULL OR role IN ('automate','database_operations','database_owner','ems_inventory','ems_operations','event','reporting','scheduler','smartproxy','smartstate','user_interface','web_services'))
>    AND (server_guid IS NULL OR server_guid = 'e36211b5-7ae2-46ad-946f-2552b357e219')
>    AND (deliver_on IS NULL OR deliver_on <= '2018-09-03 12:09:32.914202')
>    AND (priority <= 200)
>    AND queue_name = 'smartproxy'
>) ORDER BY ""miq_queue"".""priority"" ASC, ""miq_queue"".""id"" ASC LIMIT $1",false,root,MIQ|12371|1|-|1|Server|default,16384,::1,,44562,1,
>12702,,2018-08-30 16:37:50 UTC,"SELECT  ""miq_databases"".* FROM ""miq_databases"" ORDER BY ""miq_databases"".""id"" ASC LIMIT $1",false,root,MIQ|12665|1|9|1|Ui|default,16384,::1,,44590,329503,
>12744,,2018-09-03 11:41:32 UTC,"SELECT ""server_roles"".""name"" FROM ""server_roles"" INNER JOIN ""assigned_server_roles"" ON ""server_roles"".""id"" = ""assigned_server_roles"".""server_role_id"" WHERE ""assigned_server_roles"".""miq_server_id"" = $1",false,root,MIQ|12592|1|1|1|EventHandler|default,16384,::1,,44604,1681,
>12747,,2018-09-03 12:00:11 UTC,COMMIT,false,root,MIQ|12638|1|6|1|Reporting|default,16384,::1,,44606,562,
>12704,,2018-09-03 11:56:00 UTC,"SELECT  ""ext_management_systems"".* FROM ""ext_management_systems"" WHERE ""ext_management_systems"".""type"" IN ('ManageIQ::Providers::BaseManager', 'ManageIQ::Providers::ConfigurationManager', 'ManageIQ::Providers::ContainerManager', 'ManageIQ::Providers::AutomationManager', 'ManageIQ::Providers::CloudManager', 'ManageIQ::Providers::InfraManager', 'ManageIQ::Providers::MonitoringManager', 'ManageIQ::Providers::NetworkManager', 'ManageIQ::Providers::PhysicalInfraManager', 'ManageIQ::Providers::ProvisioningManager', 'ManageIQ::Providers::StorageManager', 'ManageIQ::Providers::Foreman::ConfigurationManager', 'ManageIQ::Providers::Openshift::ContainerManager', 'ManageIQ::Providers::Kubernetes::ContainerManager', 'ManageIQ::Providers::EmbeddedAutomationManager', 'ManageIQ::Providers::ExternalAutomationManager', 'ManageIQ::Providers::EmbeddedAnsible::AutomationManager', 'ManageIQ::Providers::AnsibleTower::AutomationManager', 'ManageIQ::Providers::Amazon::CloudManager', 'ManageIQ::Providers::Azure::CloudManager', 'Manag",false,root,MIQ|12665|1|9|1|Ui|default,16384,::1,,44592,813,
>12683,,2018-09-03 12:09:06 UTC,COMMIT,false,root,MIQ|12620|1|4|1|Priority|default,16384,::1,,44582,27,
>12706,,2018-08-30 16:37:51 UTC,"SELECT  ""miq_databases"".* FROM ""miq_databases"" ORDER BY ""miq_databases"".""id"" ASC LIMIT $1",false,root,MIQ|12675|1|10|1|WebService|default,16384,::1,,44594,329503,
>12698,,2018-09-03 12:07:03 UTC,COMMIT,false,root,MIQ|12628|1|5|1|Priority|default,16384,::1,,44588,150,
>12709,,2018-09-03 12:08:06 UTC,"SELECT  ""miq_enterprises"".* FROM ""miq_enterprises"" WHERE (""miq_enterprises"".""id"" BETWEEN $1 AND $2) ORDER BY ""miq_enterprises"".""id"" ASC LIMIT $3",false,root,MIQ|12675|1|10|1|WebService|default,16384,::1,,44596,88,
>12710,,2018-09-03 12:09:32 UTC,"SELECT ""miq_schedules"".* FROM ""miq_schedules"" WHERE (updated_at > '2018-09-03 12:09:17.552735')",false,root,MIQ|12655|1|8|1|Schedule|default,16384,::1,,44598,1,
>26347,,2018-09-03 12:09:29 UTC,"SELECT  ""miq_queue"".* FROM ""miq_queue"" WHERE (    state = 'ready'
>    AND (zone IS NULL OR zone = 'default')
>    AND (task_id IS NULL OR task_id NOT IN (
>      SELECT DISTINCT task_id
>      FROM miq_queue
>      WHERE state = 'dequeue'
>        AND (zone IS NULL OR zone = 'default')
>        AND task_id IS NOT NULL
>    ))
>    AND (role IS NULL OR role IN ('automate','database_operations','database_owner','ems_inventory','ems_operations','event','reporting','scheduler','smartproxy','smartstate','user_interface','web_services'))
>    AND (server_guid IS NULL OR server_guid = 'e36211b5-7ae2-46ad-946f-2552b357e219')
>    AND (deliver_on IS NULL OR deliver_on <= '2018-09-03 12:09:29.116753')
>    AND (priority <= 200)
>AND queue_name in ('ems_41','ems_42')) ORDER BY priority, id LIMIT $1",false,root,MIQ|26335|1|179|1|Openstack::InfraManager::Refresh|default,16384,::1,,40896,5,
>17300,,2018-09-03 11:55:57 UTC,"SELECT ""miq_searches"".* FROM ""miq_searches"" WHERE ""miq_searches"".""db"" = $1 AND ""miq_searches"".""search_type"" = $2 AND ""miq_searches"".""search_key"" = $3",false,root,MIQ|12665|1|9|1|Ui|default,16384,::1,,57170,816,
>12748,,2018-09-03 12:00:11 UTC,COMMIT,false,root,MIQ|12643|1|7|1|Reporting|default,16384,::1,,44608,562,
>22964,,2018-09-03 12:09:29 UTC,"SELECT  ""miq_queue"".* FROM ""miq_queue"" WHERE (    state = 'ready'
>    AND (zone IS NULL OR zone = 'default')
>    AND (task_id IS NULL OR task_id NOT IN (
>      SELECT DISTINCT task_id
>      FROM miq_queue
>      WHERE state = 'dequeue'
>        AND (zone IS NULL OR zone = 'default')
>        AND task_id IS NOT NULL
>    ))
>    AND (role IS NULL OR role IN ('automate','database_operations','database_owner','ems_inventory','ems_operations','event','reporting','scheduler','smartproxy','smartstate','user_interface','web_services'))
>    AND (server_guid IS NULL OR server_guid = 'e36211b5-7ae2-46ad-946f-2552b357e219')
>    AND (deliver_on IS NULL OR deliver_on <= '2018-09-03 12:09:29.198639')
>    AND (priority <= 200)
>AND queue_name in ('ems_21','ems_22','ems_23','ems_24')) ORDER BY priority, id LIMIT $1",false,root,MIQ|22951|1|174|1|Openstack::CloudManager::Refresh|default,16384,::1,,33788,5,
>25140,,2018-09-03 11:41:30 UTC,"SELECT ""server_roles"".""name"" FROM ""server_roles"" INNER JOIN ""assigned_server_roles"" ON ""server_roles"".""id"" = ""assigned_server_roles"".""server_role_id"" WHERE ""assigned_server_roles"".""miq_server_id"" = $1",false,root,MIQ|25126|1|176|1|SmartProxy|default,16384,::1,,34590,1683,
>13281,,2018-09-03 12:09:06 UTC,"SELECT ""miq_product_features"".""identifier"" FROM ""miq_product_features"" INNER JOIN ""miq_roles_features"" ON ""miq_product_features"".""id"" = ""miq_roles_features"".""miq_product_feature_id"" WHERE ""miq_roles_features"".""miq_user_role_id"" = $1",false,root,MIQ|12675|1|10|1|WebService|default,16384,::1,,44616,28,
>11278,2018-09-03 12:09:34 UTC,2018-09-03 12:09:34 UTC,"SELECT ""pg_stat_activity"".* FROM ""pg_stat_activity"" WHERE ""pg_stat_activity"".""datname"" = $1",false,root,MIQ|11268|1|44|1|Generic|default,16384,::1,,47670,0,
>17301,,2018-09-03 11:56:05 UTC,"SELECT ""miq_sets"".* FROM ""miq_sets"" WHERE ""miq_sets"".""set_type"" IN ('CustomButtonSet') AND (set_data like '%:applies_to_class: CloudVolume%') AND (set_data not like '%:applies_to_id%')",false,root,MIQ|12665|1|9|1|Ui|default,16384,::1,,57172,808,
>25142,,2018-09-03 11:40:06 UTC,"SELECT  ""miq_servers"".* FROM ""miq_servers"" WHERE ""miq_servers"".""id"" = $1 LIMIT $2",false,root,MIQ|25126|1|176|1|SmartProxy|default,16384,::1,,34592,1768,
>25146,,2018-09-03 11:41:31 UTC,"SELECT ""server_roles"".""name"" FROM ""server_roles"" INNER JOIN ""assigned_server_roles"" ON ""server_roles"".""id"" = ""assigned_server_roles"".""server_role_id"" WHERE ""assigned_server_roles"".""miq_server_id"" = $1",false,root,MIQ|25120|1|175|1|SmartProxy|default,16384,::1,,34594,1683,
>25148,,2018-09-03 11:40:06 UTC,"SELECT  ""miq_servers"".* FROM ""miq_servers"" WHERE ""miq_servers"".""id"" = $1 LIMIT $2",false,root,MIQ|25120|1|175|1|SmartProxy|default,16384,::1,,34596,1767,
>25266,,2018-09-03 11:41:20 UTC,ROLLBACK,false,root,"",16384,192.168.24.201,,55538,1693,
>3410,,2018-09-03 12:06:05 UTC,COMMIT,false,root,MIQ|3400|1|34|1|Generic|default,16384,::1,,32794,208,
>ACTIVITY_STATS_CSV
>[----] I, [2018-09-03T08:09:34.237294 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22754], State: [ok], Delivered in [0.076117092] seconds
>[----] I, [2018-09-03T08:09:47.585151 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:09:48.121072 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:09:48.143892 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:09:48.174518 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:48.176524 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:09:48.192425 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:02.597342 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 4.
>[----] I, [2018-09-03T08:10:02.604003 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22755],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:10:02.611213 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22756],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:10:03.951967 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:10:03 UTC]...
>[----] I, [2018-09-03T08:10:03.958383 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:10:03 UTC]...Complete
>[----] I, [2018-09-03T08:10:04.131367 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:10:04.152888 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:10:04.182058 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:04.183882 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:04.198355 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:04.894334 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:10:05.031928 #12628:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22755], MiqWorker id: [5], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [2.43086791] seconds
>[----] I, [2018-09-03T08:10:05.032056 #12628:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22755], Delivering...
>[----] I, [2018-09-03T08:10:05.079962 #12628:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22755], State: [ok], Delivered in [0.04788568] seconds
>[----] I, [2018-09-03T08:10:05.894689 #3400:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22756], MiqWorker id: [34], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [3.285642942] seconds
>[----] I, [2018-09-03T08:10:05.894869 #3400:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22756], Delivering...
>[----] I, [2018-09-03T08:10:05.900712 #3400:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22756], State: [ok], Delivered in [0.005832352] seconds
>[----] I, [2018-09-03T08:10:17.624210 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:10:20.124829 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:10:20.157817 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:10:20.190051 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:20.192249 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:20.208995 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:20.996075 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:server_dequeue=>0.0048105716705322266, :worker_monitor=>1.0615170001983643, :worker_dequeue=>0.006835460662841797, :total_time=>1.0735394954681396}
>[----] I, [2018-09-03T08:10:32.637915 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:10:36.018473 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:10:36 UTC]...
>[----] I, [2018-09-03T08:10:36.025372 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:10:36 UTC]...Complete
>[----] I, [2018-09-03T08:10:36.204220 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:10:36.238604 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:10:36.267771 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:36.269806 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:36.284405 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:37.036792 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.007066249847412109, :server_dequeue=>0.004754543304443359, :worker_monitor=>0.9999010562896729, :worker_dequeue=>0.006278514862060547, :total_time=>1.0183687210083008}
>[----] I, [2018-09-03T08:10:47.650190 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:10:52.244725 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:10:52.269219 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:10:52.299413 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:52.301701 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:52.319923 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:10:56.305901 #12675:15b52d8]  INFO -- : <AuditSuccess> MIQ(Base.audit_success) userid: [admin] - User admin successfully validated by EVM
>[----] I, [2018-09-03T08:10:56.309589 #12675:15b52d8]  INFO -- : <AuditSuccess> MIQ(Base.audit_success) userid: [admin] - Authentication successful for user admin
>[----] I, [2018-09-03T08:10:56.437267 #12675:15b52d8]  INFO -- : <AuditSuccess> MIQ(Base.audit_success) userid: [admin] - User admin successfully validated by EVM
>[----] I, [2018-09-03T08:10:56.441176 #12675:15b52d8]  INFO -- : <AuditSuccess> MIQ(Base.audit_success) userid: [admin] - Authentication successful for user admin
>[----] I, [2018-09-03T08:10:58.326812 #12675:15b3e4c]  INFO -- : <AuditSuccess> MIQ(Base.audit_success) userid: [admin] - User admin successfully validated by EVM
>[----] I, [2018-09-03T08:10:58.330978 #12675:15b3e4c]  INFO -- : <AuditSuccess> MIQ(Base.audit_success) userid: [admin] - Authentication successful for user admin
>[----] I, [2018-09-03T08:10:58.458753 #12675:15b3e4c]  INFO -- : <AuditSuccess> MIQ(Base.audit_success) userid: [admin] - User admin successfully validated by EVM
>[----] I, [2018-09-03T08:10:58.463654 #12675:15b3e4c]  INFO -- : <AuditSuccess> MIQ(Base.audit_success) userid: [admin] - Authentication successful for user admin
>[----] I, [2018-09-03T08:11:02.660840 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 4.
>[----] I, [2018-09-03T08:11:02.668020 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22757],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:11:02.673315 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22758],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:11:03.088860 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:11:03.217651 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22757], MiqWorker id: [4], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [0.552079423] seconds
>[----] I, [2018-09-03T08:11:03.217858 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22757], Delivering...
>[----] I, [2018-09-03T08:11:03.268665 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22757], State: [ok], Delivered in [0.050782796] seconds
>[----] I, [2018-09-03T08:11:04.356436 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22758], MiqWorker id: [44], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [1.684872668] seconds
>[----] I, [2018-09-03T08:11:04.356605 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22758], Delivering...
>[----] I, [2018-09-03T08:11:04.359930 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22758], State: [ok], Delivered in [0.003314883] seconds
>[----] I, [2018-09-03T08:11:08.092268 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:11:08 UTC]...
>[----] I, [2018-09-03T08:11:08.098589 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:11:08 UTC]...Complete
>[----] I, [2018-09-03T08:11:08.287037 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:11:08.315799 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:11:08.354391 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:08.356704 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:08.372634 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:09.108268 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.0064449310302734375, :server_dequeue=>0.004825592041015625, :worker_monitor=>0.9989285469055176, :worker_dequeue=>0.005570173263549805, :total_time=>1.0161471366882324}
>[----] I, [2018-09-03T08:11:17.687005 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 2.
>[----] I, [2018-09-03T08:11:17.694321 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22759],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [log_status], Command: [MiqServer.log_status], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:11:17.699022 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22760],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [log_status], Command: [MiqWorker.log_status_all], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:11:19.128239 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:11:19.311907 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22759], MiqWorker id: [4], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [log_status], Command: [MiqServer.log_status], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [1.620619089] seconds
>[----] I, [2018-09-03T08:11:19.312106 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqQueue#deliver) Message id: [22759], Delivering...
>[----] I, [2018-09-03T08:11:19.312756 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] System Status:
>[----] I, [2018-09-03T08:11:19.312895 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Active: 6223527936
>[----] I, [2018-09-03T08:11:19.312961 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Active(anon): 5795000320
>[----] I, [2018-09-03T08:11:19.313052 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Active(file): 428527616
>[----] I, [2018-09-03T08:11:19.313097 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     AnonHugePages: 822083584
>[----] I, [2018-09-03T08:11:19.313184 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     AnonPages: 5696823296
>[----] I, [2018-09-03T08:11:19.313236 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Bounce: 0
>[----] I, [2018-09-03T08:11:19.313333 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Buffers: 11735040
>[----] I, [2018-09-03T08:11:19.313384 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Cached: 916312064
>[----] I, [2018-09-03T08:11:19.313431 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     CmaFree: 0
>[----] I, [2018-09-03T08:11:19.313493 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     CmaTotal: 0
>[----] I, [2018-09-03T08:11:19.313561 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     CommitLimit: 14817452032
>[----] I, [2018-09-03T08:11:19.313607 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Committed_AS: 7848890368
>[----] I, [2018-09-03T08:11:19.313648 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     DirectMap1G: 8589934592
>[----] I, [2018-09-03T08:11:19.313689 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     DirectMap2M: 4169138176
>[----] I, [2018-09-03T08:11:19.313733 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     DirectMap4k: 125784064
>[----] I, [2018-09-03T08:11:19.313786 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Dirty: 393216
>[----] I, [2018-09-03T08:11:19.313831 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HardwareCorrupted: 0
>[----] I, [2018-09-03T08:11:19.313874 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HugePages_Free: 0
>[----] I, [2018-09-03T08:11:19.313914 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HugePages_Rsvd: 0
>[----] I, [2018-09-03T08:11:19.313956 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HugePages_Surp: 0
>[----] I, [2018-09-03T08:11:19.314001 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     HugePages_Total: 0
>[----] I, [2018-09-03T08:11:19.314044 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Hugepagesize: 2097152
>[----] I, [2018-09-03T08:11:19.314087 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Inactive: 401342464
>[----] I, [2018-09-03T08:11:19.314151 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Inactive(anon): 51253248
>[----] I, [2018-09-03T08:11:19.314199 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Inactive(file): 350089216
>[----] I, [2018-09-03T08:11:19.314241 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     KernelStack: 6275072
>[----] I, [2018-09-03T08:11:19.314293 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Mapped: 180506624
>[----] I, [2018-09-03T08:11:19.314341 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     MemAvailable: 3921293312
>[----] I, [2018-09-03T08:11:19.314384 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     MemFree: 3312549888
>[----] I, [2018-09-03T08:11:19.314452 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     MemTotal: 10315952128
>[----] I, [2018-09-03T08:11:19.314570 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Mlocked: 0
>[----] I, [2018-09-03T08:11:19.314632 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     NFS_Unstable: 0
>[----] I, [2018-09-03T08:11:19.314674 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     PageTables: 42274816
>[----] I, [2018-09-03T08:11:19.314715 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SReclaimable: 130351104
>[----] I, [2018-09-03T08:11:19.314755 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SUnreclaim: 55521280
>[----] I, [2018-09-03T08:11:19.314799 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Shmem: 149430272
>[----] I, [2018-09-03T08:11:19.314838 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Slab: 185872384
>[----] I, [2018-09-03T08:11:19.314884 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SwapCached: 0
>[----] I, [2018-09-03T08:11:19.314925 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SwapFree: 9659478016
>[----] I, [2018-09-03T08:11:19.314971 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     SwapTotal: 9659478016
>[----] I, [2018-09-03T08:11:19.315019 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Unevictable: 0
>[----] I, [2018-09-03T08:11:19.315061 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     VmallocChunk: 35184324902912
>[----] I, [2018-09-03T08:11:19.315101 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     VmallocTotal: 35184372087808
>[----] I, [2018-09-03T08:11:19.315184 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     VmallocUsed: 42315776
>[----] I, [2018-09-03T08:11:19.315232 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     Writeback: 0
>[----] I, [2018-09-03T08:11:19.315274 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)]     WritebackTmp: 0
>[----] I, [2018-09-03T08:11:19.362061 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] Disk Usage:
>[----] I, [2018-09-03T08:11:19.362254 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] Filesystem     Type        Total         Used    Available        %Used       iTotal        iUsed        iFree       %iUsed   Mounted on
>[----] I, [2018-09-03T08:11:19.364781 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_os    xfs      4.49 GB      2.15 GB      2.34 GB          48%      2359296       103664      2255632           5%            /
>[----] I, [2018-09-03T08:11:19.366512 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] devtmpfs     devtmpfs      4.79 GB      0 Bytes      4.79 GB           0%      1255943          427      1255516           1%         /dev
>[----] I, [2018-09-03T08:11:19.368701 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] tmpfs         tmpfs       4.8 GB        20 KB       4.8 GB           1%      1259271           16      1259255           1%     /dev/shm
>[----] I, [2018-09-03T08:11:19.370706 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/vda1       xfs      1010 MB       163 MB       851 MB          17%       524288          326       523962           1%        /boot
>[----] I, [2018-09-03T08:11:19.372901 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_home    xfs      1010 MB      32.2 MB       982 MB           4%       524288            9       524279           1%        /home
>[----] I, [2018-09-03T08:11:19.375115 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_tmp    xfs      1010 MB      33.2 MB       981 MB           4%       524288           19       524269           1%         /tmp
>[----] I, [2018-09-03T08:11:19.379671 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_var    xfs        12 GB       972 MB        11 GB           8%      6291456        74268      6217188           2%         /var
>[----] I, [2018-09-03T08:11:19.382165 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_var_log    xfs      1010 MB      44.3 MB       970 MB           5%       524288           72       524216           1%     /var/log
>[----] I, [2018-09-03T08:11:19.384977 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_log    xfs      9.99 GB      68.4 MB      9.92 GB           1%      5242880          103      5242777           1% /var/www/miq/vmdb/log
>[----] I, [2018-09-03T08:11:19.388330 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/VG--CFME-lv_var_log_audit    xfs       509 MB      29.4 MB       479 MB           6%       262144            4       262140           1% /var/log/audit
>[----] I, [2018-09-03T08:11:19.391797 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] /dev/mapper/vg_pg-lv_pg    xfs        40 GB       199 MB      39.8 GB           1%     20969472         3366     20966106           1% /var/opt/rh/rh-postgresql95/lib/pgsql
>[----] I, [2018-09-03T08:11:19.394529 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] MiqQueue count for state=["dequeue"] by zone and role: {"default"=>{"ems_inventory"=>34, nil=>1}}
>[----] I, [2018-09-03T08:11:19.394662 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_system_status) [EVM Server (12371)] MiqQueue count for state=["ready"] by zone and role: {"default"=>{nil=>1}}
>[----] I, [2018-09-03T08:11:19.397865 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqServer.log_status) [EVM Server (12371)] Process info: Memory Usage [330928128], Memory Size [678567936], Proportional Set Size: [249644000], Unique Set Size: [238696000], Memory % [3.21], CPU Time [2192253.0], CPU % [6.67], Priority [20]
>[----] I, [2018-09-03T08:11:19.398088 #12620:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqQueue#delivered) Message id: [22759], State: [ok], Delivered in [0.085986907] seconds
>[----] I, [2018-09-03T08:11:24.317677 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:11:24.340065 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:11:24.373863 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:24.375863 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:24.391860 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:25.114566 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 1 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:11:25.433442 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22760], MiqWorker id: [44], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [log_status], Command: [MiqWorker.log_status_all], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [7.736490348] seconds
>[----] I, [2018-09-03T08:11:25.433631 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqQueue#deliver) Message id: [22760], Delivering...
>[----] I, [2018-09-03T08:11:25.443780 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(ManageIQ::Providers::Openstack::InfraManager::RefreshWorker#log_status) [Refresh Worker for Provider: uc_ci] Worker ID [179], PID [26335], GUID [c3443cd1-2d22-4885-8c8b-f6d6b460cb9b], Last Heartbeat [2018-09-03 12:11:19 UTC], Process Info: Memory Usage [709791744], Memory Size [1074225152], Proportional Set Size: [635930000], Unique Set Size: [630220000], Memory % [6.88], CPU Time [19249.0], CPU % [0.06], Priority [27]
>[----] I, [2018-09-03T08:11:25.444488 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqEventHandler#log_status) [Event Handler] Worker ID [1], PID [12592], GUID [19b2974c-1417-4f38-b0cc-b0c23102e06f], Last Heartbeat [2018-09-03 12:11:22 UTC], Process Info: Memory Usage [279662592], Memory Size [593387520], Proportional Set Size: [216211000], Unique Set Size: [208564000], Memory % [2.71], CPU Time [18092.0], CPU % [0.06], Priority [27]
>[----] I, [2018-09-03T08:11:25.444950 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqPriorityWorker#log_status) [Priority Worker] Worker ID [5], PID [12628], GUID [1a44efbf-8bb3-474b-b704-1aa23064507c], Last Heartbeat [2018-09-03 12:11:24 UTC], Process Info: Memory Usage [412573696], Memory Size [734310400], Proportional Set Size: [361532000], Unique Set Size: [358480000], Memory % [4.0], CPU Time [80633.0], CPU % [0.25], Priority [21]
>[----] I, [2018-09-03T08:11:25.445283 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqPriorityWorker#log_status) [Priority Worker] Worker ID [4], PID [12620], GUID [1a978802-3f34-4e49-8c2c-5cbc880fd30d], Last Heartbeat [2018-09-03 12:11:24 UTC], Process Info: Memory Usage [419467264], Memory Size [741625856], Proportional Set Size: [368303000], Unique Set Size: [365256000], Memory % [4.07], CPU Time [81024.0], CPU % [0.25], Priority [21]
>[----] I, [2018-09-03T08:11:25.445691 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqReportingWorker#log_status) [Reporting Worker] Worker ID [6], PID [12638], GUID [e1630ba5-32af-4c32-add0-0348b9d74ce7], Last Heartbeat [2018-09-03 12:11:24 UTC], Process Info: Memory Usage [338317312], Memory Size [673439744], Proportional Set Size: [283148000], Unique Set Size: [279412000], Memory % [3.28], CPU Time [32918.0], CPU % [0.1], Priority [27]
>[----] I, [2018-09-03T08:11:25.446018 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqReportingWorker#log_status) [Reporting Worker] Worker ID [7], PID [12643], GUID [25718704-a0f9-4d97-b129-01e07fc4c850], Last Heartbeat [2018-09-03 12:11:24 UTC], Process Info: Memory Usage [334987264], Memory Size [667987968], Proportional Set Size: [279796000], Unique Set Size: [275952000], Memory % [3.25], CPU Time [32823.0], CPU % [0.1], Priority [27]
>[----] I, [2018-09-03T08:11:25.446410 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqScheduleWorker#log_status) [Schedule Worker] Worker ID [8], PID [12655], GUID [6084e328-e0e3-4e0f-af10-0102078b0b56], Last Heartbeat [2018-09-03 12:11:17 UTC], Process Info: Memory Usage [336531456], Memory Size [704098304], Proportional Set Size: [272407000], Unique Set Size: [264748000], Memory % [3.26], CPU Time [116664.0], CPU % [0.35], Priority [23]
>[----] I, [2018-09-03T08:11:25.449777 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(ManageIQ::Providers::Openstack::CloudManager::RefreshWorker#log_status) [Refresh Worker for Provider: oc_ci] Worker ID [174], PID [22951], GUID [627872c3-c2f8-4749-b96c-b4fce22f5b0d], Last Heartbeat [2018-09-03 12:11:19 UTC], Process Info: Memory Usage [417099776], Memory Size [770551808], Proportional Set Size: [350119000], Unique Set Size: [344432000], Memory % [4.04], CPU Time [1801.0], CPU % [0.01], Priority [27]
>[----] I, [2018-09-03T08:11:25.450321 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqGenericWorker#log_status) [Generic Worker] Worker ID [34], PID [3400], GUID [fcd40051-8665-4189-88ad-e9a598aa6056], Last Heartbeat [2018-09-03 12:11:24 UTC], Process Info: Memory Usage [547262464], Memory Size [907116544], Proportional Set Size: [482755000], Unique Set Size: [478308000], Memory % [5.31], CPU Time [105785.0], CPU % [0.32], Priority [30]
>[----] I, [2018-09-03T08:11:25.450805 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqSmartProxyWorker#log_status) [Smart Proxy Worker] Worker ID [176], PID [25126], GUID [433e067c-3b69-4267-a1d2-568f06b09617], Last Heartbeat [2018-09-03 12:11:22 UTC], Process Info: Memory Usage [326848512], Memory Size [678567936], Proportional Set Size: [236774000], Unique Set Size: [214380000], Memory % [3.17], CPU Time [267.0], CPU % [0.0], Priority [30]
>[----] I, [2018-09-03T08:11:25.451279 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqSmartProxyWorker#log_status) [Smart Proxy Worker] Worker ID [175], PID [25120], GUID [94ecd028-03ea-450f-8095-ea2cbb0105e4], Last Heartbeat [2018-09-03 12:11:22 UTC], Process Info: Memory Usage [326828032], Memory Size [678567936], Proportional Set Size: [236763000], Unique Set Size: [214352000], Memory % [3.17], CPU Time [273.0], CPU % [0.0], Priority [30]
>[----] I, [2018-09-03T08:11:25.451663 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqUiWorker#log_status) [User Interface Worker] Worker ID [9], PID [12665], GUID [1adb4ec1-b9c2-4981-b098-35c5c9901377], Last Heartbeat [2018-09-03 12:11:22 UTC], Process Info: Memory Usage [511488000], Memory Size [843968512], Proportional Set Size: [463892000], Unique Set Size: [461208000], Memory % [4.96], CPU Time [70590.0], CPU % [0.21], Priority [21]
>[----] I, [2018-09-03T08:11:25.452087 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqWebServiceWorker#log_status) [Web Services Worker] Worker ID [10], PID [12675], GUID [edfdf440-1df5-4ee1-b5f7-3d33bf226a8d], Last Heartbeat [2018-09-03 12:11:20 UTC], Process Info: Memory Usage [381546496], Memory Size [710062080], Proportional Set Size: [329281000], Unique Set Size: [325792000], Memory % [3.7], CPU Time [169732.0], CPU % [0.52], Priority [21]
>[----] I, [2018-09-03T08:11:25.452521 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqGenericWorker#log_status) [Generic Worker] Worker ID [44], PID [11268], GUID [d811e623-25ad-4309-ad83-69a010b09b58], Last Heartbeat [2018-09-03 12:11:22 UTC], Process Info: Memory Usage [539717632], Memory Size [893906944], Proportional Set Size: [474346000], Unique Set Size: [469696000], Memory % [5.23], CPU Time [103497.0], CPU % [0.31], Priority [30]
>[----] I, [2018-09-03T08:11:25.452804 #11268:11e0f7c]  INFO -- : Q-task_id([log_status]) MIQ(MiqQueue#delivered) Message id: [22760], State: [ok], Delivered in [0.019165292] seconds
>[----] I, [2018-09-03T08:11:32.710597 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:11:40.140869 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:11:40 UTC]...
>[----] I, [2018-09-03T08:11:40.147850 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:11:40 UTC]...Complete
>[----] I, [2018-09-03T08:11:40.322116 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:11:40.343484 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:11:40.372099 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:40.374067 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:40.388609 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:40.997258 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [ManageIQ::Providers::Openstack::CloudManager::RefreshWorker] with ID: [174], PID: [22951], GUID: [627872c3-c2f8-4749-b96c-b4fce22f5b0d] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.006890 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [ManageIQ::Providers::Openstack::InfraManager::RefreshWorker] with ID: [179], PID: [26335], GUID: [c3443cd1-2d22-4885-8c8b-f6d6b460cb9b] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.017234 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqEventHandler] with ID: [1], PID: [12592], GUID: [19b2974c-1417-4f38-b0cc-b0c23102e06f] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.027774 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqGenericWorker] with ID: [34], PID: [3400], GUID: [fcd40051-8665-4189-88ad-e9a598aa6056] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.034332 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqGenericWorker] with ID: [44], PID: [11268], GUID: [d811e623-25ad-4309-ad83-69a010b09b58] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.045151 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqPriorityWorker] with ID: [5], PID: [12628], GUID: [1a44efbf-8bb3-474b-b704-1aa23064507c] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.051939 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqPriorityWorker] with ID: [4], PID: [12620], GUID: [1a978802-3f34-4e49-8c2c-5cbc880fd30d] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.062130 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqReportingWorker] with ID: [6], PID: [12638], GUID: [e1630ba5-32af-4c32-add0-0348b9d74ce7] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.068400 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqReportingWorker] with ID: [7], PID: [12643], GUID: [25718704-a0f9-4d97-b129-01e07fc4c850] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.076209 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqScheduleWorker] with ID: [8], PID: [12655], GUID: [6084e328-e0e3-4e0f-af10-0102078b0b56] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.086619 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqSmartProxyWorker] with ID: [176], PID: [25126], GUID: [433e067c-3b69-4267-a1d2-568f06b09617] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.093434 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqSmartProxyWorker] with ID: [175], PID: [25120], GUID: [94ecd028-03ea-450f-8095-ea2cbb0105e4] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.101118 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqUiWorker] with ID: [9], PID: [12665], GUID: [1adb4ec1-b9c2-4981-b098-35c5c9901377] is being requested to sync_config
>[----] I, [2018-09-03T08:11:41.110237 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#worker_set_message) Worker [MiqWebServiceWorker] with ID: [10], PID: [12675], GUID: [edfdf440-1df5-4ee1-b5f7-3d33bf226a8d] is being requested to sync_config
>[----] I, [2018-09-03T08:11:42.051181 #3400:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#message_sync_config) MIQ(MiqGenericWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:42.113279 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#message_sync_config) MIQ(MiqPriorityWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:42.202491 #3400:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#sync_config) ID [34], PID [3400], GUID [fcd40051-8665-4189-88ad-e9a598aa6056], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:42.203247 #3400:11e0f7c]  INFO -- : 
>---
>:count: 2
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 524288000
>:nice_delta: 10
>:parent_time_threshold: 180
>:poll: 3
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :drb
>:queue_timeout: 600
>[----] I, [2018-09-03T08:11:42.203307 #3400:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:42.203477 #3400:11e0f7c]  INFO -- : 
>---
>:guid: fcd40051-8665-4189-88ad-e9a598aa6056
>[----] I, [2018-09-03T08:11:42.203645 #3400:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#message_sync_config) MIQ(MiqGenericWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:42.269771 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#sync_config) ID [4], PID [12620], GUID [1a978802-3f34-4e49-8c2c-5cbc880fd30d], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:42.270280 #12620:11e0f7c]  INFO -- : 
>---
>:count: 2
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 629145600
>:nice_delta: 1
>:parent_time_threshold: 180
>:poll: 1
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :drb
>:queue_timeout: 600
>[----] I, [2018-09-03T08:11:42.270326 #12620:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:42.270629 #12620:11e0f7c]  INFO -- : 
>---
>:guid: 1a978802-3f34-4e49-8c2c-5cbc880fd30d
>[----] I, [2018-09-03T08:11:42.270749 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#message_sync_config) MIQ(MiqPriorityWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:43.195919 #25120:11e0f7c]  INFO -- : MIQ(MiqSmartProxyWorker::Runner#message_sync_config) MIQ(MiqSmartProxyWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:43.212614 #25126:11e0f7c]  INFO -- : MIQ(MiqSmartProxyWorker::Runner#message_sync_config) MIQ(MiqSmartProxyWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:43.383569 #25126:11e0f7c]  INFO -- : MIQ(MiqSmartProxyWorker::Runner#sync_config) ID [176], PID [25126], GUID [433e067c-3b69-4267-a1d2-568f06b09617], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:43.384211 #25126:11e0f7c]  INFO -- : 
>---
>:count: 2
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 2147483648
>:nice_delta: 10
>:parent_time_threshold: 180
>:poll: 3
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 21600
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :drb
>:queue_timeout: 1200
>:heartbeat_thread_shutdown_timeout: 10
>[----] I, [2018-09-03T08:11:43.384261 #25126:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:43.384511 #25126:11e0f7c]  INFO -- : 
>---
>:guid: 433e067c-3b69-4267-a1d2-568f06b09617
>[----] I, [2018-09-03T08:11:43.384645 #25126:11e0f7c]  INFO -- : MIQ(MiqSmartProxyWorker::Runner#message_sync_config) MIQ(MiqSmartProxyWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:43.395333 #25120:11e0f7c]  INFO -- : MIQ(MiqSmartProxyWorker::Runner#sync_config) ID [175], PID [25120], GUID [94ecd028-03ea-450f-8095-ea2cbb0105e4], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:43.396337 #25120:11e0f7c]  INFO -- : 
>---
>:count: 2
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 2147483648
>:nice_delta: 10
>:parent_time_threshold: 180
>:poll: 3
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 21600
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :drb
>:queue_timeout: 1200
>:heartbeat_thread_shutdown_timeout: 10
>[----] I, [2018-09-03T08:11:43.396406 #25120:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:43.396818 #25120:11e0f7c]  INFO -- : 
>---
>:guid: 94ecd028-03ea-450f-8095-ea2cbb0105e4
>[----] I, [2018-09-03T08:11:43.396923 #25120:11e0f7c]  INFO -- : MIQ(MiqSmartProxyWorker::Runner#message_sync_config) MIQ(MiqSmartProxyWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:44.035861 #12675:157442c]  INFO -- : MIQ(MiqWebServiceWorker::Runner#message_sync_config) MIQ(MiqWebServiceWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:44.171962 #12675:157442c]  INFO -- : MIQ(MiqWebServiceWorker::Runner#sync_config) ID [10], PID [12675], GUID [edfdf440-1df5-4ee1-b5f7-3d33bf226a8d], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:44.172402 #12675:157442c]  INFO -- : 
>---
>:count: 1
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 1073741824
>:nice_delta: 1
>:parent_time_threshold: 180
>:poll: 3
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:connection_pool_size: 8
>[----] I, [2018-09-03T08:11:44.172446 #12675:157442c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:44.172682 #12675:157442c]  INFO -- : 
>---
>:guid: edfdf440-1df5-4ee1-b5f7-3d33bf226a8d
>[----] I, [2018-09-03T08:11:44.172870 #12675:157442c]  INFO -- : MIQ(MiqWebServiceWorker::Runner#message_sync_config) MIQ(MiqWebServiceWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:46.477119 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#message_sync_config) MIQ(MiqGenericWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:46.543744 #12665:15855d8]  INFO -- : MIQ(MiqUiWorker::Runner#message_sync_config) MIQ(MiqUiWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:46.650655 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#sync_config) ID [44], PID [11268], GUID [d811e623-25ad-4309-ad83-69a010b09b58], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:46.651297 #11268:11e0f7c]  INFO -- : 
>---
>:count: 2
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 524288000
>:nice_delta: 10
>:parent_time_threshold: 180
>:poll: 3
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :drb
>:queue_timeout: 600
>[----] I, [2018-09-03T08:11:46.651350 #11268:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:46.651628 #11268:11e0f7c]  INFO -- : 
>---
>:guid: d811e623-25ad-4309-ad83-69a010b09b58
>[----] I, [2018-09-03T08:11:46.651802 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#message_sync_config) MIQ(MiqGenericWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:46.690856 #12665:15855d8]  INFO -- : MIQ(MiqUiWorker::Runner#sync_config) ID [9], PID [12665], GUID [1adb4ec1-b9c2-4981-b098-35c5c9901377], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:46.691294 #12665:15855d8]  INFO -- : 
>---
>:count: 1
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 1073741824
>:nice_delta: 1
>:parent_time_threshold: 180
>:poll: 3
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:connection_pool_size: 8
>[----] I, [2018-09-03T08:11:46.691339 #12665:15855d8]  INFO -- : ---
>[----] I, [2018-09-03T08:11:46.691537 #12665:15855d8]  INFO -- : 
>---
>:guid: 1adb4ec1-b9c2-4981-b098-35c5c9901377
>[----] I, [2018-09-03T08:11:46.691693 #12665:15855d8]  INFO -- : MIQ(MiqUiWorker::Runner#message_sync_config) MIQ(MiqUiWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:46.995930 #12592:11e0f7c]  INFO -- : MIQ(MiqEventHandler::Runner#message_sync_config) MIQ(MiqEventHandler::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:47.145566 #12592:11e0f7c]  INFO -- : MIQ(MiqEventHandler::Runner#sync_config) ID [1], PID [12592], GUID [19b2974c-1417-4f38-b0cc-b0c23102e06f], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:47.146126 #12592:11e0f7c]  INFO -- : 
>---
>:count: 1
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 524288000
>:nice_delta: 7
>:parent_time_threshold: 180
>:poll: 3
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 0
>:dequeue_method: :drb
>:queue_timeout: 600
>[----] I, [2018-09-03T08:11:47.146179 #12592:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:47.146421 #12592:11e0f7c]  INFO -- : 
>---
>:guid: 19b2974c-1417-4f38-b0cc-b0c23102e06f
>[----] I, [2018-09-03T08:11:47.146641 #12592:11e0f7c]  INFO -- : MIQ(MiqEventHandler::Runner#message_sync_config) MIQ(MiqEventHandler::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:47.726561 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#message_sync_config) MIQ(MiqScheduleWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:47.864674 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#sync_config) ID [8], PID [12655], GUID [6084e328-e0e3-4e0f-af10-0102078b0b56], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:47.866100 #12655:11e0f7c]  INFO -- : 
>---
>:count: 1
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 524288000
>:nice_delta: 3
>:parent_time_threshold: 180
>:poll: 15
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:container_entities_purge_interval: 86400
>:binary_blob_purge_interval: 3600
>:authentication_check_interval: 3600
>:chargeback_generation_interval: 86400
>:chargeback_generation_time_utc: 3600
>:db_diagnostics_interval: 1800
>:drift_state_purge_interval: 86400
>:event_streams_purge_interval: 86400
>:evm_snapshot_delete_delay_for_job_not_found: 3600
>:evm_snapshot_interval: 3600
>:job_proxy_dispatcher_interval: 15
>:job_proxy_dispatcher_stale_message_check_interval: 60
>:job_proxy_dispatcher_stale_message_timeout: 120
>:job_timeout_interval: 60
>:load_balancer_retired_interval: 600
>:log_active_configuration_interval: 86400
>:log_database_statistics_interval: 86400
>:notifications_purge_interval: 86400
>:orchestration_stack_retired_interval: 600
>:performance_collection_interval: 180
>:performance_collection_start_delay: 300
>:performance_realtime_purging_interval: 1260
>:performance_realtime_purging_start_delay: 300
>:performance_rollup_purging_interval: 14400
>:performance_rollup_purging_start_delay: 300
>:policy_events_purge_interval: 86400
>:report_result_purge_interval: 604800
>:server_log_stats_interval: 300
>:server_stats_interval: 60
>:service_retired_interval: 600
>:session_timeout_interval: 30
>:storage_file_collection_interval: 86400
>:storage_file_collection_time_utc: 21600
>:task_purge_interval: 86400
>:task_timeout_check_frequency: 3600
>:vim_performance_states_purge_interval: 86400
>:vm_retired_interval: 600
>:yum_update_check: 43200
>[----] I, [2018-09-03T08:11:47.866200 #12655:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:47.866552 #12655:11e0f7c]  INFO -- : 
>---
>:guid: 6084e328-e0e3-4e0f-af10-0102078b0b56
>[----] I, [2018-09-03T08:11:47.866719 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#message_sync_config) MIQ(MiqScheduleWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:47.866799 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:11:48.759623 #12638:11e0f7c]  INFO -- : MIQ(MiqReportingWorker::Runner#message_sync_config) MIQ(MiqReportingWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:48.950775 #12638:11e0f7c]  INFO -- : MIQ(MiqReportingWorker::Runner#sync_config) ID [6], PID [12638], GUID [e1630ba5-32af-4c32-add0-0348b9d74ce7], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:48.951351 #12638:11e0f7c]  INFO -- : 
>---
>:count: 2
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 524288000
>:nice_delta: 7
>:parent_time_threshold: 180
>:poll: 3
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :drb
>:queue_timeout: 600
>[----] I, [2018-09-03T08:11:48.951400 #12638:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:48.951615 #12638:11e0f7c]  INFO -- : 
>---
>:guid: e1630ba5-32af-4c32-add0-0348b9d74ce7
>[----] I, [2018-09-03T08:11:48.951759 #12638:11e0f7c]  INFO -- : MIQ(MiqReportingWorker::Runner#message_sync_config) MIQ(MiqReportingWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:49.272073 #26335:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::RefreshWorker::Runner#message_sync_config) MIQ(ManageIQ::Providers::Openstack::InfraManager::RefreshWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:49.325402 #22951:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::RefreshWorker::Runner#message_sync_config) MIQ(ManageIQ::Providers::Openstack::CloudManager::RefreshWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:49.480050 #26335:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::RefreshWorker::Runner#sync_config) ID [179], PID [26335], GUID [c3443cd1-2d22-4885-8c8b-f6d6b460cb9b], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:49.480833 #26335:11e0f7c]  INFO -- : 
>---
>:count: 1
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 2147483648
>:nice_delta: 7
>:parent_time_threshold: 180
>:poll: 10
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 7200
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :sql
>:queue_timeout: 7200
>[----] I, [2018-09-03T08:11:49.480904 #26335:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:49.481238 #26335:11e0f7c]  INFO -- : 
>---
>:guid: c3443cd1-2d22-4885-8c8b-f6d6b460cb9b
>:ems_id:
>- 41
>- 42
>[----] I, [2018-09-03T08:11:49.481396 #26335:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::RefreshWorker::Runner#message_sync_config) MIQ(ManageIQ::Providers::Openstack::InfraManager::RefreshWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:49.515098 #22951:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::RefreshWorker::Runner#sync_config) ID [174], PID [22951], GUID [627872c3-c2f8-4749-b96c-b4fce22f5b0d], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:49.515741 #22951:11e0f7c]  INFO -- : 
>---
>:count: 1
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 2147483648
>:nice_delta: 7
>:parent_time_threshold: 180
>:poll: 10
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 7200
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :sql
>:queue_timeout: 7200
>[----] I, [2018-09-03T08:11:49.515810 #22951:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:49.516283 #22951:11e0f7c]  INFO -- : 
>---
>:guid: 627872c3-c2f8-4749-b96c-b4fce22f5b0d
>:ems_id:
>- 21
>- 22
>- 23
>- 24
>[----] I, [2018-09-03T08:11:49.516475 #22951:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::RefreshWorker::Runner#message_sync_config) MIQ(ManageIQ::Providers::Openstack::CloudManager::RefreshWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:50.900609 #12628:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#message_sync_config) MIQ(MiqPriorityWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:51.084076 #12628:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#sync_config) ID [5], PID [12628], GUID [1a44efbf-8bb3-474b-b704-1aa23064507c], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:51.085024 #12628:11e0f7c]  INFO -- : 
>---
>:count: 2
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 629145600
>:nice_delta: 1
>:parent_time_threshold: 180
>:poll: 1
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :drb
>:queue_timeout: 600
>[----] I, [2018-09-03T08:11:51.085083 #12628:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:51.085398 #12628:11e0f7c]  INFO -- : 
>---
>:guid: 1a44efbf-8bb3-474b-b704-1aa23064507c
>[----] I, [2018-09-03T08:11:51.085579 #12628:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#message_sync_config) MIQ(MiqPriorityWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:51.769250 #12643:11e0f7c]  INFO -- : MIQ(MiqReportingWorker::Runner#message_sync_config) MIQ(MiqReportingWorker::Runner) Synchronizing configuration...
>[----] I, [2018-09-03T08:11:51.944337 #12643:11e0f7c]  INFO -- : MIQ(MiqReportingWorker::Runner#sync_config) ID [7], PID [12643], GUID [25718704-a0f9-4d97-b129-01e07fc4c850], Zone [default], Active Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Assigned Roles [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartproxy,smartstate,user_interface,web_services], Configuration:
>[----] I, [2018-09-03T08:11:51.944956 #12643:11e0f7c]  INFO -- : 
>---
>:count: 2
>:gc_interval: 900
>:heartbeat_freq: 10
>:heartbeat_timeout: 120
>:memory_threshold: 524288000
>:nice_delta: 7
>:parent_time_threshold: 180
>:poll: 3
>:poll_escalate_max: 30
>:poll_method: :normal
>:restart_interval: 0
>:starting_timeout: 600
>:stopping_timeout: 600
>:cpu_usage_threshold: 100
>:dequeue_method: :drb
>:queue_timeout: 600
>[----] I, [2018-09-03T08:11:51.945008 #12643:11e0f7c]  INFO -- : ---
>[----] I, [2018-09-03T08:11:51.945259 #12643:11e0f7c]  INFO -- : 
>---
>:guid: 25718704-a0f9-4d97-b129-01e07fc4c850
>[----] I, [2018-09-03T08:11:51.945363 #12643:11e0f7c]  INFO -- : MIQ(MiqReportingWorker::Runner#message_sync_config) MIQ(MiqReportingWorker::Runner) Synchronizing configuration complete...
>[----] I, [2018-09-03T08:11:56.318416 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:11:56.343877 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:11:56.376724 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:56.378647 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:11:56.393645 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:02.160402 #12371:11e0f7c]  INFO -- : MiqServer: local=Y, master=Y, status= started, id=00001, pid=12371, guid=e36211b5-7ae2-46ad-946f-2552b357e219, name=EVM, zone=default, hostname=ibm-p9z-18-fsp.mgmt.pnr.lab.eng.rdu2.redhat.com, ipaddress=10.0.0.26, version=5.10.0.12, build=20180822200426_5f6e52b, active roles=automate:database_operations:database_owner:ems_inventory:ems_operations:event:reporting:scheduler:smartproxy:smartstate:user_interface:web_services
>[----] I, [2018-09-03T08:12:02.878062 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 4.
>[----] I, [2018-09-03T08:12:02.884282 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22761],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:12:02.890465 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22762],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:12:07.177358 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:12:07.332947 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22761], MiqWorker id: [4], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [4.451218993] seconds
>[----] I, [2018-09-03T08:12:07.333129 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22761], Delivering...
>[----] I, [2018-09-03T08:12:07.378159 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22761], State: [ok], Delivered in [0.045003863] seconds
>[----] I, [2018-09-03T08:12:07.676637 #11268:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22762], MiqWorker id: [44], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [4.788488106] seconds
>[----] I, [2018-09-03T08:12:07.676752 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22762], Delivering...
>[----] I, [2018-09-03T08:12:07.679926 #11268:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22762], State: [ok], Delivered in [0.003171603] seconds
>[----] I, [2018-09-03T08:12:12.180772 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:12:12 UTC]...
>[----] I, [2018-09-03T08:12:12.187533 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:12:12 UTC]...Complete
>[----] I, [2018-09-03T08:12:12.372086 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:12:12.398203 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:12:12.430701 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:12.432841 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:12.451742 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:13.239053 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.006903171539306641, :server_dequeue=>0.004782199859619141, :worker_monitor=>1.0400967597961426, :worker_dequeue=>0.0062105655670166016, :total_time=>1.0584423542022705}
>[----] I, [2018-09-03T08:12:17.903649 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:12:28.453194 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:12:28.478737 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:12:28.522469 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:28.525399 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:28.547212 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:32.916422 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:12:44.253576 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:12:44 UTC]...
>[----] I, [2018-09-03T08:12:44.260923 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:12:44 UTC]...Complete
>[----] I, [2018-09-03T08:12:44.437889 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:12:44.459180 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:12:44.487915 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:44.489854 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:44.506176 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:12:45.358026 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.0075225830078125, :server_dequeue=>0.006123065948486328, :worker_monitor=>1.0836405754089355, :worker_dequeue=>0.006773948669433594, :total_time=>1.1045548915863037}
>[----] I, [2018-09-03T08:12:47.930471 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:13:00.548245 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:13:00.570316 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:13:00.600194 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:00.602223 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:00.618064 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:02.946060 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 4.
>[----] I, [2018-09-03T08:13:02.951875 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22763],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:13:02.956911 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22764],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:13:06.410385 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:13:06.566636 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22763], MiqWorker id: [4], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [3.617125241] seconds
>[----] I, [2018-09-03T08:13:06.566787 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22763], Delivering...
>[----] I, [2018-09-03T08:13:06.614874 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22763], State: [ok], Delivered in [0.048060068] seconds
>[----] I, [2018-09-03T08:13:06.945342 #3400:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22764], MiqWorker id: [34], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [3.989311071] seconds
>[----] I, [2018-09-03T08:13:06.945594 #3400:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22764], Delivering...
>[----] I, [2018-09-03T08:13:06.965323 #3400:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22764], State: [ok], Delivered in [0.017932269] seconds
>[----] I, [2018-09-03T08:13:16.426236 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:13:16 UTC]...
>[----] I, [2018-09-03T08:13:16.434004 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:13:16 UTC]...Complete
>[----] I, [2018-09-03T08:13:16.620219 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:13:16.642401 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:13:16.674370 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:16.676329 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:16.691966 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:17.443492 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.00800633430480957, :server_dequeue=>0.0072557926177978516, :worker_monitor=>0.9959852695465088, :worker_dequeue=>0.00565791130065918, :total_time=>1.017383098602295}
>[----] I, [2018-09-03T08:13:17.968096 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:13:32.650711 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:13:32.682163 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:13:32.727742 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:32.730437 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:32.746416 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:32.979400 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:13:33.561457 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:server_dequeue=>0.004083156585693359, :worker_monitor=>1.0833263397216797, :worker_dequeue=>0.006730794906616211, :total_time=>1.0945117473602295}
>[----] I, [2018-09-03T08:13:47.989921 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:13:48.585870 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:13:48 UTC]...
>[----] I, [2018-09-03T08:13:48.593501 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:13:48 UTC]...Complete
>[----] I, [2018-09-03T08:13:48.775623 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:13:48.797447 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:13:48.825552 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:48.827342 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:48.841369 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:13:49.587467 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.007770538330078125, :server_dequeue=>0.004975557327270508, :worker_monitor=>0.9814774990081787, :worker_dequeue=>0.006991147994995117, :total_time=>1.0017147064208984}
>[----] I, [2018-09-03T08:14:03.001636 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 4.
>[----] I, [2018-09-03T08:14:03.007628 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22765],  id: [], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:14:03.013746 #12655:11e0f7c]  INFO -- : MIQ(MiqQueue.put) Message id: [22766],  id: [], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [ready], Deliver On: [], Data: [], Args: []
>[----] I, [2018-09-03T08:14:04.778646 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:14:04.803768 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:14:04.836151 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:14:04.838208 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:14:04.855256 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:14:05.539192 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#populate_queue_messages) Fetched 2 miq_queue rows for queue_name=generic, wcount=4, priority=200
>[----] I, [2018-09-03T08:14:05.794862 #12620:11e0f7c]  INFO -- : MIQ(MiqPriorityWorker::Runner#get_message_via_drb) Message id: [22765], MiqWorker id: [4], Zone: [default], Role: [], Server: [e36211b5-7ae2-46ad-946f-2552b357e219], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [MiqServer.status_update], Timeout: [600], Priority: [20], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [2.789668854] seconds
>[----] I, [2018-09-03T08:14:05.795044 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22765], Delivering...
>[----] I, [2018-09-03T08:14:05.843793 #12620:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22765], State: [ok], Delivered in [0.048733006] seconds
>[----] I, [2018-09-03T08:14:07.075001 #3400:11e0f7c]  INFO -- : MIQ(MiqGenericWorker::Runner#get_message_via_drb) Message id: [22766], MiqWorker id: [34], Zone: [default], Role: [], Server: [], MiqTask id: [], Ident: [generic], Target id: [], Instance id: [], Task id: [], Command: [Job.check_jobs_for_timeout], Timeout: [600], Priority: [90], State: [dequeue], Deliver On: [], Data: [], Args: [], Dequeued in: [4.063646621] seconds
>[----] I, [2018-09-03T08:14:07.075914 #3400:11e0f7c]  INFO -- : MIQ(MiqQueue#deliver) Message id: [22766], Delivering...
>[----] I, [2018-09-03T08:14:07.080335 #3400:11e0f7c]  INFO -- : MIQ(MiqQueue#delivered) Message id: [22766], State: [ok], Delivered in [0.005162137] seconds
>[----] I, [2018-09-03T08:14:18.027217 #12655:11e0f7c]  INFO -- : MIQ(MiqScheduleWorker::Runner#do_work) Number of scheduled items to be processed: 1.
>[----] I, [2018-09-03T08:14:20.635231 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:14:20 UTC]...
>[----] I, [2018-09-03T08:14:20.642378 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#heartbeat) Heartbeat [2018-09-03 12:14:20 UTC]...Complete
>[----] I, [2018-09-03T08:14:20.844822 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::CloudManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:14:20.866162 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::InfraManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci.  Check log history for more details.
>[----] I, [2018-09-03T08:14:20.897942 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:14:20.900038 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::NetworkManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for uc_ci Network Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:14:20.915488 #12371:11e0f7c]  INFO -- : MIQ(ManageIQ::Providers::Openstack::StorageManager::CinderManager::EventCatcher.all_valid_ems_in_zone) Event Monitor unavailable for oc_ci Cinder Manager.  Check log history for more details.
>[----] I, [2018-09-03T08:14:21.666879 #12371:11e0f7c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:heartbeat=>0.0073740482330322266, :server_dequeue=>0.005681514739990234, :worker_monitor=>1.0086102485656738, :worker_dequeue=>0.00970768928527832, :total_time=>1.0317673683166504}

Actions: View

Attachments on bug 1624840: 1480529 | 1480530 | 1480531