[ovirt-users] Error during hosted-engine-setup for 3.5.1 on F20 (Cannot add the host to cluster ... SSH has failed)

Bob Doolittle bob at doolittle.us.com
Fri Mar 6 20:21:20 UTC 2015


Hi,

I'm following the instructions here: http://www.ovirt.org/Hosted_Engine_Howto

My self-hosted install failed near the end:

          To continue make a selection from the options below:
          (1) Continue setup - engine installation is complete
          (2) Power off and restart the VM
          (3) Abort setup
          (4) Destroy VM and abort setup
         
          (1, 2, 3, 4)[1]: 1
[ INFO  ] Engine replied: DB Up!Welcome to Health Status!
          Enter the name of the cluster to which you want to add the host (Default) [Default]: 
[ ERROR ] Cannot automatically add the host to cluster Default: Cannot add Host. Connecting to host via SSH has failed, verify that the host is reachable (IP address, routable address etc.) You may refer to the engine.log file for further details. 
[ ERROR ] Failed to execute stage 'Closing up': Cannot add the host to cluster Default
[ INFO  ] Stage: Clean up
[ INFO  ] Generating answer file '/var/lib/ovirt-hosted-engine-setup/answers/answers-20150306135624.conf'
[ INFO  ] Stage: Pre-termination
[ INFO  ] Stage: Termination


I can ssh into the engine VM both locally and remotely. There is no /root/.ssh directory, however. Did I need to set that up somehow?

When I log into the Administration portal, the engine VM does not appear under the Virtual machine view (it's empty).

I've attached what I think are the relevant logs.

Also, when my host reboots, the ovirt-ha-broker and ovirt-ha-agent services do not come up automatically. I have to use systemctl to start them manually.

This is a fresh Fedora 20 machine installing a fresh copy of Ovirt 3.5.1.

What's the cleanest approach to restore/complete sanity of my setup please?

Thanks,
   Bob


I've linked 3 files to this email:
server.log <https://db.tt/g5p09AaD>(12.4 MB)Dropbox <https://www.dropbox.com/>https://db.tt/g5p09AaD
vdsm.log <https://db.tt/P4572SUm>(3.2 MB)Dropbox <https://www.dropbox.com/>https://db.tt/P4572SUm
ovirt-hosted-engine-setup-20150306123622-tad1fy.log <https://db.tt/XAM9ffhi>(413 KB)Dropbox <https://www.dropbox.com/>https://db.tt/XAM9ffhi
Mozilla Thunderbird <http://www.getthunderbird.com> makes it easy to share large files over email.

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20150306/706465c6/attachment-0001.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: attachment-24.png
Type: image/png
Size: 641 bytes
Desc: not available
URL: <http://lists.ovirt.org/pipermail/users/attachments/20150306/706465c6/attachment-0002.png>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: dropbox.png
Type: image/png
Size: 1205 bytes
Desc: not available
URL: <http://lists.ovirt.org/pipermail/users/attachments/20150306/706465c6/attachment-0003.png>
-------------- next part --------------
MainThread::INFO::2015-03-06 14:33:35,016::agent::54::ovirt_hosted_engine_ha.agent.agent.Agent::(run) ovirt-hosted-engine-ha agent 1.2.5 started
MainThread::INFO::2015-03-06 14:33:35,059::hosted_engine::222::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_hostname) Found certificate common name: xion2.smartcity.net
MainThread::INFO::2015-03-06 14:33:35,485::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: NONE
MainThread::INFO::2015-03-06 14:33:35,511::hosted_engine::615::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_domain_monitor) Started VDSM domain monitor for 3bfaccc7-cfba-411f-9c9a-93f3877295c1
MainThread::INFO::2015-03-06 14:33:35,521::hosted_engine::368::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_broker) Initializing ha-broker connection
MainThread::INFO::2015-03-06 14:33:35,522::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor ping, options {'addr': '172.16.0.1'}
MainThread::INFO::2015-03-06 14:33:35,522::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 30416720
MainThread::INFO::2015-03-06 14:33:35,523::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor mgmt-bridge, options {'use_ssl': 'true', 'bridge_name': 'ovirtmgmt', 'address': '0'}
MainThread::INFO::2015-03-06 14:33:35,527::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 30561104
MainThread::INFO::2015-03-06 14:33:35,527::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor mem-free, options {'use_ssl': 'true', 'address': '0'}
MainThread::INFO::2015-03-06 14:33:35,529::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 30561040
MainThread::INFO::2015-03-06 14:33:35,529::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor cpu-load-no-engine, options {'use_ssl': 'true', 'vm_uuid': 'b273723a-9586-4899-8573-a9be0c13850a', 'address': '0'}
MainThread::INFO::2015-03-06 14:33:35,530::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 140222025549456
MainThread::INFO::2015-03-06 14:33:35,530::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor engine-health, options {'use_ssl': 'true', 'vm_uuid': 'b273723a-9586-4899-8573-a9be0c13850a', 'address': '0'}
MainThread::INFO::2015-03-06 14:33:35,531::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 140222025587344
MainThread::INFO::2015-03-06 14:33:35,677::brokerlink::178::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(set_storage_domain) Success, id 140222025586960
MainThread::INFO::2015-03-06 14:33:35,677::hosted_engine::458::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_broker) Broker initialized, all submonitors started
MainThread::INFO::2015-03-06 14:33:35,704::hosted_engine::541::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_sanlock) Ensuring lease for lockspace hosted-engine, host id 1 is acquired (file: /rhev/data-center/mnt/xion2:_export/3bfaccc7-cfba-411f-9c9a-93f3877295c1/images/3883b84d-46d8-4c9d-a366-8e09fb872d5f/0352b69d-1bcb-4928-af4b-fef14175bce7)
MainThread::INFO::2015-03-06 14:33:56,706::hosted_engine::575::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_sanlock) Acquired lock on host id 1
MainThread::INFO::2015-03-06 14:33:56,721::state_machine::160::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(refresh) Global metadata: {}
MainThread::INFO::2015-03-06 14:33:56,721::state_machine::168::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(refresh) Local (id 1): {'engine-health': {'health': 'good', 'vm': 'up', 'detail': 'up'}, 'bridge': True, 'mem-free': 20750.0, 'maintenance': False, 'cpu-load': 0.0095, 'gateway': True}
MainThread::INFO::2015-03-06 14:33:56,722::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Trying: notify time=1425670436.72 type=state_transition detail=StartState-ReinitializeFSM hostname='xion2.smartcity.net'
MainThread::INFO::2015-03-06 14:33:56,748::brokerlink::120::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Success, was notification of state_transition (StartState-ReinitializeFSM) sent? ignored
MainThread::INFO::2015-03-06 14:33:57,167::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state ReinitializeFSM (score: 0)
MainThread::INFO::2015-03-06 14:34:07,190::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Trying: notify time=1425670447.19 type=state_transition detail=ReinitializeFSM-EngineStarting hostname='xion2.smartcity.net'
MainThread::INFO::2015-03-06 14:34:07,193::brokerlink::120::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Success, was notification of state_transition (ReinitializeFSM-EngineStarting) sent? ignored
MainThread::INFO::2015-03-06 14:34:07,633::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineStarting (score: 2400)
MainThread::INFO::2015-03-06 14:34:17,655::state_decorators::88::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(check) Timeout cleared while transitioning <class 'ovirt_hosted_engine_ha.agent.states.EngineStarting'> -> <class 'ovirt_hosted_engine_ha.agent.states.EngineUp'>
MainThread::INFO::2015-03-06 14:34:17,656::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Trying: notify time=1425670457.66 type=state_transition detail=EngineStarting-EngineUp hostname='xion2.smartcity.net'
MainThread::INFO::2015-03-06 14:34:17,659::brokerlink::120::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Success, was notification of state_transition (EngineStarting-EngineUp) sent? ignored
MainThread::INFO::2015-03-06 14:34:18,079::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:34:28,102::states::393::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) Engine vm running on localhost
MainThread::INFO::2015-03-06 14:34:28,525::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:34:38,975::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:34:49,431::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:34:59,893::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:35:10,342::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:35:20,794::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:35:30,812::states::393::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) Engine vm running on localhost
MainThread::INFO::2015-03-06 14:35:31,232::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:35:41,683::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:35:51,707::state_decorators::51::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(check) Global maintenance detected
MainThread::INFO::2015-03-06 14:35:51,709::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Trying: notify time=1425670551.71 type=state_transition detail=EngineUp-GlobalMaintenance hostname='xion2.smartcity.net'
MainThread::INFO::2015-03-06 14:35:51,713::brokerlink::120::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Success, was notification of state_transition (EngineUp-GlobalMaintenance) sent? ignored
MainThread::INFO::2015-03-06 14:35:52,144::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state GlobalMaintenance (score: 2400)
MainThread::INFO::2015-03-06 14:36:02,167::state_decorators::51::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(check) Global maintenance detected
MainThread::INFO::2015-03-06 14:36:02,599::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state GlobalMaintenance (score: 2400)
MainThread::INFO::2015-03-06 14:36:12,622::state_decorators::51::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(check) Global maintenance detected
MainThread::INFO::2015-03-06 14:36:13,031::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state GlobalMaintenance (score: 2400)
MainThread::INFO::2015-03-06 14:36:13,451::state_decorators::51::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(check) Global maintenance detected
MainThread::INFO::2015-03-06 14:36:13,452::agent::118::ovirt_hosted_engine_ha.agent.agent.Agent::(run) Agent shutting down
MainThread::INFO::2015-03-06 14:46:55,991::agent::54::ovirt_hosted_engine_ha.agent.agent.Agent::(run) ovirt-hosted-engine-ha agent 1.2.5 started
MainThread::INFO::2015-03-06 14:46:56,086::hosted_engine::222::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_hostname) Found certificate common name: xion2.smartcity.net
MainThread::INFO::2015-03-06 14:46:56,800::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: NONE
MainThread::INFO::2015-03-06 14:46:56,867::hosted_engine::615::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_domain_monitor) Started VDSM domain monitor for 3bfaccc7-cfba-411f-9c9a-93f3877295c1
MainThread::INFO::2015-03-06 14:46:56,880::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:01,907::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:06,936::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:11,965::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:16,994::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:22,023::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:27,052::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:32,081::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:37,108::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:42,137::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:47,165::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:52,193::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:47:57,222::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:02,252::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:07,278::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:12,307::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:17,325::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:22,354::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:27,383::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:32,412::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:37,440::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:42,468::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:47,500::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:52,529::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:48:57,555::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:02,584::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:07,611::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:12,640::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:17,669::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:22,698::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:27,727::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:32,756::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:37,782::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:42,810::hosted_engine::662::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
MainThread::INFO::2015-03-06 14:49:47,839::hosted_engine::368::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_broker) Initializing ha-broker connection
MainThread::INFO::2015-03-06 14:49:47,840::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor ping, options {'addr': '172.16.0.1'}
MainThread::INFO::2015-03-06 14:49:47,841::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 43044560
MainThread::INFO::2015-03-06 14:49:47,841::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor mgmt-bridge, options {'use_ssl': 'true', 'bridge_name': 'ovirtmgmt', 'address': '0'}
MainThread::INFO::2015-03-06 14:49:47,849::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 43193168
MainThread::INFO::2015-03-06 14:49:47,850::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor mem-free, options {'use_ssl': 'true', 'address': '0'}
MainThread::INFO::2015-03-06 14:49:47,853::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 43292624
MainThread::INFO::2015-03-06 14:49:47,854::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor cpu-load-no-engine, options {'use_ssl': 'true', 'vm_uuid': 'b273723a-9586-4899-8573-a9be0c13850a', 'address': '0'}
MainThread::INFO::2015-03-06 14:49:47,857::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 140187531589520
MainThread::INFO::2015-03-06 14:49:47,857::brokerlink::129::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Starting monitor engine-health, options {'use_ssl': 'true', 'vm_uuid': 'b273723a-9586-4899-8573-a9be0c13850a', 'address': '0'}
MainThread::INFO::2015-03-06 14:49:47,860::brokerlink::140::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(start_monitor) Success, id 140187531627280
MainThread::INFO::2015-03-06 14:49:48,041::brokerlink::178::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(set_storage_domain) Success, id 140187397160528
MainThread::INFO::2015-03-06 14:49:48,041::hosted_engine::458::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_broker) Broker initialized, all submonitors started
MainThread::INFO::2015-03-06 14:49:48,077::hosted_engine::541::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_sanlock) Ensuring lease for lockspace hosted-engine, host id 1 is acquired (file: /rhev/data-center/mnt/xion2:_export/3bfaccc7-cfba-411f-9c9a-93f3877295c1/images/3883b84d-46d8-4c9d-a366-8e09fb872d5f/0352b69d-1bcb-4928-af4b-fef14175bce7)
MainThread::INFO::2015-03-06 14:52:29,089::hosted_engine::575::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_initialize_sanlock) Acquired lock on host id 1
MainThread::INFO::2015-03-06 14:52:29,095::state_machine::160::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(refresh) Global metadata: {'maintenance': False}
MainThread::INFO::2015-03-06 14:52:29,095::state_machine::168::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(refresh) Local (id 1): {'engine-health': {'reason': 'vm not running on this host', 'health': 'bad', 'vm': 'down', 'detail': 'unknown'}, 'bridge': True, 'mem-free': 23508.0, 'maintenance': False, 'cpu-load': 0.0039, 'gateway': True}
MainThread::INFO::2015-03-06 14:52:29,095::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Trying: notify time=1425671549.1 type=state_transition detail=StartState-ReinitializeFSM hostname='xion2.smartcity.net'
MainThread::INFO::2015-03-06 14:52:29,165::brokerlink::120::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Success, was notification of state_transition (StartState-ReinitializeFSM) sent? ignored
MainThread::INFO::2015-03-06 14:52:29,670::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state ReinitializeFSM (score: 0)
MainThread::INFO::2015-03-06 14:52:39,692::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Trying: notify time=1425671559.69 type=state_transition detail=ReinitializeFSM-EngineDown hostname='xion2.smartcity.net'
MainThread::INFO::2015-03-06 14:52:39,695::brokerlink::120::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Success, was notification of state_transition (ReinitializeFSM-EngineDown) sent? ignored
MainThread::INFO::2015-03-06 14:52:40,159::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineDown (score: 2400)
MainThread::INFO::2015-03-06 14:52:50,181::states::445::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) The engine is not running, but we do not have enough data to decide which hosts are alive
MainThread::INFO::2015-03-06 14:52:50,679::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineDown (score: 2400)
MainThread::INFO::2015-03-06 14:53:00,698::states::445::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) The engine is not running, but we do not have enough data to decide which hosts are alive
MainThread::INFO::2015-03-06 14:53:01,166::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineDown (score: 2400)
MainThread::INFO::2015-03-06 14:53:11,188::states::445::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) The engine is not running, but we do not have enough data to decide which hosts are alive
MainThread::INFO::2015-03-06 14:53:11,661::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineDown (score: 2400)
MainThread::INFO::2015-03-06 14:53:21,684::states::445::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) The engine is not running, but we do not have enough data to decide which hosts are alive
MainThread::INFO::2015-03-06 14:53:22,139::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineDown (score: 2400)
MainThread::INFO::2015-03-06 14:53:32,160::states::458::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) Engine down and local host has best score (2400), attempting to start engine VM
MainThread::INFO::2015-03-06 14:53:32,162::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Trying: notify time=1425671612.16 type=state_transition detail=EngineDown-EngineStart hostname='xion2.smartcity.net'
MainThread::INFO::2015-03-06 14:53:32,164::brokerlink::120::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Success, was notification of state_transition (EngineDown-EngineStart) sent? ignored
MainThread::INFO::2015-03-06 14:53:32,637::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineStart (score: 2400)
MainThread::INFO::2015-03-06 14:53:32,645::hosted_engine::917::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
MainThread::INFO::2015-03-06 14:53:32,655::hosted_engine::929::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
MainThread::INFO::2015-03-06 14:53:32,655::hosted_engine::884::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
MainThread::INFO::2015-03-06 14:53:32,862::hosted_engine::890::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_start_engine_vm) stdout: 
b273723a-9586-4899-8573-a9be0c13850a
	Status = WaitForLaunch
	nicModel = rtl8139,pv
	emulatedMachine = pc
	pid = 0
	vmName = HostedEngine
	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'f3a6f2d8-5b09-4992-8705-1724d9944264', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/nas/iso/Linux/Fedora/Fedora-Live-Desktop-x86_64-20-1.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'format': 'raw', 'bootOrder': '1', 'poolID': '00000000-0000-0000-0000-000000000000', 'volumeID': 'b8bf4875-6259-459c-b3e8-efdc7961f829', 'imageID': '0c845577-8285-4547-b812-a77f53f96a67', 'specParams': {}, 'readonly': 'false', 'domainID': '3bfaccc7-cfba-411f-9c9a-93f3877295c1', 'optional': 'false', 'deviceId': '0c845577-8285-4547-b812-a77f53f96a67', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'type': 'disk'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:1f:0a:2b', 'linkActive': 'true', 'network': 'ovirtmgmt', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': 'cd5f9184-91b1-4273-905c-844c29cf7fbf', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': 'b14992a0-ba2f-4419-9eb2-3ae44e459b16', 'alias': 'console0'}]
	smp = 2
	vmType = kvm
	display = vnc
	displaySecurePort = -1
	memSize = 4096
	displayPort = -1
	cpuType = SandyBridge
	clientIp = 
	guestDiskMapping = {}
	displayIp = 0
	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir

