Attachment 829281 Details for Bug 1034787 – Agent log

Agent log

agent.log (text/x-log), 89.35 KB, created by Pablo Iranzo Gómez on 2013-11-26 14:07:43 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Pablo Iranzo Gómez

Created: 2013-11-26 14:07:43 UTC

Size: 89.35 KB

patch

obsolete

>MainThread::INFO::2013-11-26 14:03:38,733::agent::50::Broker::(run) ovirt-hosted-engine-ha agent 0.1.0-0.7.beta1 started
>MainThread::INFO::2013-11-26 14:03:38,775::hosted_engine::293::HostedEngine::(_initialize_broker) Initializing ha-broker connection
>MainThread::INFO::2013-11-26 14:03:38,776::brokerlink::67::BrokerLink::(connect) Failed to connect to broker: [Errno 2] No such file or directory
>MainThread::INFO::2013-11-26 14:03:38,776::brokerlink::68::BrokerLink::(connect) Retrying broker connection...
>MainThread::INFO::2013-11-26 14:03:39,778::brokerlink::126::BrokerLink::(start_monitor) Starting monitor ping, options {'addr': '192.168.2.1'}
>MainThread::INFO::2013-11-26 14:03:39,783::brokerlink::137::BrokerLink::(start_monitor) Success, id 33128912
>MainThread::INFO::2013-11-26 14:03:39,784::brokerlink::126::BrokerLink::(start_monitor) Starting monitor mgmt-bridge, options {'use_ssl': 'true', 'bridge_name': 'rhevm', 'address': '0'}
>MainThread::INFO::2013-11-26 14:03:39,800::brokerlink::137::BrokerLink::(start_monitor) Success, id 33129552
>MainThread::INFO::2013-11-26 14:03:39,800::brokerlink::126::BrokerLink::(start_monitor) Starting monitor mem-free, options {'use_ssl': 'true', 'address': '0'}
>MainThread::INFO::2013-11-26 14:03:39,805::brokerlink::137::BrokerLink::(start_monitor) Success, id 33129488
>MainThread::INFO::2013-11-26 14:03:39,806::brokerlink::126::BrokerLink::(start_monitor) Starting monitor cpu-load, options {'use_ssl': 'true', 'address': '0'}
>MainThread::INFO::2013-11-26 14:03:39,827::brokerlink::137::BrokerLink::(start_monitor) Success, id 139818231373008
>MainThread::INFO::2013-11-26 14:03:39,829::brokerlink::126::BrokerLink::(start_monitor) Starting monitor mem-load, options {'use_ssl': 'true', 'address': '0'}
>MainThread::INFO::2013-11-26 14:03:39,841::brokerlink::137::BrokerLink::(start_monitor) Success, id 139818231373776
>MainThread::INFO::2013-11-26 14:03:39,842::brokerlink::126::BrokerLink::(start_monitor) Starting monitor engine-health, options {'use_ssl': 'true', 'vm_uuid': 'ebdc068e-a1b6-4403-a8f3-1a44db957e15', 'address': '0'}
>MainThread::INFO::2013-11-26 14:03:39,847::brokerlink::137::BrokerLink::(start_monitor) Success, id 139818231373648
>MainThread::INFO::2013-11-26 14:03:39,848::hosted_engine::316::HostedEngine::(_initialize_broker) Broker initialized, all submonitors started
>MainThread::INFO::2013-11-26 14:03:40,612::hosted_engine::384::HostedEngine::(_initialize_sanlock) Ensuring lease for lockspace hosted-engine, host id 1 is acquired (file: /rhev/data-center/mnt/192.168.2.154:_home_shared_RHEV/314a1331-6bc7-4a78-87a8-41f99371b87b/ha_agent/hosted-engine.lockspace)
>MainThread::INFO::2013-11-26 14:04:01,618::hosted_engine::416::HostedEngine::(_initialize_sanlock) Acquired lock on host id 1
>MainThread::INFO::2013-11-26 14:04:01,692::hosted_engine::489::HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: NONE
>MainThread::INFO::2013-11-26 14:04:01,776::hosted_engine::442::HostedEngine::(_initialize_domain_monitor) Started VDSM domain monitor for 314a1331-6bc7-4a78-87a8-41f99371b87b
>MainThread::INFO::2013-11-26 14:04:01,846::hosted_engine::489::HostedEngine::(_get_domain_monitor_status) VDSM domain monitor status: PENDING
>MainThread::INFO::2013-11-26 14:04:07,340::hosted_engine::741::HostedEngine::(_collect_all_host_stats) Waiting for first update from host Virtualizador (id 1)
>MainThread::INFO::2013-11-26 14:04:07,341::hosted_engine::758::HostedEngine::(_collect_all_host_stats) Global metadata: {}
>MainThread::INFO::2013-11-26 14:04:07,341::hosted_engine::762::HostedEngine::(_collect_all_host_stats) Host Virtualizador (id 1): {'last-update-host-ts': 1385471046, 'last-update-local-ts': 1385471047, 'hostname': 'Virtualizador', 'alive': 'unknown', 'engine-status': 'vm-down', 'score': 2400, 'first-update': True}
>MainThread::INFO::2013-11-26 14:04:07,341::hosted_engine::789::HostedEngine::(_perform_engine_actions) Unknown host state for id 1, waiting for initialization
>MainThread::INFO::2013-11-26 14:04:18,134::hosted_engine::869::HostedEngine::(_handle_entry) Determining initial state for host
>MainThread::INFO::2013-11-26 14:04:18,134::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471058.13 type=state_transition detail=ENTRY-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:04:18,137::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ENTRY-OFF) sent? ignored
>MainThread::ERROR::2013-11-26 14:04:18,137::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (2400), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:04:18,138::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471058.14 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:04:18,414::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:04:18,415::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:04:18,494::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:04:18,494::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:04:18,863::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:04:18,864::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:04:18,864::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:04:18,866::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471058.87 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:04:18,917::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:04:29,970::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:04:40,933::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:04:51,755::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:05:02,644::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:05:13,418::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 256 seconds
>MainThread::ERROR::2013-11-26 14:05:26,261::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 243 seconds
>MainThread::ERROR::2013-11-26 14:05:37,229::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:05:37,230::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471137.23 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:05:37,666::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:05:49,778::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:05:49,780::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471149.78 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:05:50,241::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:05:50,241::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:05:50,419::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:05:53,307::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:05:53,307::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:05:53,739::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'reqsize': '0', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'type': 'disk', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:05:53,740::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:05:53,740::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:05:53,749::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471153.75 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:05:53,826::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:06:04,696::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:06:15,726::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:06:26,735::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:06:37,693::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:06:48,830::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 256 seconds
>MainThread::ERROR::2013-11-26 14:07:00,092::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 244 seconds
>MainThread::ERROR::2013-11-26 14:07:11,468::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:07:11,469::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471231.47 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:07:11,617::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:07:24,152::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:07:24,155::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471244.16 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:07:24,246::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:07:24,246::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:07:24,347::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:07:29,651::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:07:29,652::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:07:30,022::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:07:30,023::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:07:30,023::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:07:30,034::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471250.03 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:07:30,133::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:07:41,745::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:07:52,747::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:08:03,608::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:08:14,449::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:08:25,500::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 256 seconds
>MainThread::ERROR::2013-11-26 14:08:36,426::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:08:36,427::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471316.43 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:08:36,532::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:08:50,356::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:08:50,359::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471330.36 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:08:50,532::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:08:50,533::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:08:50,617::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:08:54,803::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:08:54,803::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:08:55,183::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:08:55,184::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:08:55,184::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:08:55,193::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471335.19 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:08:55,269::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:09:06,288::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:09:17,065::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:09:28,020::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:09:38,803::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 268 seconds
>MainThread::ERROR::2013-11-26 14:09:49,774::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 257 seconds
>MainThread::ERROR::2013-11-26 14:10:01,092::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:10:01,094::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471401.09 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:10:01,252::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:10:12,153::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:10:12,154::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471412.15 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:10:12,284::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:10:12,285::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:10:12,374::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:10:17,545::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:10:17,545::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:10:17,899::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:10:17,900::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:10:17,900::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:10:17,908::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471417.91 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:10:17,996::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:10:28,881::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:10:39,731::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:10:50,799::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:11:01,705::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:11:13,330::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 255 seconds
>MainThread::ERROR::2013-11-26 14:11:24,379::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:11:24,381::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471484.38 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:11:24,510::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:11:37,996::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:11:37,998::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471498.0 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:11:38,112::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:11:38,112::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:11:38,185::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:11:40,991::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:11:40,991::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:11:41,344::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:11:41,344::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:11:41,345::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:11:41,345::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471501.35 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:11:41,433::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:11:52,284::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:12:03,288::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:12:14,110::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:12:25,071::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:12:36,028::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 256 seconds
>MainThread::ERROR::2013-11-26 14:12:47,119::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:12:47,120::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471567.12 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:12:47,269::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:13:00,893::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:13:00,895::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471580.9 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:13:00,997::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:13:00,998::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:13:01,071::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:13:07,356::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:13:07,356::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:13:07,721::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:13:07,722::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:13:07,722::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:13:07,731::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471587.73 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:13:07,820::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:13:18,800::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:13:29,778::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:13:40,998::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:13:51,786::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:14:02,736::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:14:02,737::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471642.74 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:14:02,835::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::INFO::2013-11-26 14:14:13,817::hosted_engine::758::HostedEngine::(_collect_all_host_stats) Global metadata: {}
>MainThread::INFO::2013-11-26 14:14:13,818::hosted_engine::762::HostedEngine::(_collect_all_host_stats) Host Virtualizador (id 1): {'last-update-host-ts': 1385471653, 'last-update-local-ts': 1385471653, 'hostname': 'Virtualizador', 'alive': True, 'engine-status': 'vm-down', 'score': 0, 'first-update': False}
>MainThread::ERROR::2013-11-26 14:14:13,819::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:14:13,821::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471653.82 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:14:13,897::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:14:13,898::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:14:13,992::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:14:18,149::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:14:18,150::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:14:18,516::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:14:18,516::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:14:18,517::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:14:18,518::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471658.52 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:14:18,602::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:14:29,766::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:14:41,065::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 288 seconds
>MainThread::ERROR::2013-11-26 14:14:51,910::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:15:02,762::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:15:13,613::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:15:13,623::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471713.62 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:15:13,756::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:15:24,658::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:15:24,660::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471724.66 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:15:24,793::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:15:24,794::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:15:24,905::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:15:30,149::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:15:30,149::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:15:30,713::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'reqsize': '0', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'type': 'disk', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:15:30,713::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:15:30,714::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:15:30,724::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471730.72 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:15:30,809::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:15:41,826::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:15:52,727::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:16:03,671::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:16:14,727::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:16:26,067::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 255 seconds
>MainThread::ERROR::2013-11-26 14:16:37,104::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:16:37,105::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471797.11 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:16:37,247::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:16:48,302::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:16:48,304::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471808.3 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:16:48,365::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:16:48,365::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:16:48,443::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:16:53,371::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:16:53,371::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:16:53,723::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:16:53,724::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:16:53,724::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:16:53,732::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471813.73 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:16:53,801::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:17:05,102::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:17:16,349::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:17:27,090::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:17:37,879::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 268 seconds
>MainThread::ERROR::2013-11-26 14:17:48,616::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 257 seconds
>MainThread::ERROR::2013-11-26 14:17:59,520::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 246 seconds
>MainThread::ERROR::2013-11-26 14:18:10,554::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:18:10,555::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471890.55 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:18:10,679::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:18:27,972::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:18:27,973::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471907.97 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:18:28,070::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:18:28,071::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:18:28,157::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:18:30,192::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:18:30,193::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:18:30,573::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:18:30,573::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:18:30,574::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:18:30,576::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385471910.58 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:18:30,691::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:18:41,795::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:18:52,723::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:19:03,625::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:19:14,471::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:19:41,826::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 240 seconds
>MainThread::ERROR::2013-11-26 14:20:05,354::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:20:05,357::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472005.36 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:20:05,509::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:20:17,332::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:20:17,333::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472017.33 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:20:17,425::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:20:17,426::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:20:17,525::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:20:22,700::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:20:22,701::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:20:23,064::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:20:23,064::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:20:23,064::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:20:23,074::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472023.07 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:20:23,172::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:20:34,972::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:20:45,768::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:20:56,517::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:21:07,381::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:21:18,483::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 256 seconds
>MainThread::ERROR::2013-11-26 14:21:29,707::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:21:29,719::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472089.72 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:21:29,867::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:21:45,720::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:21:45,724::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472105.72 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:21:45,839::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:21:45,840::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:21:45,922::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:21:51,187::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:21:51,187::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:21:51,570::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:21:51,571::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:21:51,572::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:21:51,581::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472111.58 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:21:51,655::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:22:02,700::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:22:13,449::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:22:24,181::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:22:35,085::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:22:47,187::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 255 seconds
>MainThread::ERROR::2013-11-26 14:22:58,345::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:22:58,346::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472178.35 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:22:58,448::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:23:09,429::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:23:09,432::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472189.43 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:23:09,529::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:23:09,530::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:23:09,625::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:23:15,254::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:23:15,254::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:23:15,655::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:23:15,656::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:23:15,656::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:23:15,665::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472195.67 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:23:15,740::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:23:26,707::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:23:37,545::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:23:48,456::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:23:59,582::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::INFO::2013-11-26 14:24:21,443::hosted_engine::758::HostedEngine::(_collect_all_host_stats) Global metadata: {}
>MainThread::INFO::2013-11-26 14:24:21,444::hosted_engine::762::HostedEngine::(_collect_all_host_stats) Host Virtualizador (id 1): {'last-update-host-ts': 1385472253, 'last-update-local-ts': 1385472261, 'hostname': 'Virtualizador', 'alive': True, 'engine-status': 'vm-up bad-health-status', 'score': 0, 'first-update': False}
>MainThread::ERROR::2013-11-26 14:24:21,444::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 245 seconds
>MainThread::ERROR::2013-11-26 14:24:32,428::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:24:32,428::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472272.43 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:24:32,580::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:24:48,804::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:24:48,806::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472288.81 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:24:48,900::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:24:48,901::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:24:48,980::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:24:50,797::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:24:50,797::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:24:51,189::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:24:51,190::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:24:51,190::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:24:51,199::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472291.2 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:24:51,278::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:25:02,456::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:25:13,431::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:25:24,243::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:25:35,013::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:25:46,251::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 256 seconds
>MainThread::ERROR::2013-11-26 14:25:57,229::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:25:57,229::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472357.23 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:25:57,351::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:26:08,307::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:26:08,310::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472368.31 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:26:08,458::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:26:08,458::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:26:08,561::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:26:12,680::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:26:12,681::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:26:13,041::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:26:13,041::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:26:13,041::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:26:13,051::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472373.05 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:26:13,114::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:26:23,999::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:26:34,796::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:26:45,765::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:26:57,687::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 266 seconds
>MainThread::ERROR::2013-11-26 14:27:08,895::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:27:08,907::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472428.91 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:27:09,050::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:27:20,043::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:27:20,045::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472440.05 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:27:20,131::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:27:20,132::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:27:20,229::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:27:24,413::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:27:24,413::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:27:24,789::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:27:24,789::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:27:24,790::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:27:24,799::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472444.8 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:27:24,882::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:27:35,927::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::ERROR::2013-11-26 14:27:46,726::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 289 seconds
>MainThread::ERROR::2013-11-26 14:27:57,531::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 278 seconds
>MainThread::ERROR::2013-11-26 14:28:08,375::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 267 seconds
>MainThread::ERROR::2013-11-26 14:28:19,508::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 256 seconds
>MainThread::ERROR::2013-11-26 14:28:30,473::hosted_engine::1015::HostedEngine::(_handle_on) Engine vm died unexpectedly
>MainThread::INFO::2013-11-26 14:28:30,474::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472510.47 type=state_transition detail=ON-OFF hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:28:30,613::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-OFF) sent? sent
>MainThread::ERROR::2013-11-26 14:28:41,659::hosted_engine::917::HostedEngine::(_handle_off) Engine down and local host has best score (0), attempting to start engine VM
>MainThread::INFO::2013-11-26 14:28:41,661::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472521.66 type=state_transition detail=OFF-START hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:28:41,791::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (OFF-START) sent? sent
>MainThread::INFO::2013-11-26 14:28:41,792::hosted_engine::973::HostedEngine::(_clean_vdsm_state) Ensuring VDSM state is clear for engine VM
>MainThread::INFO::2013-11-26 14:28:41,886::hosted_engine::995::HostedEngine::(_clean_vdsm_state) Cleaning state for non-running VM
>MainThread::INFO::2013-11-26 14:28:43,929::hosted_engine::985::HostedEngine::(_clean_vdsm_state) Vdsm state for VM clean
>MainThread::INFO::2013-11-26 14:28:43,930::hosted_engine::944::HostedEngine::(_start_engine_vm) Starting vm using `/usr/sbin/hosted-engine --vm-start`
>MainThread::INFO::2013-11-26 14:28:44,294::hosted_engine::949::HostedEngine::(_start_engine_vm) stdout: 
>ebdc068e-a1b6-4403-a8f3-1a44db957e15
>	Status = WaitForLaunch
>	nicModel = rtl8139,pv
>	emulatedMachine = rhel6.5.0
>	pid = 0
>	displayIp = 0
>	devices = [{'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': 'ef42c425-6da3-4b5a-b716-293ab174e3dd', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'shared': 'false', 'path': '/rhel-server-6.5-x86_64-dvd.iso', 'type': 'disk'}, {'index': '0', 'iface': 'virtio', 'type': 'disk', 'format': 'raw', 'bootOrder': '1', 'address': {'slot': '0x06', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'volumeID': 'b984f356-5211-486b-9812-97c2d1d3d37a', 'imageID': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'specParams': {}, 'readonly': 'false', 'domainID': '314a1331-6bc7-4a78-87a8-41f99371b87b', 'deviceId': '59bc6b3e-9109-4bdc-8141-2e1a27149a05', 'poolID': '00000000-0000-0000-0000-000000000000', 'device': 'disk', 'shared': 'exclusive', 'propagateErrors': 'off', 'optional': 'false'}, {'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}, {'nicModel': 'pv', 'macAddr': '00:16:3e:15:97:16', 'linkActive': 'true', 'network': 'rhevm', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '7bae8f44-bb04-4c6d-9fc4-34e1bab59873', 'address': {'slot': '0x03', 'bus': '0x00', 'domain': '0x0000', 'type': 'pci', 'function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'console', 'specParams': {}, 'type': 'console', 'deviceId': '85f9b1eb-834c-4a59-bce6-9bb759794b52', 'alias': 'console0'}]
>	smp = 2
>	vmType = kvm
>	display = qxl
>	displaySecurePort = -1
>	memSize = 3072
>	displayPort = -1
>	cpuType = Penryn
>	spiceSecureChannels = smain,sdisplay,sinputs,scursor,splayback,srecord,ssmartcard,susbredir
>	vmName = HostedEngine
>	clientIp = 
>
>MainThread::INFO::2013-11-26 14:28:44,294::hosted_engine::950::HostedEngine::(_start_engine_vm) stderr: 
>MainThread::ERROR::2013-11-26 14:28:44,294::hosted_engine::963::HostedEngine::(_start_engine_vm) Engine VM started on localhost
>MainThread::INFO::2013-11-26 14:28:44,303::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472524.3 type=state_transition detail=START-ON hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:28:44,396::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (START-ON) sent? sent
>MainThread::ERROR::2013-11-26 14:28:55,293::hosted_engine::1052::HostedEngine::(_handle_on) Engine VM has bad health status, timeout in 300 seconds
>MainThread::INFO::2013-11-26 14:29:06,347::hosted_engine::682::HostedEngine::(_collect_all_host_stats) Global metadata changed: {'maintenance': True}
>MainThread::INFO::2013-11-26 14:29:06,349::hosted_engine::1025::HostedEngine::(_handle_on) Global HA maintenance enabled
>MainThread::INFO::2013-11-26 14:29:06,349::brokerlink::108::BrokerLink::(notify) Trying: notify time=1385472546.35 type=state_transition detail=ON-MAINTENANCE hostname='Virtualizador'
>MainThread::INFO::2013-11-26 14:29:06,660::brokerlink::117::BrokerLink::(notify) Success, was notification of state_transition (ON-MAINTENANCE) sent? sent
>MainThread::INFO::2013-11-26 14:29:17,583::hosted_engine::1218::HostedEngine::(_handle_maintenance) Global HA maintenance enabled
>MainThread::INFO::2013-11-26 14:34:23,912::hosted_engine::758::HostedEngine::(_collect_all_host_stats) Global metadata: {'maintenance': True}
>MainThread::INFO::2013-11-26 14:34:23,913::hosted_engine::762::HostedEngine::(_collect_all_host_stats) Host Virtualizador (id 1): {'last-update-host-ts': 1385472863, 'last-update-local-ts': 1385472863, 'hostname': 'Virtualizador', 'alive': True, 'engine-status': 'vm-down', 'score': 0, 'first-update': False}
>MainThread::INFO::2013-11-26 14:44:22,346::hosted_engine::1218::HostedEngine::(_handle_maintenance) Global HA maintenance enabled
>MainThread::INFO::2013-11-26 14:44:33,043::hosted_engine::758::HostedEngine::(_collect_all_host_stats) Global metadata: {'maintenance': True}
>MainThread::INFO::2013-11-26 14:44:33,044::hosted_engine::762::HostedEngine::(_collect_all_host_stats) Host Virtualizador (id 1): {'last-update-host-ts': 1385473472, 'last-update-local-ts': 1385473473, 'hostname': 'Virtualizador', 'alive': True, 'engine-status': 'vm-down', 'score': 2400, 'first-update': False}
>MainThread::INFO::2013-11-26 14:54:38,644::hosted_engine::758::HostedEngine::(_collect_all_host_stats) Global metadata: {'maintenance': True}
>MainThread::INFO::2013-11-26 14:54:38,645::hosted_engine::762::HostedEngine::(_collect_all_host_stats) Host Virtualizador (id 1): {'last-update-host-ts': 1385474078, 'last-update-local-ts': 1385474078, 'hostname': 'Virtualizador', 'alive': True, 'engine-status': 'vm-down', 'score': 2400, 'first-update': False}
>MainThread::INFO::2013-11-26 14:59:33,487::hosted_engine::1218::HostedEngine::(_handle_maintenance) Global HA maintenance enabled
>MainThread::INFO::2013-11-26 15:04:38,116::hosted_engine::758::HostedEngine::(_collect_all_host_stats) Global metadata: {'maintenance': True}
>MainThread::INFO::2013-11-26 15:04:38,116::hosted_engine::762::HostedEngine::(_collect_all_host_stats) Host Virtualizador (id 1): {'last-update-host-ts': 1385474677, 'last-update-local-ts': 1385474678, 'hostname': 'Virtualizador', 'alive': True, 'engine-status': 'vm-down', 'score': 2400, 'first-update': False}

Actions: View

Attachments on bug 1034787: 829281 | 829282 | 829295