13:25:37 [ INFO ] TASK [Wait for the engine to come up on the target VM]
13:37:01 [ ERROR ] fatal: [localhost]: FAILED! => {"attempts": 120, "changed": true, "cmd": ["hosted-engine", "--vm-status", "--json"], "delta": "0:00:00.316574", "end": "2018-04-05 09:37:00.826888", "rc": 0, "start": "2018-04-05 09:37:00.510314", "stderr": "", "stderr_lines": [], "stdout": "{\"1\": {\"conf_on_shared_storage\": true, \"live-data\": true, \"extra\": \"metadata_parse_version=1\\nmetadata_feature_version=1\\ntimestamp=1838 (Thu Apr 5 09:36:51 2018)\\nhost-id=1\\nscore=0\\nvm_conf_refresh_time=1838 (Thu Apr 5 09:36:51 2018)\\nconf_on_shared_storage=True\\nmaintenance=False\\nstate=EngineUnexpectedlyDown\\nstopped=False\\ntimeout=Wed Dec 31 19:31:35 1969\\n\", \"hostname\": \"lago-he-basic-ansible-suite-master-host0\", \"host-id\": 1, \"engine-status\": {\"reason\": \"bad vm status\", \"health\": \"bad\", \"vm\": \"down_unexpected\", \"detail\": \"Down\"}, \"score\": 0, \"stopped\": false, \"maintenance\": false, \"crc32\": \"72e3e0e9\", \"local_conf_timestamp\": 1838, \"host-ts\": 1838}, \"global_maintenance\": false}", "stdout_lines": ["{\"1\": {\"conf_on_shared_storage\": true, \"live-data\": true, \"extra\": \"metadata_parse_version=1\\nmetadata_feature_version=1\\ntimestamp=1838 (Thu Apr 5 09:36:51 2018)\\nhost-id=1\\nscore=0\\nvm_conf_refresh_time=1838 (Thu Apr 5 09:36:51 2018)\\nconf_on_shared_storage=True\\nmaintenance=False\\nstate=EngineUnexpectedlyDown\\nstopped=False\\ntimeout=Wed Dec 31 19:31:35 1969\\n\", \"hostname\": \"lago-he-basic-ansible-suite-master-host0\", \"host-id\": 1, \"engine-status\": {\"reason\": \"bad vm status\", \"health\": \"bad\", \"vm\": \"down_unexpected\", \"detail\": \"Down\"}, \"score\": 0, \"stopped\": false, \"maintenance\": false, \"crc32\": \"72e3e0e9\", \"local_conf_timestamp\": 1838, \"host-ts\": 1838}, \"global_maintenance\": false}"]}
13:37:01 [ ERROR ] Failed to execute stage 'Closing up': Failed executing ansible-playbook
Anso so we have to check VDSM logs where we can find:
2018-04-05 09:27:01,083-0400 ERROR (vm/9bb2deab) [virt.vm] (vmId='9bb2deab-f686-4629-acee-90e2774a66b6') The vm start process failed (vm:945)
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 874, in _startUnderlyingVm
self._run()
File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 2832, in _run
domxml = hooks.before_vm_start(self._buildDomainXML(),
File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 2258, in _buildDomainXML
return vmxml.format_xml(dom, pretty=True)
File "/usr/lib/python2.7/site-packages/vdsm/virt/vmxml.py", line 71, in format_xml
stream, encoding='utf-8', xml_declaration=True)
File "/usr/lib64/python2.7/xml/etree/ElementTree.py", line 820, in write
serialize(write, self._root, encoding, qnames, namespaces)
File "/usr/lib64/python2.7/xml/etree/ElementTree.py", line 939, in _serialize_xml
_serialize_xml(write, e, encoding, qnames, None)
File "/usr/lib64/python2.7/xml/etree/ElementTree.py", line 939, in _serialize_xml
_serialize_xml(write, e, encoding, qnames, None)
File "/usr/lib64/python2.7/xml/etree/ElementTree.py", line 939, in _serialize_xml
_serialize_xml(write, e, encoding, qnames, None)
File "/usr/lib64/python2.7/xml/etree/ElementTree.py", line 932, in _serialize_xml
v = _escape_attrib(v, encoding)
File "/usr/lib64/python2.7/xml/etree/ElementTree.py", line 1092, in _escape_attrib
_raise_serialization_error(text)
File "/usr/lib64/python2.7/xml/etree/ElementTree.py", line 1052, in _raise_serialization_error
"cannot serialize %r (type %s)" % (text, type(text).__name__)
TypeError: cannot serialize 0 (type int)
2018-04-05 09:27:01,084-0400 INFO (vm/9bb2deab) [virt.vm] (vmId='9bb2deab-f686-4629-acee-90e2774a66b6') Changed state to Down: cannot serialize 0 (type int) (code=1) (vm:1685)It seams another regression with the libvirt XML generated by the engine.