MainThread::INFO::2015-03-06 14:53:32,863::hosted_engine::891::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_start_engine_vm) stderr: 
MainThread::ERROR::2015-03-06 14:53:32,863::hosted_engine::904::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(_start_engine_vm) Engine VM started on localhost
MainThread::INFO::2015-03-06 14:53:32,864::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Trying: notify time=1425671612.86 type=state_transition detail=EngineStart-EngineStarting hostname='xion2.smartcity.net'
MainThread::INFO::2015-03-06 14:53:32,865::brokerlink::120::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Success, was notification of state_transition (EngineStart-EngineStarting) sent? ignored
MainThread::INFO::2015-03-06 14:53:33,253::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineStarting (score: 2400)
MainThread::INFO::2015-03-06 14:53:43,286::states::721::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) VM is powering up..
MainThread::INFO::2015-03-06 14:53:43,287::state_decorators::95::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(check) Timeout set to Fri Mar  6 15:03:43 2015 while transitioning <class 'ovirt_hosted_engine_ha.agent.states.EngineStarting'> -> <class 'ovirt_hosted_engine_ha.agent.states.EngineStarting'>
MainThread::INFO::2015-03-06 14:53:43,743::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineStarting (score: 2400)
MainThread::INFO::2015-03-06 14:53:53,769::states::721::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) VM is powering up..
MainThread::INFO::2015-03-06 14:53:54,223::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineStarting (score: 2400)
MainThread::INFO::2015-03-06 14:54:04,245::states::721::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) VM is powering up..
MainThread::INFO::2015-03-06 14:54:04,701::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineStarting (score: 2400)
MainThread::INFO::2015-03-06 14:54:14,724::states::721::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) VM is powering up..
MainThread::INFO::2015-03-06 14:54:15,183::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineStarting (score: 2400)
MainThread::INFO::2015-03-06 14:54:25,214::states::721::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) VM is powering up..
MainThread::INFO::2015-03-06 14:54:25,660::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineStarting (score: 2400)
MainThread::INFO::2015-03-06 14:54:35,681::states::721::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) VM is powering up..
MainThread::INFO::2015-03-06 14:54:36,035::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineStarting (score: 2400)
MainThread::INFO::2015-03-06 14:54:46,060::state_decorators::88::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(check) Timeout cleared while transitioning <class 'ovirt_hosted_engine_ha.agent.states.EngineStarting'> -> <class 'ovirt_hosted_engine_ha.agent.states.EngineUp'>
MainThread::INFO::2015-03-06 14:54:46,062::brokerlink::111::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Trying: notify time=1425671686.06 type=state_transition detail=EngineStarting-EngineUp hostname='xion2.smartcity.net'
MainThread::INFO::2015-03-06 14:54:46,065::brokerlink::120::ovirt_hosted_engine_ha.lib.brokerlink.BrokerLink::(notify) Success, was notification of state_transition (EngineStarting-EngineUp) sent? ignored
MainThread::INFO::2015-03-06 14:54:46,537::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:54:56,560::states::393::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) Engine vm running on localhost
MainThread::INFO::2015-03-06 14:54:56,989::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:55:07,420::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:55:17,886::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:55:28,351::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:55:38,778::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:55:49,223::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:55:59,246::states::393::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) Engine vm running on localhost
MainThread::INFO::2015-03-06 14:55:59,723::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:56:10,178::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:56:20,626::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:56:31,047::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:56:41,536::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:56:51,974::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:57:01,994::states::393::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) Engine vm running on localhost
MainThread::INFO::2015-03-06 14:57:02,443::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:57:12,930::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:57:23,398::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:57:33,849::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:57:44,295::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:57:54,751::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:58:04,774::states::393::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) Engine vm running on localhost
MainThread::INFO::2015-03-06 14:58:05,240::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:58:15,709::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:58:26,167::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:58:36,597::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:58:46,985::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:58:57,431::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:59:07,454::states::393::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(consume) Engine vm running on localhost
MainThread::INFO::2015-03-06 14:59:07,865::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:59:18,329::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:59:28,796::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:59:39,237::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
MainThread::INFO::2015-03-06 14:59:49,680::hosted_engine::327::ovirt_hosted_engine_ha.agent.hosted_engine.HostedEngine::(start_monitoring) Current state EngineUp (score: 2400)
-------------- next part --------------
MainThread::INFO::2015-03-06 14:33:34,829::broker::57::ovirt_hosted_engine_ha.broker.broker.Broker::(run) ovirt-hosted-engine-ha broker 1.2.5 started
MainThread::INFO::2015-03-06 14:33:34,847::monitor::40::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Searching for submonitors in /usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/submonitors
MainThread::INFO::2015-03-06 14:33:34,847::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor cpu-load
MainThread::INFO::2015-03-06 14:33:34,862::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor engine-health
MainThread::INFO::2015-03-06 14:33:34,862::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mgmt-bridge
MainThread::INFO::2015-03-06 14:33:34,863::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mgmt-bridge
MainThread::INFO::2015-03-06 14:33:34,863::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mem-free
MainThread::INFO::2015-03-06 14:33:34,864::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mem-load
MainThread::INFO::2015-03-06 14:33:34,864::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor cpu-load
MainThread::INFO::2015-03-06 14:33:34,869::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor ping
MainThread::INFO::2015-03-06 14:33:34,869::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mem-load
MainThread::INFO::2015-03-06 14:33:34,883::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor cpu-load-no-engine
MainThread::INFO::2015-03-06 14:33:34,884::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor cpu-load-no-engine
MainThread::INFO::2015-03-06 14:33:34,884::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mem-free
MainThread::INFO::2015-03-06 14:33:34,884::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor engine-health
MainThread::INFO::2015-03-06 14:33:34,885::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor ping
MainThread::INFO::2015-03-06 14:33:34,885::monitor::50::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Finished loading submonitors
MainThread::INFO::2015-03-06 14:33:34,885::listener::41::ovirt_hosted_engine_ha.broker.listener.Listener::(__init__) Initializing SocketServer
MainThread::INFO::2015-03-06 14:33:34,885::listener::56::ovirt_hosted_engine_ha.broker.listener.Listener::(__init__) SocketServer ready
Thread-1::INFO::2015-03-06 14:33:35,522::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-1::INFO::2015-03-06 14:33:35,522::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor ping
Thread-1::INFO::2015-03-06 14:33:35,522::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor ping, id 30416720
Thread-1::INFO::2015-03-06 14:33:35,523::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor mgmt-bridge
Thread-1::INFO::2015-03-06 14:33:35,526::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor mgmt-bridge, id 30561104
Thread-2::INFO::2015-03-06 14:33:35,527::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-1::INFO::2015-03-06 14:33:35,528::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor mem-free
Thread-1::INFO::2015-03-06 14:33:35,529::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor mem-free, id 30561040
Thread-1::INFO::2015-03-06 14:33:35,529::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor cpu-load-no-engine
Thread-1::INFO::2015-03-06 14:33:35,530::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor cpu-load-no-engine, id 140222025549456
Thread-1::INFO::2015-03-06 14:33:35,531::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor engine-health
Thread-1::INFO::2015-03-06 14:33:35,531::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor engine-health, id 140222025587344
Thread-7::INFO::2015-03-06 14:33:35,600::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-6::INFO::2015-03-06 14:33:35,642::engine_health::110::engine_health.CpuLoadNoEngine::(action) VM is up on this host with healthy engine
Thread-1::INFO::2015-03-06 14:33:35,648::storage_backends::112::ovirt_hosted_engine_ha.lib.storage_backends::(_check_symlinks) Cleaning up stale LV link '/rhev/data-center/mnt/xion2:_export/3bfaccc7-cfba-411f-9c9a-93f3877295c1/ha_agent/hosted-engine.lockspace'
Thread-3::INFO::2015-03-06 14:33:35,650::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-1::INFO::2015-03-06 14:33:35,676::storage_backends::112::ovirt_hosted_engine_ha.lib.storage_backends::(_check_symlinks) Cleaning up stale LV link '/rhev/data-center/mnt/xion2:_export/3bfaccc7-cfba-411f-9c9a-93f3877295c1/ha_agent/hosted-engine.metadata'
Thread-7::INFO::2015-03-06 14:33:35,678::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:33:35,683::mem_free::53::mem_free.MemFree::(action) memFree: 20751
Thread-8::INFO::2015-03-06 14:33:41,771::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-8::INFO::2015-03-06 14:33:41,774::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-9::INFO::2015-03-06 14:33:41,775::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-9::INFO::2015-03-06 14:33:41,776::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-10::INFO::2015-03-06 14:33:44,326::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-10::INFO::2015-03-06 14:33:44,328::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-11::INFO::2015-03-06 14:33:44,329::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-11::INFO::2015-03-06 14:33:44,330::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-5::INFO::2015-03-06 14:33:45,607::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0142, engine=0.0047, non-engine=0.0095
Thread-12::INFO::2015-03-06 14:33:45,729::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-12::INFO::2015-03-06 14:33:45,731::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:33:45,746::mem_free::53::mem_free.MemFree::(action) memFree: 20747
Thread-13::INFO::2015-03-06 14:33:55,305::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-13::INFO::2015-03-06 14:33:55,307::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-14::INFO::2015-03-06 14:33:55,307::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-14::INFO::2015-03-06 14:33:55,309::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-15::INFO::2015-03-06 14:33:55,775::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-15::INFO::2015-03-06 14:33:55,777::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:33:55,790::mem_free::53::mem_free.MemFree::(action) memFree: 20750
Thread-16::INFO::2015-03-06 14:33:56,586::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-16::INFO::2015-03-06 14:33:56,587::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-17::INFO::2015-03-06 14:33:56,588::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-17::INFO::2015-03-06 14:33:56,589::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-1::ERROR::2015-03-06 14:33:56,730::notifications::30::ovirt_hosted_engine_ha.broker.notifications.Notifications::(send_email) [Errno 111] Connection refused
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/notifications.py", line 21, in send_email
    server = smtplib.SMTP(cfg["smtp-server"], port=cfg["smtp-port"])
  File "/usr/lib64/python2.7/smtplib.py", line 250, in __init__
    (code, msg) = self.connect(host, port)
  File "/usr/lib64/python2.7/smtplib.py", line 310, in connect
    self.sock = self._get_socket(host, port, self.timeout)
  File "/usr/lib64/python2.7/smtplib.py", line 285, in _get_socket
    return socket.create_connection((host, port), timeout)
  File "/usr/lib64/python2.7/socket.py", line 571, in create_connection
    raise err
error: [Errno 111] Connection refused
Thread-18::INFO::2015-03-06 14:34:05,826::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-18::INFO::2015-03-06 14:34:05,830::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-19::INFO::2015-03-06 14:34:05,831::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-19::INFO::2015-03-06 14:34:05,832::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:34:05,843::mem_free::53::mem_free.MemFree::(action) memFree: 20747
Thread-1::ERROR::2015-03-06 14:34:07,191::notifications::30::ovirt_hosted_engine_ha.broker.notifications.Notifications::(send_email) [Errno 111] Connection refused
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/notifications.py", line 21, in send_email
    server = smtplib.SMTP(cfg["smtp-server"], port=cfg["smtp-port"])
  File "/usr/lib64/python2.7/smtplib.py", line 250, in __init__
    (code, msg) = self.connect(host, port)
  File "/usr/lib64/python2.7/smtplib.py", line 310, in connect
    self.sock = self._get_socket(host, port, self.timeout)
  File "/usr/lib64/python2.7/smtplib.py", line 285, in _get_socket
    return socket.create_connection((host, port), timeout)
  File "/usr/lib64/python2.7/socket.py", line 571, in create_connection
    raise err
error: [Errno 111] Connection refused
Thread-20::INFO::2015-03-06 14:34:07,343::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-20::INFO::2015-03-06 14:34:07,345::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-21::INFO::2015-03-06 14:34:07,346::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-21::INFO::2015-03-06 14:34:07,347::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-22::INFO::2015-03-06 14:34:07,347::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-22::INFO::2015-03-06 14:34:07,348::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-23::INFO::2015-03-06 14:34:13,276::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-23::INFO::2015-03-06 14:34:13,278::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-24::INFO::2015-03-06 14:34:13,278::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-24::INFO::2015-03-06 14:34:13,279::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:34:13,280::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-25::INFO::2015-03-06 14:34:13,282::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-26::INFO::2015-03-06 14:34:15,865::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-26::INFO::2015-03-06 14:34:15,867::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-27::INFO::2015-03-06 14:34:15,868::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-27::INFO::2015-03-06 14:34:15,869::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:34:15,877::mem_free::53::mem_free.MemFree::(action) memFree: 20741
Thread-28::INFO::2015-03-06 14:34:15,927::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-28::INFO::2015-03-06 14:34:15,929::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-29::INFO::2015-03-06 14:34:15,929::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-29::INFO::2015-03-06 14:34:15,930::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-30::INFO::2015-03-06 14:34:15,930::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-30::INFO::2015-03-06 14:34:15,932::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-1::ERROR::2015-03-06 14:34:17,658::notifications::30::ovirt_hosted_engine_ha.broker.notifications.Notifications::(send_email) [Errno 111] Connection refused
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/notifications.py", line 21, in send_email
    server = smtplib.SMTP(cfg["smtp-server"], port=cfg["smtp-port"])
  File "/usr/lib64/python2.7/smtplib.py", line 250, in __init__
    (code, msg) = self.connect(host, port)
  File "/usr/lib64/python2.7/smtplib.py", line 310, in connect
    self.sock = self._get_socket(host, port, self.timeout)
  File "/usr/lib64/python2.7/smtplib.py", line 285, in _get_socket
    return socket.create_connection((host, port), timeout)
  File "/usr/lib64/python2.7/socket.py", line 571, in create_connection
    raise err
error: [Errno 111] Connection refused
Thread-31::INFO::2015-03-06 14:34:18,590::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-31::INFO::2015-03-06 14:34:18,593::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-32::INFO::2015-03-06 14:34:18,593::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-32::INFO::2015-03-06 14:34:18,594::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-33::INFO::2015-03-06 14:34:18,595::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-33::INFO::2015-03-06 14:34:18,596::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-34::INFO::2015-03-06 14:34:20,745::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-34::INFO::2015-03-06 14:34:20,748::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-35::INFO::2015-03-06 14:34:20,749::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-35::INFO::2015-03-06 14:34:20,751::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-36::INFO::2015-03-06 14:34:20,751::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-36::INFO::2015-03-06 14:34:20,753::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-37::INFO::2015-03-06 14:34:24,913::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-37::INFO::2015-03-06 14:34:24,915::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-38::INFO::2015-03-06 14:34:24,916::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-38::INFO::2015-03-06 14:34:24,917::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:34:24,927::mem_free::53::mem_free.MemFree::(action) memFree: 20748
Thread-39::INFO::2015-03-06 14:34:34,959::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-39::INFO::2015-03-06 14:34:34,963::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-40::INFO::2015-03-06 14:34:34,964::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-40::INFO::2015-03-06 14:34:34,965::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:34:34,975::mem_free::53::mem_free.MemFree::(action) memFree: 20744
Thread-2::INFO::2015-03-06 14:34:35,604::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-3::INFO::2015-03-06 14:34:36,127::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-41::INFO::2015-03-06 14:34:45,011::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-41::INFO::2015-03-06 14:34:45,013::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-42::INFO::2015-03-06 14:34:45,014::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-42::INFO::2015-03-06 14:34:45,015::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:34:45,026::mem_free::53::mem_free.MemFree::(action) memFree: 20742
Thread-6::INFO::2015-03-06 14:34:45,442::engine_health::110::engine_health.CpuLoadNoEngine::(action) VM is up on this host with healthy engine
Thread-5::INFO::2015-03-06 14:34:45,663::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0193, engine=0.0065, non-engine=0.0127
Thread-43::INFO::2015-03-06 14:34:55,063::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-43::INFO::2015-03-06 14:34:55,067::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-44::INFO::2015-03-06 14:34:55,068::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-44::INFO::2015-03-06 14:34:55,069::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:34:55,079::mem_free::53::mem_free.MemFree::(action) memFree: 20743
Thread-45::INFO::2015-03-06 14:35:05,107::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-45::INFO::2015-03-06 14:35:05,109::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-46::INFO::2015-03-06 14:35:05,110::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-46::INFO::2015-03-06 14:35:05,111::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-47::INFO::2015-03-06 14:35:15,149::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-47::INFO::2015-03-06 14:35:15,152::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-48::INFO::2015-03-06 14:35:15,153::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-48::INFO::2015-03-06 14:35:15,154::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-49::INFO::2015-03-06 14:35:25,192::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-49::INFO::2015-03-06 14:35:25,195::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-50::INFO::2015-03-06 14:35:25,196::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-50::INFO::2015-03-06 14:35:25,197::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:35:25,208::mem_free::53::mem_free.MemFree::(action) memFree: 20744
Thread-51::INFO::2015-03-06 14:35:35,243::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-51::INFO::2015-03-06 14:35:35,246::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-52::INFO::2015-03-06 14:35:35,247::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-52::INFO::2015-03-06 14:35:35,248::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:35:35,258::mem_free::53::mem_free.MemFree::(action) memFree: 20742
Thread-2::INFO::2015-03-06 14:35:35,689::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-3::INFO::2015-03-06 14:35:36,892::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-53::INFO::2015-03-06 14:35:41,402::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-53::INFO::2015-03-06 14:35:41,424::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-54::INFO::2015-03-06 14:35:44,176::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-54::INFO::2015-03-06 14:35:44,179::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-55::INFO::2015-03-06 14:35:44,179::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-55::INFO::2015-03-06 14:35:44,180::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-56::INFO::2015-03-06 14:35:44,181::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-56::INFO::2015-03-06 14:35:44,183::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-57::INFO::2015-03-06 14:35:45,292::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-57::INFO::2015-03-06 14:35:45,294::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-58::INFO::2015-03-06 14:35:45,295::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-58::INFO::2015-03-06 14:35:45,296::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-5::INFO::2015-03-06 14:35:45,700::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0178, engine=0.0050, non-engine=0.0127
Thread-6::INFO::2015-03-06 14:35:46,054::engine_health::110::engine_health.CpuLoadNoEngine::(action) VM is up on this host with healthy engine
Thread-1::ERROR::2015-03-06 14:35:51,711::notifications::30::ovirt_hosted_engine_ha.broker.notifications.Notifications::(send_email) [Errno 111] Connection refused
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/notifications.py", line 21, in send_email
    server = smtplib.SMTP(cfg["smtp-server"], port=cfg["smtp-port"])
  File "/usr/lib64/python2.7/smtplib.py", line 250, in __init__
    (code, msg) = self.connect(host, port)
  File "/usr/lib64/python2.7/smtplib.py", line 310, in connect
    self.sock = self._get_socket(host, port, self.timeout)
  File "/usr/lib64/python2.7/smtplib.py", line 285, in _get_socket
    return socket.create_connection((host, port), timeout)
  File "/usr/lib64/python2.7/socket.py", line 571, in create_connection
    raise err
error: [Errno 111] Connection refused
Thread-59::INFO::2015-03-06 14:35:55,331::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-59::INFO::2015-03-06 14:35:55,333::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-60::INFO::2015-03-06 14:35:55,333::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-60::INFO::2015-03-06 14:35:55,334::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-6::WARNING::2015-03-06 14:35:56,150::engine_health::116::engine_health.CpuLoadNoEngine::(action) bad health status: Hosted Engine is not up!

Thread-61::INFO::2015-03-06 14:36:00,083::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-61::INFO::2015-03-06 14:36:00,085::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-62::INFO::2015-03-06 14:36:00,085::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-62::INFO::2015-03-06 14:36:00,086::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-63::INFO::2015-03-06 14:36:00,086::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-63::INFO::2015-03-06 14:36:00,087::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-64::INFO::2015-03-06 14:36:04,064::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-64::INFO::2015-03-06 14:36:04,067::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-65::INFO::2015-03-06 14:36:04,067::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-65::INFO::2015-03-06 14:36:04,069::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-66::INFO::2015-03-06 14:36:04,069::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-66::INFO::2015-03-06 14:36:04,071::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-67::INFO::2015-03-06 14:36:05,375::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-67::INFO::2015-03-06 14:36:05,378::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-68::INFO::2015-03-06 14:36:05,379::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-68::INFO::2015-03-06 14:36:05,380::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:36:05,390::mem_free::53::mem_free.MemFree::(action) memFree: 23234
Thread-6::INFO::2015-03-06 14:36:06,183::engine_health::94::engine_health.CpuLoadNoEngine::(action) VM not running on this host, status down
Thread-69::INFO::2015-03-06 14:36:08,575::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-69::INFO::2015-03-06 14:36:08,577::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-70::INFO::2015-03-06 14:36:08,578::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-70::INFO::2015-03-06 14:36:08,579::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-71::INFO::2015-03-06 14:36:08,580::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-71::INFO::2015-03-06 14:36:08,582::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-72::INFO::2015-03-06 14:36:11,198::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-72::INFO::2015-03-06 14:36:11,201::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-73::INFO::2015-03-06 14:36:11,202::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-73::INFO::2015-03-06 14:36:11,203::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-74::INFO::2015-03-06 14:36:11,204::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-74::INFO::2015-03-06 14:36:11,205::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-1::INFO::2015-03-06 14:36:13,452::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-1::INFO::2015-03-06 14:36:13,452::monitor::90::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopping submonitor ping, id 30416720
Thread-1::INFO::2015-03-06 14:36:13,452::monitor::99::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopped submonitor ping, id 30416720
Thread-1::INFO::2015-03-06 14:36:13,452::monitor::90::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopping submonitor mgmt-bridge, id 30561104
Thread-1::INFO::2015-03-06 14:36:13,452::monitor::99::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopped submonitor mgmt-bridge, id 30561104
Thread-1::INFO::2015-03-06 14:36:13,453::monitor::90::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopping submonitor mem-free, id 30561040
Thread-1::INFO::2015-03-06 14:36:13,453::monitor::99::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopped submonitor mem-free, id 30561040
Thread-1::INFO::2015-03-06 14:36:13,453::monitor::90::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopping submonitor cpu-load-no-engine, id 140222025549456
Thread-1::INFO::2015-03-06 14:36:13,453::monitor::99::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopped submonitor cpu-load-no-engine, id 140222025549456
Thread-1::INFO::2015-03-06 14:36:13,453::monitor::90::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopping submonitor engine-health, id 140222025587344
Thread-1::INFO::2015-03-06 14:36:13,453::monitor::99::ovirt_hosted_engine_ha.broker.monitor.Monitor::(stop_submonitor) Stopped submonitor engine-health, id 140222025587344
MainThread::INFO::2015-03-06 14:36:14,367::broker::114::ovirt_hosted_engine_ha.broker.broker.Broker::(run) Server shutting down
MainThread::INFO::2015-03-06 14:46:55,679::broker::57::ovirt_hosted_engine_ha.broker.broker.Broker::(run) ovirt-hosted-engine-ha broker 1.2.5 started
MainThread::INFO::2015-03-06 14:46:55,713::monitor::40::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Searching for submonitors in /usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/submonitors
MainThread::INFO::2015-03-06 14:46:55,714::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor cpu-load
MainThread::INFO::2015-03-06 14:46:55,809::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor engine-health
MainThread::INFO::2015-03-06 14:46:55,811::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mgmt-bridge
MainThread::INFO::2015-03-06 14:46:55,811::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mgmt-bridge
MainThread::INFO::2015-03-06 14:46:55,812::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mem-free
MainThread::INFO::2015-03-06 14:46:55,813::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mem-load
MainThread::INFO::2015-03-06 14:46:55,813::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor cpu-load
MainThread::INFO::2015-03-06 14:46:55,826::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor ping
MainThread::INFO::2015-03-06 14:46:55,826::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mem-load
MainThread::INFO::2015-03-06 14:46:55,836::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor cpu-load-no-engine
MainThread::INFO::2015-03-06 14:46:55,837::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor cpu-load-no-engine
MainThread::INFO::2015-03-06 14:46:55,837::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor mem-free
MainThread::INFO::2015-03-06 14:46:55,838::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor engine-health
MainThread::INFO::2015-03-06 14:46:55,838::monitor::49::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Loaded submonitor ping
MainThread::INFO::2015-03-06 14:46:55,838::monitor::50::ovirt_hosted_engine_ha.broker.monitor.Monitor::(_discover_submonitors) Finished loading submonitors
MainThread::INFO::2015-03-06 14:46:55,838::listener::41::ovirt_hosted_engine_ha.broker.listener.Listener::(__init__) Initializing SocketServer
MainThread::INFO::2015-03-06 14:46:55,839::listener::56::ovirt_hosted_engine_ha.broker.listener.Listener::(__init__) SocketServer ready
Thread-1::INFO::2015-03-06 14:47:19,302::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-1::INFO::2015-03-06 14:47:19,311::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-2::INFO::2015-03-06 14:47:19,312::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-2::INFO::2015-03-06 14:47:19,313::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-3::INFO::2015-03-06 14:47:19,313::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-3::INFO::2015-03-06 14:47:19,315::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-4::INFO::2015-03-06 14:48:02,946::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-4::INFO::2015-03-06 14:48:02,982::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-5::INFO::2015-03-06 14:48:04,902::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-5::INFO::2015-03-06 14:48:04,904::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-6::INFO::2015-03-06 14:48:04,904::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-6::INFO::2015-03-06 14:48:04,906::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-7::INFO::2015-03-06 14:48:04,906::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-7::INFO::2015-03-06 14:48:04,907::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-8::INFO::2015-03-06 14:48:14,718::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-8::INFO::2015-03-06 14:48:14,753::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-9::INFO::2015-03-06 14:48:17,324::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-9::INFO::2015-03-06 14:48:17,326::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-10::INFO::2015-03-06 14:48:17,327::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-10::INFO::2015-03-06 14:48:17,328::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-11::INFO::2015-03-06 14:48:17,328::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-11::INFO::2015-03-06 14:48:17,330::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-12::INFO::2015-03-06 14:48:35,645::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-12::INFO::2015-03-06 14:48:35,648::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-13::INFO::2015-03-06 14:48:35,648::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-13::INFO::2015-03-06 14:48:35,650::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-14::INFO::2015-03-06 14:48:35,650::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-14::INFO::2015-03-06 14:48:35,652::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-15::INFO::2015-03-06 14:49:02,773::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-15::INFO::2015-03-06 14:49:02,800::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-16::INFO::2015-03-06 14:49:04,871::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-16::INFO::2015-03-06 14:49:04,874::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-17::INFO::2015-03-06 14:49:04,875::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-17::INFO::2015-03-06 14:49:04,876::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-18::INFO::2015-03-06 14:49:04,877::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-18::INFO::2015-03-06 14:49:04,879::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-19::INFO::2015-03-06 14:49:13,697::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-19::INFO::2015-03-06 14:49:13,700::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-20::INFO::2015-03-06 14:49:13,701::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-20::INFO::2015-03-06 14:49:13,702::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-21::INFO::2015-03-06 14:49:13,702::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-21::INFO::2015-03-06 14:49:13,704::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-22::INFO::2015-03-06 14:49:47,840::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-22::INFO::2015-03-06 14:49:47,840::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor ping
Thread-22::INFO::2015-03-06 14:49:47,841::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor ping, id 43044560
Thread-22::INFO::2015-03-06 14:49:47,843::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor mgmt-bridge
Thread-22::INFO::2015-03-06 14:49:47,848::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor mgmt-bridge, id 43193168
Thread-22::INFO::2015-03-06 14:49:47,851::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor mem-free
Thread-22::INFO::2015-03-06 14:49:47,853::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor mem-free, id 43292624
Thread-23::INFO::2015-03-06 14:49:47,854::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-22::INFO::2015-03-06 14:49:47,855::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor cpu-load-no-engine
Thread-22::INFO::2015-03-06 14:49:47,857::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor cpu-load-no-engine, id 140187531589520
Thread-22::INFO::2015-03-06 14:49:47,858::monitor::66::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Starting submonitor engine-health
Thread-22::INFO::2015-03-06 14:49:47,860::monitor::79::ovirt_hosted_engine_ha.broker.monitor.Monitor::(start_submonitor) Started submonitor engine-health, id 140187531627280
Thread-26::INFO::2015-03-06 14:49:47,910::cpu_load_no_engine::153::cpu_load_no_engine.EngineHealth::(update_stat_file) VM not on this host
Thread-27::INFO::2015-03-06 14:49:47,926::engine_health::63::engine_health.CpuLoadNoEngine::(action) VM not on this host
Thread-28::INFO::2015-03-06 14:49:47,936::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-22::INFO::2015-03-06 14:49:47,997::storage_backends::112::ovirt_hosted_engine_ha.lib.storage_backends::(_check_symlinks) Cleaning up stale LV link '/rhev/data-center/mnt/xion2:_export/3bfaccc7-cfba-411f-9c9a-93f3877295c1/ha_agent/hosted-engine.lockspace'
Thread-22::INFO::2015-03-06 14:49:48,040::storage_backends::112::ovirt_hosted_engine_ha.lib.storage_backends::(_check_symlinks) Cleaning up stale LV link '/rhev/data-center/mnt/xion2:_export/3bfaccc7-cfba-411f-9c9a-93f3877295c1/ha_agent/hosted-engine.metadata'
Thread-28::INFO::2015-03-06 14:49:48,045::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-29::INFO::2015-03-06 14:49:48,046::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-29::INFO::2015-03-06 14:49:48,047::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-30::INFO::2015-03-06 14:49:48,049::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-30::INFO::2015-03-06 14:49:48,051::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-31::INFO::2015-03-06 14:49:48,054::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-25::INFO::2015-03-06 14:49:48,056::mem_free::53::mem_free.MemFree::(action) memFree: 23516
Thread-31::INFO::2015-03-06 14:49:48,057::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-32::INFO::2015-03-06 14:49:48,057::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-32::INFO::2015-03-06 14:49:48,059::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-24::INFO::2015-03-06 14:49:48,231::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-33::INFO::2015-03-06 14:49:53,785::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-33::INFO::2015-03-06 14:49:53,809::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-34::INFO::2015-03-06 14:49:55,280::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-34::INFO::2015-03-06 14:49:55,283::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-35::INFO::2015-03-06 14:49:55,283::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-35::INFO::2015-03-06 14:49:55,285::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-36::INFO::2015-03-06 14:49:55,285::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-36::INFO::2015-03-06 14:49:55,287::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-37::INFO::2015-03-06 14:49:57,093::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-37::INFO::2015-03-06 14:49:57,096::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-38::INFO::2015-03-06 14:49:57,096::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-38::INFO::2015-03-06 14:49:57,097::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:49:57,106::mem_free::53::mem_free.MemFree::(action) memFree: 23508
Thread-26::INFO::2015-03-06 14:49:57,946::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0182, engine=0.0000, non-engine=0.0182
Thread-39::INFO::2015-03-06 14:50:07,142::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-39::INFO::2015-03-06 14:50:07,145::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-40::INFO::2015-03-06 14:50:07,145::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-40::INFO::2015-03-06 14:50:07,147::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-41::INFO::2015-03-06 14:50:17,190::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-41::INFO::2015-03-06 14:50:17,192::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-42::INFO::2015-03-06 14:50:17,193::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-42::INFO::2015-03-06 14:50:17,194::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-43::INFO::2015-03-06 14:50:27,238::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-43::INFO::2015-03-06 14:50:27,240::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-44::INFO::2015-03-06 14:50:27,241::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-44::INFO::2015-03-06 14:50:27,242::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:50:27,251::mem_free::53::mem_free.MemFree::(action) memFree: 23507
Thread-45::INFO::2015-03-06 14:50:37,285::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-45::INFO::2015-03-06 14:50:37,287::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-46::INFO::2015-03-06 14:50:37,288::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-46::INFO::2015-03-06 14:50:37,289::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:50:37,299::mem_free::53::mem_free.MemFree::(action) memFree: 23508
Thread-47::INFO::2015-03-06 14:50:47,334::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-47::INFO::2015-03-06 14:50:47,337::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-48::INFO::2015-03-06 14:50:47,338::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-48::INFO::2015-03-06 14:50:47,339::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:50:47,349::mem_free::53::mem_free.MemFree::(action) memFree: 23507
Thread-23::INFO::2015-03-06 14:50:47,943::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-27::INFO::2015-03-06 14:50:57,129::engine_health::63::engine_health.CpuLoadNoEngine::(action) VM not on this host
Thread-49::INFO::2015-03-06 14:50:57,382::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-49::INFO::2015-03-06 14:50:57,385::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-50::INFO::2015-03-06 14:50:57,386::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-50::INFO::2015-03-06 14:50:57,387::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-24::INFO::2015-03-06 14:50:57,723::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-26::INFO::2015-03-06 14:50:58,028::cpu_load_no_engine::153::cpu_load_no_engine.EngineHealth::(update_stat_file) VM not on this host
Thread-26::INFO::2015-03-06 14:50:58,028::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0039, engine=0.0000, non-engine=0.0039
Thread-51::INFO::2015-03-06 14:51:07,424::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-51::INFO::2015-03-06 14:51:07,427::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-52::INFO::2015-03-06 14:51:07,427::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-52::INFO::2015-03-06 14:51:07,429::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-53::INFO::2015-03-06 14:51:17,471::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-53::INFO::2015-03-06 14:51:17,473::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-54::INFO::2015-03-06 14:51:17,474::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-54::INFO::2015-03-06 14:51:17,475::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-55::INFO::2015-03-06 14:51:27,509::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-55::INFO::2015-03-06 14:51:27,511::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-56::INFO::2015-03-06 14:51:27,512::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-56::INFO::2015-03-06 14:51:27,513::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:51:27,523::mem_free::53::mem_free.MemFree::(action) memFree: 23508
Thread-57::INFO::2015-03-06 14:51:37,556::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-57::INFO::2015-03-06 14:51:37,558::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-58::INFO::2015-03-06 14:51:37,559::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-58::INFO::2015-03-06 14:51:37,560::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-59::INFO::2015-03-06 14:51:47,596::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-59::INFO::2015-03-06 14:51:47,598::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-60::INFO::2015-03-06 14:51:47,599::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-60::INFO::2015-03-06 14:51:47,600::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-23::INFO::2015-03-06 14:51:48,030::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-27::INFO::2015-03-06 14:51:57,309::engine_health::63::engine_health.CpuLoadNoEngine::(action) VM not on this host
Thread-61::INFO::2015-03-06 14:51:57,643::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-61::INFO::2015-03-06 14:51:57,646::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-62::INFO::2015-03-06 14:51:57,647::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-62::INFO::2015-03-06 14:51:57,648::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:51:57,658::mem_free::53::mem_free.MemFree::(action) memFree: 23510
Thread-26::INFO::2015-03-06 14:51:58,102::cpu_load_no_engine::153::cpu_load_no_engine.EngineHealth::(update_stat_file) VM not on this host
Thread-26::INFO::2015-03-06 14:51:58,102::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0039, engine=0.0000, non-engine=0.0039
Thread-24::INFO::2015-03-06 14:52:07,224::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-63::INFO::2015-03-06 14:52:07,691::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-63::INFO::2015-03-06 14:52:07,694::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-64::INFO::2015-03-06 14:52:07,695::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-64::INFO::2015-03-06 14:52:07,696::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:52:07,706::mem_free::53::mem_free.MemFree::(action) memFree: 23507
Thread-65::INFO::2015-03-06 14:52:17,725::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-65::INFO::2015-03-06 14:52:17,727::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-66::INFO::2015-03-06 14:52:17,727::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-66::INFO::2015-03-06 14:52:17,728::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-67::INFO::2015-03-06 14:52:27,760::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-67::INFO::2015-03-06 14:52:27,762::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-68::INFO::2015-03-06 14:52:27,763::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-68::INFO::2015-03-06 14:52:27,764::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:52:27,774::mem_free::53::mem_free.MemFree::(action) memFree: 23508
Thread-22::ERROR::2015-03-06 14:52:29,144::notifications::30::ovirt_hosted_engine_ha.broker.notifications.Notifications::(send_email) [Errno 111] Connection refused
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/notifications.py", line 21, in send_email
    server = smtplib.SMTP(cfg["smtp-server"], port=cfg["smtp-port"])
  File "/usr/lib64/python2.7/smtplib.py", line 250, in __init__
    (code, msg) = self.connect(host, port)
  File "/usr/lib64/python2.7/smtplib.py", line 310, in connect
    self.sock = self._get_socket(host, port, self.timeout)
  File "/usr/lib64/python2.7/smtplib.py", line 285, in _get_socket
    return socket.create_connection((host, port), timeout)
  File "/usr/lib64/python2.7/socket.py", line 571, in create_connection
    raise err
error: [Errno 111] Connection refused
Thread-69::INFO::2015-03-06 14:52:37,801::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-69::INFO::2015-03-06 14:52:37,805::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-70::INFO::2015-03-06 14:52:37,805::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-70::INFO::2015-03-06 14:52:37,807::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:52:37,816::mem_free::53::mem_free.MemFree::(action) memFree: 23504
Thread-22::ERROR::2015-03-06 14:52:39,694::notifications::30::ovirt_hosted_engine_ha.broker.notifications.Notifications::(send_email) [Errno 111] Connection refused
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/notifications.py", line 21, in send_email
    server = smtplib.SMTP(cfg["smtp-server"], port=cfg["smtp-port"])
  File "/usr/lib64/python2.7/smtplib.py", line 250, in __init__
    (code, msg) = self.connect(host, port)
  File "/usr/lib64/python2.7/smtplib.py", line 310, in connect
    self.sock = self._get_socket(host, port, self.timeout)
  File "/usr/lib64/python2.7/smtplib.py", line 285, in _get_socket
    return socket.create_connection((host, port), timeout)
  File "/usr/lib64/python2.7/socket.py", line 571, in create_connection
    raise err
error: [Errno 111] Connection refused
Thread-71::INFO::2015-03-06 14:52:47,845::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-71::INFO::2015-03-06 14:52:47,848::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-72::INFO::2015-03-06 14:52:47,849::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-72::INFO::2015-03-06 14:52:47,850::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-23::INFO::2015-03-06 14:52:48,120::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-27::INFO::2015-03-06 14:52:57,499::engine_health::63::engine_health.CpuLoadNoEngine::(action) VM not on this host
Thread-73::INFO::2015-03-06 14:52:57,886::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-73::INFO::2015-03-06 14:52:57,890::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-74::INFO::2015-03-06 14:52:57,890::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-74::INFO::2015-03-06 14:52:57,892::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-26::INFO::2015-03-06 14:52:58,175::cpu_load_no_engine::153::cpu_load_no_engine.EngineHealth::(update_stat_file) VM not on this host
Thread-26::INFO::2015-03-06 14:52:58,176::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0106, engine=0.0000, non-engine=0.0106
Thread-24::INFO::2015-03-06 14:53:07,672::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-75::INFO::2015-03-06 14:53:07,934::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-75::INFO::2015-03-06 14:53:07,936::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-76::INFO::2015-03-06 14:53:07,937::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-76::INFO::2015-03-06 14:53:07,938::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:53:07,948::mem_free::53::mem_free.MemFree::(action) memFree: 23503
Thread-77::INFO::2015-03-06 14:53:17,976::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-77::INFO::2015-03-06 14:53:17,979::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-78::INFO::2015-03-06 14:53:17,980::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-78::INFO::2015-03-06 14:53:17,981::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:53:17,990::mem_free::53::mem_free.MemFree::(action) memFree: 23502
Thread-79::INFO::2015-03-06 14:53:27,350::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-79::INFO::2015-03-06 14:53:27,353::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-80::INFO::2015-03-06 14:53:27,354::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-80::INFO::2015-03-06 14:53:27,355::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-81::INFO::2015-03-06 14:53:27,356::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-81::INFO::2015-03-06 14:53:27,357::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-82::INFO::2015-03-06 14:53:28,024::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-82::INFO::2015-03-06 14:53:28,027::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-83::INFO::2015-03-06 14:53:28,028::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-83::INFO::2015-03-06 14:53:28,029::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:53:28,039::mem_free::53::mem_free.MemFree::(action) memFree: 23501
Thread-22::ERROR::2015-03-06 14:53:32,164::notifications::30::ovirt_hosted_engine_ha.broker.notifications.Notifications::(send_email) [Errno 111] Connection refused
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/notifications.py", line 21, in send_email
    server = smtplib.SMTP(cfg["smtp-server"], port=cfg["smtp-port"])
  File "/usr/lib64/python2.7/smtplib.py", line 250, in __init__
    (code, msg) = self.connect(host, port)
  File "/usr/lib64/python2.7/smtplib.py", line 310, in connect
    self.sock = self._get_socket(host, port, self.timeout)
  File "/usr/lib64/python2.7/smtplib.py", line 285, in _get_socket
    return socket.create_connection((host, port), timeout)
  File "/usr/lib64/python2.7/socket.py", line 571, in create_connection
    raise err
error: [Errno 111] Connection refused
Thread-22::ERROR::2015-03-06 14:53:32,865::notifications::30::ovirt_hosted_engine_ha.broker.notifications.Notifications::(send_email) [Errno 111] Connection refused
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/notifications.py", line 21, in send_email
    server = smtplib.SMTP(cfg["smtp-server"], port=cfg["smtp-port"])
  File "/usr/lib64/python2.7/smtplib.py", line 250, in __init__
    (code, msg) = self.connect(host, port)
  File "/usr/lib64/python2.7/smtplib.py", line 310, in connect
    self.sock = self._get_socket(host, port, self.timeout)
  File "/usr/lib64/python2.7/smtplib.py", line 285, in _get_socket
    return socket.create_connection((host, port), timeout)
  File "/usr/lib64/python2.7/socket.py", line 571, in create_connection
    raise err
error: [Errno 111] Connection refused
Thread-84::INFO::2015-03-06 14:53:37,064::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-84::INFO::2015-03-06 14:53:37,066::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-85::INFO::2015-03-06 14:53:37,066::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-85::INFO::2015-03-06 14:53:37,067::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:53:37,072::mem_free::53::mem_free.MemFree::(action) memFree: 23472
Thread-27::INFO::2015-03-06 14:53:37,607::engine_health::83::engine_health.CpuLoadNoEngine::(action) VM status: powering up
Thread-86::INFO::2015-03-06 14:53:44,945::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-86::INFO::2015-03-06 14:53:45,048::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-87::INFO::2015-03-06 14:53:45,048::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-87::INFO::2015-03-06 14:53:45,049::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-88::INFO::2015-03-06 14:53:45,050::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-88::INFO::2015-03-06 14:53:45,051::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-89::INFO::2015-03-06 14:53:47,101::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-89::INFO::2015-03-06 14:53:47,102::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-90::INFO::2015-03-06 14:53:47,103::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-90::INFO::2015-03-06 14:53:47,104::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:53:47,109::mem_free::53::mem_free.MemFree::(action) memFree: 22955
Thread-23::INFO::2015-03-06 14:53:48,202::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-91::INFO::2015-03-06 14:53:49,855::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-91::INFO::2015-03-06 14:53:49,857::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-92::INFO::2015-03-06 14:53:49,857::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-92::INFO::2015-03-06 14:53:49,858::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-93::INFO::2015-03-06 14:53:49,858::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-93::INFO::2015-03-06 14:53:49,859::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-94::INFO::2015-03-06 14:53:52,839::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-94::INFO::2015-03-06 14:53:52,841::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-95::INFO::2015-03-06 14:53:52,841::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-95::INFO::2015-03-06 14:53:52,842::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-96::INFO::2015-03-06 14:53:52,842::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-96::INFO::2015-03-06 14:53:52,843::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-97::INFO::2015-03-06 14:53:55,420::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-97::INFO::2015-03-06 14:53:55,422::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-98::INFO::2015-03-06 14:53:55,422::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-98::INFO::2015-03-06 14:53:55,423::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-99::INFO::2015-03-06 14:53:55,423::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-99::INFO::2015-03-06 14:53:55,425::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-100::INFO::2015-03-06 14:53:57,134::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-100::INFO::2015-03-06 14:53:57,136::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-101::INFO::2015-03-06 14:53:57,136::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-101::INFO::2015-03-06 14:53:57,137::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:53:57,142::mem_free::53::mem_free.MemFree::(action) memFree: 22018
Thread-102::INFO::2015-03-06 14:53:58,193::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-102::INFO::2015-03-06 14:53:58,196::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-103::INFO::2015-03-06 14:53:58,196::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-103::INFO::2015-03-06 14:53:58,197::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-104::INFO::2015-03-06 14:53:58,198::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-104::INFO::2015-03-06 14:53:58,199::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-26::INFO::2015-03-06 14:53:58,264::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0863, engine=0.0000, non-engine=0.0863
Thread-105::INFO::2015-03-06 14:54:00,735::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-105::INFO::2015-03-06 14:54:00,737::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-106::INFO::2015-03-06 14:54:00,738::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-106::INFO::2015-03-06 14:54:00,739::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-107::INFO::2015-03-06 14:54:00,739::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-107::INFO::2015-03-06 14:54:00,741::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-108::INFO::2015-03-06 14:54:03,183::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-108::INFO::2015-03-06 14:54:03,186::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-109::INFO::2015-03-06 14:54:03,186::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-109::INFO::2015-03-06 14:54:03,187::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-110::INFO::2015-03-06 14:54:03,188::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-110::INFO::2015-03-06 14:54:03,190::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-111::INFO::2015-03-06 14:54:05,795::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-111::INFO::2015-03-06 14:54:05,798::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-112::INFO::2015-03-06 14:54:05,799::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-112::INFO::2015-03-06 14:54:05,800::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-113::INFO::2015-03-06 14:54:05,800::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-113::INFO::2015-03-06 14:54:05,802::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-114::INFO::2015-03-06 14:54:07,178::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-114::INFO::2015-03-06 14:54:07,181::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-115::INFO::2015-03-06 14:54:07,182::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-115::INFO::2015-03-06 14:54:07,183::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:54:07,193::mem_free::53::mem_free.MemFree::(action) memFree: 22006
Thread-24::INFO::2015-03-06 14:54:08,068::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-116::INFO::2015-03-06 14:54:08,330::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-116::INFO::2015-03-06 14:54:08,333::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-117::INFO::2015-03-06 14:54:08,334::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-117::INFO::2015-03-06 14:54:08,335::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-118::INFO::2015-03-06 14:54:08,335::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-118::INFO::2015-03-06 14:54:08,337::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-119::INFO::2015-03-06 14:54:10,882::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-119::INFO::2015-03-06 14:54:10,884::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-120::INFO::2015-03-06 14:54:10,885::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-120::INFO::2015-03-06 14:54:10,886::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-121::INFO::2015-03-06 14:54:10,887::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-121::INFO::2015-03-06 14:54:10,889::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-122::INFO::2015-03-06 14:54:13,560::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-122::INFO::2015-03-06 14:54:13,561::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-123::INFO::2015-03-06 14:54:13,562::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-123::INFO::2015-03-06 14:54:13,562::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-124::INFO::2015-03-06 14:54:13,563::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-124::INFO::2015-03-06 14:54:13,564::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-125::INFO::2015-03-06 14:54:16,636::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-125::INFO::2015-03-06 14:54:16,638::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-126::INFO::2015-03-06 14:54:16,638::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-126::INFO::2015-03-06 14:54:16,639::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-127::INFO::2015-03-06 14:54:16,639::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-127::INFO::2015-03-06 14:54:16,640::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-128::INFO::2015-03-06 14:54:17,219::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-128::INFO::2015-03-06 14:54:17,220::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-129::INFO::2015-03-06 14:54:17,220::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-129::INFO::2015-03-06 14:54:17,221::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:54:17,226::mem_free::53::mem_free.MemFree::(action) memFree: 21944
Thread-130::INFO::2015-03-06 14:54:27,262::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-130::INFO::2015-03-06 14:54:27,265::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-131::INFO::2015-03-06 14:54:27,266::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-131::INFO::2015-03-06 14:54:27,267::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:54:27,277::mem_free::53::mem_free.MemFree::(action) memFree: 21925
Thread-132::INFO::2015-03-06 14:54:37,306::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-132::INFO::2015-03-06 14:54:37,308::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-133::INFO::2015-03-06 14:54:37,309::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-133::INFO::2015-03-06 14:54:37,309::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:54:37,316::mem_free::53::mem_free.MemFree::(action) memFree: 21887
Thread-27::INFO::2015-03-06 14:54:37,868::engine_health::110::engine_health.CpuLoadNoEngine::(action) VM is up on this host with healthy engine
Thread-22::ERROR::2015-03-06 14:54:46,064::notifications::30::ovirt_hosted_engine_ha.broker.notifications.Notifications::(send_email) [Errno 111] Connection refused
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ovirt_hosted_engine_ha/broker/notifications.py", line 21, in send_email
    server = smtplib.SMTP(cfg["smtp-server"], port=cfg["smtp-port"])
  File "/usr/lib64/python2.7/smtplib.py", line 250, in __init__
    (code, msg) = self.connect(host, port)
  File "/usr/lib64/python2.7/smtplib.py", line 310, in connect
    self.sock = self._get_socket(host, port, self.timeout)
  File "/usr/lib64/python2.7/smtplib.py", line 285, in _get_socket
    return socket.create_connection((host, port), timeout)
  File "/usr/lib64/python2.7/socket.py", line 571, in create_connection
    raise err
error: [Errno 111] Connection refused
Thread-134::INFO::2015-03-06 14:54:47,349::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-134::INFO::2015-03-06 14:54:47,352::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-135::INFO::2015-03-06 14:54:47,353::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-135::INFO::2015-03-06 14:54:47,354::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:54:47,363::mem_free::53::mem_free.MemFree::(action) memFree: 21881
Thread-23::INFO::2015-03-06 14:54:48,283::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-136::INFO::2015-03-06 14:54:57,398::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-136::INFO::2015-03-06 14:54:57,401::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-137::INFO::2015-03-06 14:54:57,402::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-137::INFO::2015-03-06 14:54:57,403::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:54:57,411::mem_free::53::mem_free.MemFree::(action) memFree: 21879
Thread-26::INFO::2015-03-06 14:54:58,326::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0341, engine=0.0196, non-engine=0.0145
Thread-138::INFO::2015-03-06 14:55:07,461::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-138::INFO::2015-03-06 14:55:07,467::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-139::INFO::2015-03-06 14:55:07,467::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-139::INFO::2015-03-06 14:55:07,467::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:55:07,472::mem_free::53::mem_free.MemFree::(action) memFree: 21827
Thread-140::INFO::2015-03-06 14:55:17,532::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-140::INFO::2015-03-06 14:55:17,535::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-141::INFO::2015-03-06 14:55:17,536::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-141::INFO::2015-03-06 14:55:17,538::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:55:17,557::mem_free::53::mem_free.MemFree::(action) memFree: 21819
Thread-24::INFO::2015-03-06 14:55:17,576::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-142::INFO::2015-03-06 14:55:27,588::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-142::INFO::2015-03-06 14:55:27,592::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-143::INFO::2015-03-06 14:55:27,593::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-143::INFO::2015-03-06 14:55:27,595::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:55:27,607::mem_free::53::mem_free.MemFree::(action) memFree: 21826
Thread-144::INFO::2015-03-06 14:55:37,635::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-144::INFO::2015-03-06 14:55:37,637::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-145::INFO::2015-03-06 14:55:37,637::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-145::INFO::2015-03-06 14:55:37,638::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:55:37,650::mem_free::53::mem_free.MemFree::(action) memFree: 21828
Thread-27::INFO::2015-03-06 14:55:47,597::engine_health::110::engine_health.CpuLoadNoEngine::(action) VM is up on this host with healthy engine
Thread-146::INFO::2015-03-06 14:55:47,676::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-146::INFO::2015-03-06 14:55:47,679::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-147::INFO::2015-03-06 14:55:47,680::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-147::INFO::2015-03-06 14:55:47,681::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:55:47,691::mem_free::53::mem_free.MemFree::(action) memFree: 21825
Thread-23::INFO::2015-03-06 14:55:48,358::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-148::INFO::2015-03-06 14:55:57,721::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-148::INFO::2015-03-06 14:55:57,723::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-149::INFO::2015-03-06 14:55:57,723::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-149::INFO::2015-03-06 14:55:57,724::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:55:57,732::mem_free::53::mem_free.MemFree::(action) memFree: 21826
Thread-26::INFO::2015-03-06 14:55:58,383::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0167, engine=0.0062, non-engine=0.0105
Thread-150::INFO::2015-03-06 14:56:07,770::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-150::INFO::2015-03-06 14:56:07,773::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-151::INFO::2015-03-06 14:56:07,773::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-151::INFO::2015-03-06 14:56:07,775::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:56:07,783::mem_free::53::mem_free.MemFree::(action) memFree: 21822
Thread-152::INFO::2015-03-06 14:56:17,818::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-152::INFO::2015-03-06 14:56:17,822::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-153::INFO::2015-03-06 14:56:17,822::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-153::INFO::2015-03-06 14:56:17,824::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:56:17,836::mem_free::53::mem_free.MemFree::(action) memFree: 21824
Thread-24::INFO::2015-03-06 14:56:17,936::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-154::INFO::2015-03-06 14:56:27,872::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-154::INFO::2015-03-06 14:56:27,876::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-155::INFO::2015-03-06 14:56:27,876::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-155::INFO::2015-03-06 14:56:27,878::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:56:27,888::mem_free::53::mem_free.MemFree::(action) memFree: 21825
Thread-156::INFO::2015-03-06 14:56:37,924::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-156::INFO::2015-03-06 14:56:37,927::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-157::INFO::2015-03-06 14:56:37,928::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-157::INFO::2015-03-06 14:56:37,929::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:56:37,939::mem_free::53::mem_free.MemFree::(action) memFree: 21824
Thread-158::INFO::2015-03-06 14:56:47,968::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-158::INFO::2015-03-06 14:56:47,971::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-159::INFO::2015-03-06 14:56:47,972::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-159::INFO::2015-03-06 14:56:47,973::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:56:47,984::mem_free::53::mem_free.MemFree::(action) memFree: 21822
Thread-23::INFO::2015-03-06 14:56:48,447::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-27::INFO::2015-03-06 14:56:57,339::engine_health::110::engine_health.CpuLoadNoEngine::(action) VM is up on this host with healthy engine
Thread-160::INFO::2015-03-06 14:56:58,019::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-160::INFO::2015-03-06 14:56:58,023::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-161::INFO::2015-03-06 14:56:58,023::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-161::INFO::2015-03-06 14:56:58,025::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:56:58,035::mem_free::53::mem_free.MemFree::(action) memFree: 21820
Thread-26::INFO::2015-03-06 14:56:58,442::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0175, engine=0.0062, non-engine=0.0113
Thread-162::INFO::2015-03-06 14:57:07,070::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-162::INFO::2015-03-06 14:57:07,073::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-163::INFO::2015-03-06 14:57:07,074::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-163::INFO::2015-03-06 14:57:07,076::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:57:07,086::mem_free::53::mem_free.MemFree::(action) memFree: 21817
Thread-164::INFO::2015-03-06 14:57:11,456::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-164::INFO::2015-03-06 14:57:11,460::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-165::INFO::2015-03-06 14:57:11,460::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-165::INFO::2015-03-06 14:57:11,462::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-166::INFO::2015-03-06 14:57:11,463::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-166::INFO::2015-03-06 14:57:11,465::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-167::INFO::2015-03-06 14:57:17,125::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-167::INFO::2015-03-06 14:57:17,127::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-168::INFO::2015-03-06 14:57:17,128::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-168::INFO::2015-03-06 14:57:17,129::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:57:17,139::mem_free::53::mem_free.MemFree::(action) memFree: 21816
Thread-169::INFO::2015-03-06 14:57:27,179::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-169::INFO::2015-03-06 14:57:27,182::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-170::INFO::2015-03-06 14:57:27,183::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-170::INFO::2015-03-06 14:57:27,184::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:57:27,194::mem_free::53::mem_free.MemFree::(action) memFree: 21814
Thread-24::INFO::2015-03-06 14:57:27,484::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-171::INFO::2015-03-06 14:57:37,218::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-171::INFO::2015-03-06 14:57:37,221::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-172::INFO::2015-03-06 14:57:37,221::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-172::INFO::2015-03-06 14:57:37,222::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:57:37,227::mem_free::53::mem_free.MemFree::(action) memFree: 21771
Thread-173::INFO::2015-03-06 14:57:47,251::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-173::INFO::2015-03-06 14:57:47,254::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-174::INFO::2015-03-06 14:57:47,254::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-174::INFO::2015-03-06 14:57:47,255::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:57:47,265::mem_free::53::mem_free.MemFree::(action) memFree: 21766
Thread-23::INFO::2015-03-06 14:57:48,531::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-175::INFO::2015-03-06 14:57:57,300::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-175::INFO::2015-03-06 14:57:57,303::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-176::INFO::2015-03-06 14:57:57,304::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-176::INFO::2015-03-06 14:57:57,305::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-27::INFO::2015-03-06 14:57:57,960::engine_health::110::engine_health.CpuLoadNoEngine::(action) VM is up on this host with healthy engine
Thread-26::INFO::2015-03-06 14:57:58,497::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0291, engine=0.0178, non-engine=0.0113
Thread-177::INFO::2015-03-06 14:58:07,350::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-177::INFO::2015-03-06 14:58:07,353::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-178::INFO::2015-03-06 14:58:07,354::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-178::INFO::2015-03-06 14:58:07,355::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-179::INFO::2015-03-06 14:58:17,394::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-179::INFO::2015-03-06 14:58:17,396::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-180::INFO::2015-03-06 14:58:17,397::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-180::INFO::2015-03-06 14:58:17,399::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:58:17,409::mem_free::53::mem_free.MemFree::(action) memFree: 21767
Thread-181::INFO::2015-03-06 14:58:27,443::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-181::INFO::2015-03-06 14:58:27,445::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-182::INFO::2015-03-06 14:58:27,446::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-182::INFO::2015-03-06 14:58:27,447::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-24::INFO::2015-03-06 14:58:27,917::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-183::INFO::2015-03-06 14:58:37,488::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-183::INFO::2015-03-06 14:58:37,491::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-184::INFO::2015-03-06 14:58:37,491::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-184::INFO::2015-03-06 14:58:37,492::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:58:37,501::mem_free::53::mem_free.MemFree::(action) memFree: 21766
Thread-185::INFO::2015-03-06 14:58:47,523::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-185::INFO::2015-03-06 14:58:47,526::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-186::INFO::2015-03-06 14:58:47,526::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-186::INFO::2015-03-06 14:58:47,527::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-23::INFO::2015-03-06 14:58:48,618::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-187::INFO::2015-03-06 14:58:57,554::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-187::INFO::2015-03-06 14:58:57,556::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-188::INFO::2015-03-06 14:58:57,556::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-188::INFO::2015-03-06 14:58:57,557::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-26::INFO::2015-03-06 14:58:58,546::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0343, engine=0.0234, non-engine=0.0109
Thread-189::INFO::2015-03-06 14:59:07,585::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-189::INFO::2015-03-06 14:59:07,588::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-190::INFO::2015-03-06 14:59:07,588::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-190::INFO::2015-03-06 14:59:07,589::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:59:07,595::mem_free::53::mem_free.MemFree::(action) memFree: 21759
Thread-27::INFO::2015-03-06 14:59:07,628::engine_health::110::engine_health.CpuLoadNoEngine::(action) VM is up on this host with healthy engine
Thread-191::INFO::2015-03-06 14:59:17,629::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-191::INFO::2015-03-06 14:59:17,632::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-192::INFO::2015-03-06 14:59:17,632::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-192::INFO::2015-03-06 14:59:17,634::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:59:17,645::mem_free::53::mem_free.MemFree::(action) memFree: 21768
Thread-193::INFO::2015-03-06 14:59:27,679::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-193::INFO::2015-03-06 14:59:27,681::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-194::INFO::2015-03-06 14:59:27,682::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-194::INFO::2015-03-06 14:59:27,683::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:59:27,692::mem_free::53::mem_free.MemFree::(action) memFree: 21767
Thread-24::INFO::2015-03-06 14:59:37,392::mgmt_bridge::59::mgmt_bridge.MgmtBridge::(action) Found bridge ovirtmgmt with ports
Thread-195::INFO::2015-03-06 14:59:37,725::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-195::INFO::2015-03-06 14:59:37,728::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-196::INFO::2015-03-06 14:59:37,728::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-196::INFO::2015-03-06 14:59:37,730::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:59:37,740::mem_free::53::mem_free.MemFree::(action) memFree: 21768
Thread-197::INFO::2015-03-06 14:59:47,765::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-197::INFO::2015-03-06 14:59:47,769::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-198::INFO::2015-03-06 14:59:47,770::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-198::INFO::2015-03-06 14:59:47,771::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:59:47,781::mem_free::53::mem_free.MemFree::(action) memFree: 21766
Thread-23::INFO::2015-03-06 14:59:48,707::ping::52::ping.Ping::(action) Successfully pinged 172.16.0.1
Thread-199::INFO::2015-03-06 14:59:57,815::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-199::INFO::2015-03-06 14:59:57,818::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-200::INFO::2015-03-06 14:59:57,819::listener::134::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(setup) Connection established
Thread-200::INFO::2015-03-06 14:59:57,820::listener::186::ovirt_hosted_engine_ha.broker.listener.ConnectionHandler::(handle) Connection closed
Thread-25::INFO::2015-03-06 14:59:57,830::mem_free::53::mem_free.MemFree::(action) memFree: 21756
Thread-26::INFO::2015-03-06 14:59:58,598::cpu_load_no_engine::121::cpu_load_no_engine.EngineHealth::(calculate_load) System load total=0.0166, engine=0.0066, non-engine=0.0101
-------------- next part --------------
2015-03-06 13:55:57,124 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/usr/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf".
2015-03-06 13:55:57,124 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) The file "/etc/ovirt-engine/engine.conf" doesn't exist or isn't readable. Will return an empty set of properties.
2015-03-06 13:55:57,125 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-database.conf".
2015-03-06 13:55:57,125 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-jboss.conf".
2015-03-06 13:55:57,125 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-pki.conf".
2015-03-06 13:55:57,132 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-protocols.conf".
2015-03-06 13:55:57,132 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_AJP_ENABLED" is "true".
2015-03-06 13:55:57,132 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_AJP_PORT" is "8702".
2015-03-06 13:55:57,133 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_APPS" is "engine.ear restapi.war legacy_restapi.war".
2015-03-06 13:55:57,133 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_CACHE" is "/var/cache/ovirt-engine".
2015-03-06 13:55:57,133 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_CHECK_INTERVAL" is "1000".
2015-03-06 13:55:57,133 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_CONNECTION_TIMEOUT" is "300000".
2015-03-06 13:55:57,133 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_DATABASE" is "engine".
2015-03-06 13:55:57,134 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver".
2015-03-06 13:55:57,134 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_HOST" is "localhost".
2015-03-06 13:55:57,134 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100".
2015-03-06 13:55:57,134 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1".
2015-03-06 13:55:57,138 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_PASSWORD" is "***".
2015-03-06 13:55:57,138 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_PORT" is "5432".
2015-03-06 13:55:57,138 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_SECURED" is "False".
2015-03-06 13:55:57,138 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_SECURED_VALIDATION" is "False".
2015-03-06 13:55:57,138 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine?sslfactory=org.postgresql.ssl.NonValidatingFactory".
2015-03-06 13:55:57,139 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DB_USER" is "engine".
2015-03-06 13:55:57,139 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DEBUG_ADDRESS" is "".
2015-03-06 13:55:57,139 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_DOC" is "/usr/share/doc/ovirt-engine".
2015-03-06 13:55:57,139 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_ETC" is "/etc/ovirt-engine".
2015-03-06 13:55:57,140 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_EXTENSION_PATH" is "/usr/share/ovirt-engine/extensions.d:/etc/ovirt-engine/extensions.d".
2015-03-06 13:55:57,140 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_EXTERNAL_PROVIDERS_TRUST_STORE" is "/var/lib/ovirt-engine/external_truststore".
2015-03-06 13:55:57,140 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_EXTERNAL_PROVIDERS_TRUST_STORE_PASSWORD" is "***".
2015-03-06 13:55:57,140 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_EXTERNAL_PROVIDERS_TRUST_STORE_TYPE" is "JKS".
2015-03-06 13:55:57,151 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_FQDN" is "ovirt-vm.smartcity.net".
2015-03-06 13:55:57,151 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_GROUP" is "ovirt".
2015-03-06 13:55:57,151 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HEAP_MAX" is "1g".
2015-03-06 13:55:57,151 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HEAP_MIN" is "1g".
2015-03-06 13:55:57,152 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTPS_ENABLED" is "false".
2015-03-06 13:55:57,152 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTPS_PORT" is "None".
2015-03-06 13:55:57,152 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTPS_PROTOCOLS" is "SSLv3,TLSv1,TLSv1.1,TLSv1.2".
2015-03-06 13:55:57,152 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTP_ENABLED" is "false".
2015-03-06 13:55:57,153 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_HTTP_PORT" is "None".
2015-03-06 13:55:57,153 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_JAVA_MODULEPATH" is "/usr/share/ovirt-engine/modules".
2015-03-06 13:55:57,153 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_JVM_ARGS" is " -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath="/var/log/ovirt-engine/dump"".
2015-03-06 13:55:57,153 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_LOG" is "/var/log/ovirt-engine".
2015-03-06 13:55:57,153 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_LOG_TO_CONSOLE" is "false".
2015-03-06 13:55:57,154 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_MANUAL" is "/usr/share/ovirt-engine/manual".
2015-03-06 13:55:57,154 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PERM_MAX" is "256m".
2015-03-06 13:55:57,154 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PERM_MIN" is "256m".
2015-03-06 13:55:57,154 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI" is "/etc/pki/ovirt-engine".
2015-03-06 13:55:57,155 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_CA" is "/etc/pki/ovirt-engine/ca.pem".
2015-03-06 13:55:57,155 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_CERT" is "/etc/pki/ovirt-engine/certs/engine.cer".
2015-03-06 13:55:57,155 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_STORE" is "/etc/pki/ovirt-engine/keys/engine.p12".
2015-03-06 13:55:57,155 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_STORE_ALIAS" is "1".
2015-03-06 13:55:57,156 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_ENGINE_STORE_PASSWORD" is "***".
2015-03-06 13:55:57,156 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_TRUST_STORE" is "/etc/pki/ovirt-engine/.truststore".
2015-03-06 13:55:57,156 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PKI_TRUST_STORE_PASSWORD" is "***".
2015-03-06 13:55:57,156 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PROPERTIES" is " jsse.enableSNIExtension=false "java.security.krb5.conf=/etc/ovirt-engine/krb5.conf"".
2015-03-06 13:55:57,157 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PROXY_ENABLED" is "true".
2015-03-06 13:55:57,157 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443".
2015-03-06 13:55:57,157 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_PROXY_HTTP_PORT" is "80".
2015-03-06 13:55:57,157 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_BASE_URL" is "".
2015-03-06 13:55:57,157 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_DASHBOARD_URL" is "/flow.html?viewAsDashboardFrame=true".
2015-03-06 13:55:57,158 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_NOT_INSTALLED_URL" is "/ovirt-engine/ReportsNotInstalled.html".
2015-03-06 13:55:57,158 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_PROXY_URL" is "/ovirt/reports-interface".
2015-03-06 13:55:57,158 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_READ_TIMEOUT" is "".
2015-03-06 13:55:57,158 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_RIGHTCLICK_URL" is "".
2015-03-06 13:55:57,165 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_VERIFY_CHAIN" is "true".
2015-03-06 13:55:57,165 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_REPORTS_VERIFY_HOST" is "true".
2015-03-06 13:55:57,165 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_STOP_INTERVAL" is "1".
2015-03-06 13:55:57,165 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_STOP_TIME" is "10".
2015-03-06 13:55:57,166 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_TMP" is "/var/tmp/ovirt-engine".
2015-03-06 13:55:57,166 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_UP_MARK" is "/var/lib/ovirt-engine/engine.up".
2015-03-06 13:55:57,166 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_URI" is "/ovirt-engine".
2015-03-06 13:55:57,166 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_USER" is "ovirt".
2015-03-06 13:55:57,166 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_USR" is "/usr/share/ovirt-engine".
2015-03-06 13:55:57,167 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_VAR" is "/var/lib/ovirt-engine".
2015-03-06 13:55:57,167 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "ENGINE_VERBOSE_GC" is "false".
2015-03-06 13:55:57,167 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "JBOSS_HOME" is "/usr/share/ovirt-engine-jboss-as".
2015-03-06 13:55:57,167 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "JBOSS_RUNTIME" is "/var/lib/ovirt-engine/jboss_runtime".
2015-03-06 13:55:57,168 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-2) Value of property "SENSITIVE_KEYS" is ",ENGINE_DB_PASSWORD,ENGINE_PKI_TRUST_STORE_PASSWORD,ENGINE_PKI_ENGINE_STORE_PASSWORD,ENGINE_EXTERNAL_PROVIDERS_TRUST_STORE_PASSWORD".
2015-03-06 13:55:57,556 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-3) Start initializing Backend
2015-03-06 13:55:57,807 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-3) Could not parse option AutoRecoveryAllowedTypes value.
2015-03-06 13:55:57,840 INFO  [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-3) Loading file /etc/ovirt-engine/osinfo.conf.d/00-defaults.properties
2015-03-06 13:55:57,960 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-3) Running ovirt-engine 3.5.1.1-1.fc20
2015-03-06 13:55:57,961 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-3) Start initializing dictionaries
2015-03-06 13:55:57,971 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-3) Finished initializing dictionaries
2015-03-06 13:55:57,972 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-3) Start initializing AuditLogCleanupManager
2015-03-06 13:55:57,973 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-3) Setting audit cleanup manager to run at: 35 35 3 * * ?
2015-03-06 13:55:57,988 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-3) Finished initializing AuditLogCleanupManager
2015-03-06 13:55:57,989 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-3) Start initializing CommandEntityCleanupManager
2015-03-06 13:55:57,989 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-3) Setting command entity cleanup manager to run at: 35 35 3 * * ?
2015-03-06 13:55:57,992 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-3) Finished initializing CommandEntityCleanupManager
2015-03-06 13:55:57,993 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-3) Start initializing TagsDirector
2015-03-06 13:55:58,006 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-3) Tag root added to tree
2015-03-06 13:55:58,013 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-3) Finished initializing TagsDirector
2015-03-06 13:55:58,020 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-3) Start initializing IsoDomainListSyncronizer
2015-03-06 13:55:58,025 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-3) Finished initializing IsoDomainListSyncronizer
2015-03-06 13:55:58,094 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-3) Completed initializing handlers
2015-03-06 13:55:58,118 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-3) Start initializing ErrorTranslatorImpl
2015-03-06 13:55:58,130 WARN  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-3) Code MAC_ADDRESS_IS_IN_USE appears more than once in string table.
2015-03-06 13:55:58,131 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-3) Finished initializing ErrorTranslatorImpl
2015-03-06 13:55:58,131 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-3) Start initializing ErrorTranslatorImpl
2015-03-06 13:55:58,131 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-3) Finished initializing ErrorTranslatorImpl
2015-03-06 13:55:58,132 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-3) Mark incomplete jobs as UNKNOWN
2015-03-06 13:55:58,150 INFO  [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-3) Start initializing JobRepositoryCleanupManager
2015-03-06 13:55:58,150 INFO  [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-3) Finished initializing JobRepositoryCleanupManager
2015-03-06 13:55:58,151 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-3) Start initializing AutoRecoveryManager
2015-03-06 13:55:58,152 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-3) Finished initializing AutoRecoveryManager
2015-03-06 13:55:58,153 INFO  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-3) Start initializing ExecutionMessageDirector
2015-03-06 13:55:58,155 INFO  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-3) Finished initializing ExecutionMessageDirector
2015-03-06 13:55:58,192 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Loading extension 'builtin-authn-internal'
2015-03-06 13:55:58,193 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Extension 'builtin-authn-internal' loaded
2015-03-06 13:55:58,196 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Loading extension 'internal'
2015-03-06 13:55:58,200 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Extension 'internal' loaded
2015-03-06 13:55:58,201 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Initializing extension 'builtin-authn-internal'
2015-03-06 13:55:58,202 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Extension 'builtin-authn-internal' initialized
2015-03-06 13:55:58,202 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Initializing extension 'internal'
2015-03-06 13:55:58,202 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Extension 'internal' initialized
2015-03-06 13:55:58,203 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Start of enabled extensions list
2015-03-06 13:55:58,203 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Instance name: 'builtin-authn-internal', Extension name: 'Internal Authn (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0',  File: 'N/A', Initialized: 'true'
2015-03-06 13:55:58,204 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) Instance name: 'internal', Extension name: 'Internal Authz (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0',  File: 'N/A', Initialized: 'true'
2015-03-06 13:55:58,205 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-3) End of enabled extensions list
2015-03-06 13:55:58,269 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (MSC service thread 1-2) Initialization of AsyncTaskManager completed successfully.
2015-03-06 13:55:58,270 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-2) Start initializing ResourceManager
2015-03-06 13:55:58,349 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-2) Finished initializing ResourceManager
2015-03-06 13:55:58,350 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (MSC service thread 1-2) Initialization of OvfDataUpdater completed successfully.
2015-03-06 13:55:58,352 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-2) Start scheduling to enable vds load balancer
2015-03-06 13:55:58,353 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-2) Finished scheduling to enable vds load balancer
2015-03-06 13:55:58,353 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-2) Start HA Reservation check
2015-03-06 13:55:58,354 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-2) Finished HA Reservation check
2015-03-06 13:55:58,363 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) Init VM custom properties utilities
2015-03-06 13:55:58,365 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) Init device custom properties utilities
2015-03-06 13:55:58,371 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-2) Initializing Scheduling manager
2015-03-06 13:55:58,373 INFO  [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Start initializing MacPoolManagerRanges
2015-03-06 13:55:58,405 INFO  [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Finished initializing. Available MACs in pool: 256
2015-03-06 13:55:58,421 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-2) External scheduler disabled, discovery skipped
2015-03-06 13:55:58,421 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-2) Initialized Scheduling manager
2015-03-06 13:55:58,422 INFO  [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-2) Initializing DWH Heart Beat
2015-03-06 13:55:58,438 INFO  [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-2) DWH Heart Beat initialized
2015-03-06 13:56:18,004 INFO  [org.ovirt.engine.core.bll.aaa.LoginUserCommand] (ajp--127.0.0.1-8702-4) Running command: LoginUserCommand internal: false.
2015-03-06 13:56:18,011 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: UNASSIGNED not exist in string table
2015-03-06 13:56:18,011 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: VDS_HIGH_NETWORK_USE not exist in string table
2015-03-06 13:56:18,012 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_FAILED_REMOVE_VM not exist in string table
2015-03-06 13:56:18,012 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table
2015-03-06 13:56:18,013 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table
2015-03-06 13:56:18,014 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table
2015-03-06 13:56:18,014 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_ADDED_DISK_PROFILE not exist in string table
2015-03-06 13:56:18,015 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_FAILED_TO_ADD_DISK_PROFILE not exist in string table
2015-03-06 13:56:18,015 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_REMOVED_DISK_PROFILE not exist in string table
2015-03-06 13:56:18,016 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_FAILED_TO_REMOVE_DISK_PROFILE not exist in string table
2015-03-06 13:56:18,016 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_UPDATED_DISK_PROFILE not exist in string table
2015-03-06 13:56:18,016 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_FAILED_TO_UPDATE_DISK_PROFILE not exist in string table
2015-03-06 13:56:18,017 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_ADDED_CPU_PROFILE not exist in string table
2015-03-06 13:56:18,017 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_FAILED_TO_ADD_CPU_PROFILE not exist in string table
2015-03-06 13:56:18,018 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_REMOVED_CPU_PROFILE not exist in string table
2015-03-06 13:56:18,018 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_FAILED_TO_REMOVE_CPU_PROFILE not exist in string table
2015-03-06 13:56:18,018 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_UPDATED_CPU_PROFILE not exist in string table
2015-03-06 13:56:18,019 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) AuditLogType: USER_FAILED_TO_UPDATE_CPU_PROFILE not exist in string table
2015-03-06 13:56:18,148 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin at internal logged in.
2015-03-06 13:56:24,318 ERROR [org.ovirt.engine.core.bll.AddVdsCommand] (ajp--127.0.0.1-8702-6) [26fe815b] Failed to establish session with host ovirt-vm: java.nio.channels.UnresolvedAddressException
	at sun.nio.ch.Net.checkAddress(Net.java:127) [rt.jar:1.7.0_75]
	at sun.nio.ch.UnixAsynchronousSocketChannelImpl.implConnect(UnixAsynchronousSocketChannelImpl.java:301) [rt.jar:1.7.0_75]
	at sun.nio.ch.AsynchronousSocketChannelImpl.connect(AsynchronousSocketChannelImpl.java:208) [rt.jar:1.7.0_75]
	at org.apache.sshd.common.io.nio2.Nio2Connector.connect(Nio2Connector.java:47)
	at org.apache.sshd.SshClient.connect(SshClient.java:334)
	at org.apache.sshd.SshClient.connect(SshClient.java:320)
	at org.apache.sshd.SshClient.connect(SshClient.java:310)
	at org.ovirt.engine.core.uutils.ssh.SSHClient.connect(SSHClient.java:284) [uutils.jar:]
	at org.ovirt.engine.core.bll.utils.EngineSSHClient.connect(EngineSSHClient.java:59) [bll.jar:]
	at org.ovirt.engine.core.bll.AddVdsCommand.canConnect(AddVdsCommand.java:452) [bll.jar:]
	at org.ovirt.engine.core.bll.AddVdsCommand.canDoAction(AddVdsCommand.java:365) [bll.jar:]
	at org.ovirt.engine.core.bll.CommandBase.internalCanDoAction(CommandBase.java:768) [bll.jar:]
	at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:347) [bll.jar:]
	at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:430) [bll.jar:]
	at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:411) [bll.jar:]
	at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:369) [bll.jar:]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_75]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_75]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_75]
	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_75]
	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:114) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:125) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:135) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.ovirt.engine.core.bll.interceptors.ThreadLocalSessionCleanerInterceptor.injectWebContextToThreadLocal(ThreadLocalSessionCleanerInterceptor.java:13) [bll.jar:]
	at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source) [:1.7.0_75]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_75]
	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_75]
	at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:123) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:82) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
	at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
	at org.ovirt.engine.core.common.interfaces.BackendLocal$$$view7.runAction(Unknown Source) [common.jar:]
	at org.ovirt.engine.api.restapi.resource.BackendResource.doAction(BackendResource.java:264)
	at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.doCreateEntity(AbstractBackendCollectionResource.java:243)
	at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.performCreate(AbstractBackendCollectionResource.java:120)
	at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.performCreate(AbstractBackendCollectionResource.java:110)
	at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.performCreate(AbstractBackendCollectionResource.java:129)
	at org.ovirt.engine.api.restapi.resource.BackendHostsResource.add(BackendHostsResource.java:100)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_75]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_75]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_75]
	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_75]
	at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl.java:155) [resteasy-jaxrs-2.3.2.Final.jar:]
	at org.jboss.resteasy.core.ResourceMethod.invokeOnTarget(ResourceMethod.java:257) [resteasy-jaxrs-2.3.2.Final.jar:]
	at org.jboss.resteasy.core.ResourceMethod.invoke(ResourceMethod.java:222) [resteasy-jaxrs-2.3.2.Final.jar:]
	at org.jboss.resteasy.core.ResourceMethod.invoke(ResourceMethod.java:211) [resteasy-jaxrs-2.3.2.Final.jar:]
	at org.jboss.resteasy.core.SynchronousDispatcher.getResponse(SynchronousDispatcher.java:525) [resteasy-jaxrs-2.3.2.Final.jar:]
	at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:502) [resteasy-jaxrs-2.3.2.Final.jar:]
	at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:119) [resteasy-jaxrs-2.3.2.Final.jar:]
	at org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher.service(ServletContainerDispatcher.java:208) [resteasy-jaxrs-2.3.2.Final.jar:]
	at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:55) [resteasy-jaxrs-2.3.2.Final.jar:]
	at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:50) [resteasy-jaxrs-2.3.2.Final.jar:]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.0.Final.jar:1.0.0.Final]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.RestApiSessionMgmtFilter.doFilter(RestApiSessionMgmtFilter.java:69) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.EnforceAuthFilter.doFilter(EnforceAuthFilter.java:39) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.LoginFilter.doFilter(LoginFilter.java:74) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.NegotiationFilter.doFilter(NegotiationFilter.java:113) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:90) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.SessionValidationFilter.doFilter(SessionValidationFilter.java:73) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.EngineSessionTokenAuthenticationFilter.doFilter(EngineSessionTokenAuthenticationFilter.java:31) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.core.aaa.filters.RestApiSessionValidationFilter.doFilter(RestApiSessionValidationFilter.java:32) [aaa.jar:]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.ovirt.engine.api.common.security.CSRFProtectionFilter.doFilter(CSRFProtectionFilter.java:110)
	at org.ovirt.engine.api.common.security.CSRFProtectionFilter.doFilter(CSRFProtectionFilter.java:101)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:489)
	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:153)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.jboss.web.rewrite.RewriteValve.invoke(RewriteValve.java:466)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:368)
	at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:505)
	at org.apache.coyote.ajp.AjpProtocol$AjpConnectionHandler.process(AjpProtocol.java:445)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:930)
	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.7.0_75]

2015-03-06 13:56:24,343 WARN  [org.ovirt.engine.core.bll.AddVdsCommand] (ajp--127.0.0.1-8702-6) [26fe815b] CanDoAction of action AddVds failed for user admin at internal. Reasons: VAR__ACTION__ADD,VAR__TYPE__HOST,$server xion2.smartcity.net,VDS_CANNOT_CONNECT_TO_SERVER
2015-03-06 13:56:24,375 ERROR [org.ovirt.engine.api.restapi.resource.AbstractBackendResource] (ajp--127.0.0.1-8702-6) Operation Failed: [Cannot add Host. Connecting to host via SSH has failed, verify that the host is reachable (IP address, routable address etc.) You may refer to the engine.log file for further details.]
2015-03-06 14:05:24,672 INFO  [org.ovirt.engine.core.bll.aaa.LoginAdminUserCommand] (ajp--127.0.0.1-8702-11) Running command: LoginAdminUserCommand internal: false.
2015-03-06 14:05:24,688 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-11) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin at internal logged in.
2015-03-06 14:53:55,613 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/usr/share/ovirt-engine/services/ovirt-engine/ovirt-engine.conf".
2015-03-06 14:53:55,616 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) The file "/etc/ovirt-engine/engine.conf" doesn't exist or isn't readable. Will return an empty set of properties.
2015-03-06 14:53:55,619 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-database.conf".
2015-03-06 14:53:55,619 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-jboss.conf".
2015-03-06 14:53:55,620 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-pki.conf".
2015-03-06 14:53:55,620 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Loaded file "/etc/ovirt-engine/engine.conf.d/10-setup-protocols.conf".
2015-03-06 14:53:55,621 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_AJP_ENABLED" is "true".
2015-03-06 14:53:55,621 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_AJP_PORT" is "8702".
2015-03-06 14:53:55,621 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_APPS" is "engine.ear restapi.war legacy_restapi.war".
2015-03-06 14:53:55,621 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_CACHE" is "/var/cache/ovirt-engine".
2015-03-06 14:53:55,622 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_CHECK_INTERVAL" is "1000".
2015-03-06 14:53:55,622 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_CONNECTION_TIMEOUT" is "300000".
2015-03-06 14:53:55,622 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_DATABASE" is "engine".
2015-03-06 14:53:55,623 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_DRIVER" is "org.postgresql.Driver".
2015-03-06 14:53:55,623 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_HOST" is "localhost".
2015-03-06 14:53:55,623 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_MAX_CONNECTIONS" is "100".
2015-03-06 14:53:55,623 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_MIN_CONNECTIONS" is "1".
2015-03-06 14:53:55,624 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_PASSWORD" is "***".
2015-03-06 14:53:55,624 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_PORT" is "5432".
2015-03-06 14:53:55,624 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_SECURED" is "False".
2015-03-06 14:53:55,624 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_SECURED_VALIDATION" is "False".
2015-03-06 14:53:55,625 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_URL" is "jdbc:postgresql://localhost:5432/engine?sslfactory=org.postgresql.ssl.NonValidatingFactory".
2015-03-06 14:53:55,632 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DB_USER" is "engine".
2015-03-06 14:53:55,632 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DEBUG_ADDRESS" is "".
2015-03-06 14:53:55,632 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_DOC" is "/usr/share/doc/ovirt-engine".
2015-03-06 14:53:55,633 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_ETC" is "/etc/ovirt-engine".
2015-03-06 14:53:55,633 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_EXTENSION_PATH" is "/usr/share/ovirt-engine/extensions.d:/etc/ovirt-engine/extensions.d".
2015-03-06 14:53:55,633 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_EXTERNAL_PROVIDERS_TRUST_STORE" is "/var/lib/ovirt-engine/external_truststore".
2015-03-06 14:53:55,633 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_EXTERNAL_PROVIDERS_TRUST_STORE_PASSWORD" is "***".
2015-03-06 14:53:55,634 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_EXTERNAL_PROVIDERS_TRUST_STORE_TYPE" is "JKS".
2015-03-06 14:53:55,634 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_FQDN" is "ovirt-vm.smartcity.net".
2015-03-06 14:53:55,634 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_GROUP" is "ovirt".
2015-03-06 14:53:55,635 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HEAP_MAX" is "1g".
2015-03-06 14:53:55,635 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HEAP_MIN" is "1g".
2015-03-06 14:53:55,635 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTPS_ENABLED" is "false".
2015-03-06 14:53:55,635 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTPS_PORT" is "None".
2015-03-06 14:53:55,635 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTPS_PROTOCOLS" is "SSLv3,TLSv1,TLSv1.1,TLSv1.2".
2015-03-06 14:53:55,636 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTP_ENABLED" is "false".
2015-03-06 14:53:55,636 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_HTTP_PORT" is "None".
2015-03-06 14:53:55,636 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_JAVA_MODULEPATH" is "/usr/share/ovirt-engine/modules".
2015-03-06 14:53:55,636 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_JVM_ARGS" is " -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath="/var/log/ovirt-engine/dump"".
2015-03-06 14:53:55,637 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_LOG" is "/var/log/ovirt-engine".
2015-03-06 14:53:55,637 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_LOG_TO_CONSOLE" is "false".
2015-03-06 14:53:55,637 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_MANUAL" is "/usr/share/ovirt-engine/manual".
2015-03-06 14:53:55,637 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PERM_MAX" is "256m".
2015-03-06 14:53:55,638 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PERM_MIN" is "256m".
2015-03-06 14:53:55,638 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI" is "/etc/pki/ovirt-engine".
2015-03-06 14:53:55,638 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_CA" is "/etc/pki/ovirt-engine/ca.pem".
2015-03-06 14:53:55,638 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_ENGINE_CERT" is "/etc/pki/ovirt-engine/certs/engine.cer".
2015-03-06 14:53:55,639 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_ENGINE_STORE" is "/etc/pki/ovirt-engine/keys/engine.p12".
2015-03-06 14:53:55,639 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_ENGINE_STORE_ALIAS" is "1".
2015-03-06 14:53:55,639 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_ENGINE_STORE_PASSWORD" is "***".
2015-03-06 14:53:55,639 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_TRUST_STORE" is "/etc/pki/ovirt-engine/.truststore".
2015-03-06 14:53:55,640 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PKI_TRUST_STORE_PASSWORD" is "***".
2015-03-06 14:53:55,640 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PROPERTIES" is " jsse.enableSNIExtension=false "java.security.krb5.conf=/etc/ovirt-engine/krb5.conf"".
2015-03-06 14:53:55,640 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PROXY_ENABLED" is "true".
2015-03-06 14:53:55,640 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PROXY_HTTPS_PORT" is "443".
2015-03-06 14:53:55,642 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_PROXY_HTTP_PORT" is "80".
2015-03-06 14:53:55,642 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_REPORTS_BASE_URL" is "".
2015-03-06 14:53:55,642 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_REPORTS_DASHBOARD_URL" is "/flow.html?viewAsDashboardFrame=true".
2015-03-06 14:53:55,643 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_REPORTS_NOT_INSTALLED_URL" is "/ovirt-engine/ReportsNotInstalled.html".
2015-03-06 14:53:55,643 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_REPORTS_PROXY_URL" is "/ovirt/reports-interface".
2015-03-06 14:53:55,643 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_REPORTS_READ_TIMEOUT" is "".
2015-03-06 14:53:55,644 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_REPORTS_RIGHTCLICK_URL" is "".
2015-03-06 14:53:55,644 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_REPORTS_VERIFY_CHAIN" is "true".
2015-03-06 14:53:55,644 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_REPORTS_VERIFY_HOST" is "true".
2015-03-06 14:53:55,645 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_STOP_INTERVAL" is "1".
2015-03-06 14:53:55,645 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_STOP_TIME" is "10".
2015-03-06 14:53:55,645 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_TMP" is "/var/tmp/ovirt-engine".
2015-03-06 14:53:55,645 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_UP_MARK" is "/var/lib/ovirt-engine/engine.up".
2015-03-06 14:53:55,646 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_URI" is "/ovirt-engine".
2015-03-06 14:53:55,646 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_USER" is "ovirt".
2015-03-06 14:53:55,646 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_USR" is "/usr/share/ovirt-engine".
2015-03-06 14:53:55,646 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_VAR" is "/var/lib/ovirt-engine".
2015-03-06 14:53:55,647 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "ENGINE_VERBOSE_GC" is "false".
2015-03-06 14:53:55,647 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "JBOSS_HOME" is "/usr/share/ovirt-engine-jboss-as".
2015-03-06 14:53:55,647 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "JBOSS_RUNTIME" is "/var/lib/ovirt-engine/jboss_runtime".
2015-03-06 14:53:55,647 INFO  [org.ovirt.engine.core.uutils.config.ShellLikeConfd] (MSC service thread 1-3) Value of property "SENSITIVE_KEYS" is ",ENGINE_DB_PASSWORD,ENGINE_PKI_TRUST_STORE_PASSWORD,ENGINE_PKI_ENGINE_STORE_PASSWORD,ENGINE_EXTERNAL_PROVIDERS_TRUST_STORE_PASSWORD".
2015-03-06 14:53:56,095 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Start initializing Backend
2015-03-06 14:53:56,407 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-1) Could not parse option AutoRecoveryAllowedTypes value.
2015-03-06 14:53:56,434 INFO  [org.ovirt.engine.core.utils.osinfo.OsInfoPreferencesLoader] (MSC service thread 1-1) Loading file /etc/ovirt-engine/osinfo.conf.d/00-defaults.properties
2015-03-06 14:53:56,569 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Running ovirt-engine 3.5.1.1-1.fc20
2015-03-06 14:53:56,570 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-1) Start initializing dictionaries
2015-03-06 14:53:56,571 INFO  [org.ovirt.engine.core.bll.CpuFlagsManagerHandler] (MSC service thread 1-1) Finished initializing dictionaries
2015-03-06 14:53:56,572 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Start initializing AuditLogCleanupManager
2015-03-06 14:53:56,572 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Setting audit cleanup manager to run at: 35 35 3 * * ?
2015-03-06 14:53:56,584 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Finished initializing AuditLogCleanupManager
2015-03-06 14:53:56,585 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Start initializing CommandEntityCleanupManager
2015-03-06 14:53:56,585 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Setting command entity cleanup manager to run at: 35 35 3 * * ?
2015-03-06 14:53:56,586 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-1) Finished initializing CommandEntityCleanupManager
2015-03-06 14:53:56,588 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Start initializing TagsDirector
2015-03-06 14:53:56,594 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Tag root added to tree
2015-03-06 14:53:56,603 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-1) Finished initializing TagsDirector
2015-03-06 14:53:56,604 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-1) Start initializing IsoDomainListSyncronizer
2015-03-06 14:53:56,610 INFO  [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (MSC service thread 1-1) Finished initializing IsoDomainListSyncronizer
2015-03-06 14:53:56,662 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Completed initializing handlers
2015-03-06 14:53:56,680 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Start initializing ErrorTranslatorImpl
2015-03-06 14:53:56,686 WARN  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Code MAC_ADDRESS_IS_IN_USE appears more than once in string table.
2015-03-06 14:53:56,687 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Finished initializing ErrorTranslatorImpl
2015-03-06 14:53:56,687 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Start initializing ErrorTranslatorImpl
2015-03-06 14:53:56,688 INFO  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-1) Finished initializing ErrorTranslatorImpl
2015-03-06 14:53:56,688 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-1) Mark incomplete jobs as UNKNOWN
2015-03-06 14:53:56,725 INFO  [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-1) Start initializing JobRepositoryCleanupManager
2015-03-06 14:53:56,726 INFO  [org.ovirt.engine.core.bll.job.JobRepositoryCleanupManager] (MSC service thread 1-1) Finished initializing JobRepositoryCleanupManager
2015-03-06 14:53:56,726 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-1) Start initializing AutoRecoveryManager
2015-03-06 14:53:56,727 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (MSC service thread 1-1) Finished initializing AutoRecoveryManager
2015-03-06 14:53:56,728 INFO  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-1) Start initializing ExecutionMessageDirector
2015-03-06 14:53:56,730 INFO  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (MSC service thread 1-1) Finished initializing ExecutionMessageDirector
2015-03-06 14:53:56,784 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Loading extension 'builtin-authn-internal'
2015-03-06 14:53:56,785 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Extension 'builtin-authn-internal' loaded
2015-03-06 14:53:56,786 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Loading extension 'internal'
2015-03-06 14:53:56,787 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Extension 'internal' loaded
2015-03-06 14:53:56,789 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Initializing extension 'builtin-authn-internal'
2015-03-06 14:53:56,789 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Extension 'builtin-authn-internal' initialized
2015-03-06 14:53:56,790 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Initializing extension 'internal'
2015-03-06 14:53:56,790 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Extension 'internal' initialized
2015-03-06 14:53:56,790 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Start of enabled extensions list
2015-03-06 14:53:56,791 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Instance name: 'builtin-authn-internal', Extension name: 'Internal Authn (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0',  File: 'N/A', Initialized: 'true'
2015-03-06 14:53:56,791 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) Instance name: 'internal', Extension name: 'Internal Authz (Built-in)', Version: 'N/A', Notes: '', License: 'ASL 2.0', Home: 'http://www.ovirt.org', Author 'The oVirt Project', Build interface Version: '0',  File: 'N/A', Initialized: 'true'
2015-03-06 14:53:56,792 INFO  [org.ovirt.engine.core.extensions.mgr.ExtensionsManager] (MSC service thread 1-1) End of enabled extensions list
2015-03-06 14:53:56,840 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (MSC service thread 1-1) Initialization of AsyncTaskManager completed successfully.
2015-03-06 14:53:56,841 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-1) Start initializing ResourceManager
2015-03-06 14:53:56,894 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-1) Finished initializing ResourceManager
2015-03-06 14:53:56,895 INFO  [org.ovirt.engine.core.bll.OvfDataUpdater] (MSC service thread 1-1) Initialization of OvfDataUpdater completed successfully.
2015-03-06 14:53:56,895 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Start scheduling to enable vds load balancer
2015-03-06 14:53:56,895 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Finished scheduling to enable vds load balancer
2015-03-06 14:53:56,896 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Start HA Reservation check
2015-03-06 14:53:56,896 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Finished HA Reservation check
2015-03-06 14:53:56,901 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) Init VM custom properties utilities
2015-03-06 14:53:56,902 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) Init device custom properties utilities
2015-03-06 14:53:56,903 INFO  [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Start initializing MacPoolManagerRanges
2015-03-06 14:53:56,905 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Initializing Scheduling manager
2015-03-06 14:53:56,938 INFO  [org.ovirt.engine.core.bll.network.MacPoolManagerRanges] (org.ovirt.thread.pool-8-thread-1) Finished initializing. Available MACs in pool: 256
2015-03-06 14:53:56,944 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) External scheduler disabled, discovery skipped
2015-03-06 14:53:56,944 INFO  [org.ovirt.engine.core.bll.scheduling.SchedulingManager] (MSC service thread 1-1) Initialized Scheduling manager
2015-03-06 14:53:56,944 INFO  [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-1) Initializing DWH Heart Beat
2015-03-06 14:53:56,945 INFO  [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (MSC service thread 1-1) DWH Heart Beat initialized
2015-03-06 14:54:35,832 INFO  [org.ovirt.engine.core.bll.aaa.LogoutUserCommand] (ajp--127.0.0.1-8702-1) [28c890] Running command: LogoutUserCommand internal: false.
2015-03-06 14:54:35,835 INFO  [org.ovirt.engine.core.bll.aaa.LogoutUserCommand] (ajp--127.0.0.1-8702-5) [3997670e] Running command: LogoutUserCommand internal: false.
2015-03-06 14:54:35,837 INFO  [org.ovirt.engine.core.bll.aaa.LogoutUserCommand] (ajp--127.0.0.1-8702-4) [5ed3444b] Running command: LogoutUserCommand internal: false.
2015-03-06 14:54:35,838 INFO  [org.ovirt.engine.core.bll.aaa.LogoutUserCommand] (ajp--127.0.0.1-8702-3) [176847f] Running command: LogoutUserCommand internal: false.
2015-03-06 14:54:35,859 ERROR [org.ovirt.engine.core.bll.aaa.LogoutUserCommand] (ajp--127.0.0.1-8702-3) [176847f] Transaction rolled-back for command: org.ovirt.engine.core.bll.aaa.LogoutUserCommand.
2015-03-06 14:54:35,860 ERROR [org.ovirt.engine.core.bll.aaa.LogoutUserCommand] (ajp--127.0.0.1-8702-5) [3997670e] Transaction rolled-back for command: org.ovirt.engine.core.bll.aaa.LogoutUserCommand.
2015-03-06 14:54:35,861 ERROR [org.ovirt.engine.core.bll.aaa.LogoutUserCommand] (ajp--127.0.0.1-8702-1) [28c890] Transaction rolled-back for command: org.ovirt.engine.core.bll.aaa.LogoutUserCommand.
2015-03-06 14:54:35,862 ERROR [org.ovirt.engine.core.bll.aaa.LogoutUserCommand] (ajp--127.0.0.1-8702-4) [5ed3444b] Transaction rolled-back for command: org.ovirt.engine.core.bll.aaa.LogoutUserCommand.
2015-03-06 14:54:35,876 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: UNASSIGNED not exist in string table
2015-03-06 14:54:35,877 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: VDS_HIGH_NETWORK_USE not exist in string table
2015-03-06 14:54:35,877 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_FAILED_REMOVE_VM not exist in string table
2015-03-06 14:54:35,878 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_RUN_UNLOCK_ENTITY_SCRIPT not exist in string table
2015-03-06 14:54:35,878 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table
2015-03-06 14:54:35,879 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table
2015-03-06 14:54:35,879 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_ADDED_DISK_PROFILE not exist in string table
2015-03-06 14:54:35,880 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_FAILED_TO_ADD_DISK_PROFILE not exist in string table
2015-03-06 14:54:35,880 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_REMOVED_DISK_PROFILE not exist in string table
2015-03-06 14:54:35,880 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_FAILED_TO_REMOVE_DISK_PROFILE not exist in string table
2015-03-06 14:54:35,881 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_UPDATED_DISK_PROFILE not exist in string table
2015-03-06 14:54:35,881 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_FAILED_TO_UPDATE_DISK_PROFILE not exist in string table
2015-03-06 14:54:35,882 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_ADDED_CPU_PROFILE not exist in string table
2015-03-06 14:54:35,882 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_FAILED_TO_ADD_CPU_PROFILE not exist in string table
2015-03-06 14:54:35,882 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_REMOVED_CPU_PROFILE not exist in string table
2015-03-06 14:54:35,883 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_FAILED_TO_REMOVE_CPU_PROFILE not exist in string table
2015-03-06 14:54:35,883 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_UPDATED_CPU_PROFILE not exist in string table
2015-03-06 14:54:35,883 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] AuditLogType: USER_FAILED_TO_UPDATE_CPU_PROFILE not exist in string table
2015-03-06 14:54:35,937 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-4) [5ed3444b] Correlation ID: 5ed3444b, Call Stack: null, Custom Event ID: -1, Message: Failed to log User null at N/A out.
2015-03-06 14:54:35,951 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-3) [176847f] Correlation ID: 176847f, Call Stack: null, Custom Event ID: -1, Message: Failed to log User null at N/A out.
2015-03-06 14:54:35,951 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-5) [3997670e] Correlation ID: 3997670e, Call Stack: null, Custom Event ID: -1, Message: Failed to log User null at N/A out.
2015-03-06 14:54:35,951 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-1) [28c890] Correlation ID: 28c890, Call Stack: null, Custom Event ID: -1, Message: Failed to log User null at N/A out.
2015-03-06 14:57:34,621 INFO  [org.ovirt.engine.core.bll.aaa.LoginAdminUserCommand] (ajp--127.0.0.1-8702-9) Running command: LoginAdminUserCommand internal: false.
2015-03-06 14:57:34,621 INFO  [org.ovirt.engine.core.bll.aaa.LoginUserCommand] (ajp--127.0.0.1-8702-6) Running command: LoginUserCommand internal: false.
2015-03-06 14:57:34,621 INFO  [org.ovirt.engine.core.bll.aaa.LoginAdminUserCommand] (ajp--127.0.0.1-8702-7) Running command: LoginAdminUserCommand internal: false.
2015-03-06 14:57:34,786 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-9) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: User admin at internal logged in.
2015-03-06 14:57:36,571 INFO  [org.ovirt.engine.core.bll.aaa.LoginUserCommand] (ajp--127.0.0.1-8702-2) Running command: LoginUserCommand internal: false.
2015-03-06 14:57:36,574 INFO  [org.ovirt.engine.core.bll.aaa.LoginUserCommand] (ajp--127.0.0.1-8702-12) Running command: LoginUserCommand internal: false.
2015-03-06 14:57:36,767 INFO  [org.ovirt.engine.core.bll.aaa.LoginUserCommand] (ajp--127.0.0.1-8702-12) Running command: LoginUserCommand internal: false.
-------------- next part --------------
14:53:48,869 INFO  [org.jboss.modules] JBoss Modules version 1.1.1.GA
14:53:49,266 INFO  [org.jboss.msc] JBoss MSC version 1.0.2.GA
14:53:49,418 INFO  [org.jboss.as] JBAS015899: JBoss AS 7.1.1.Final "Brontes" starting
14:53:50,761 INFO  [org.xnio] XNIO Version 3.0.3.GA
14:53:50,779 INFO  [org.xnio.nio] XNIO NIO Implementation Version 3.0.3.GA
14:53:50,790 INFO  [org.jboss.remoting] JBoss Remoting version 3.2.3.GA
14:53:50,834 INFO  [org.jboss.as.logging] JBAS011502: Removing bootstrap log handlers


More information about the Users mailing list