745762 – aeolus-image build fails for vsphere and rhevm

Bug 745762 - aeolus-image build fails for vsphere and rhevm

Summary: aeolus-image build fails for vsphere and rhevm

Keywords:
Status:	CLOSED ERRATA
Alias:	None
Product:	CloudForms Cloud Engine
Classification:	Retired
Component:	rubygem-aeolus-image
Sub Component:
Version:	1.0.0
Hardware:	Unspecified
OS:	Unspecified
Priority:	unspecified
Severity:	unspecified
Target Milestone:	rc
Assignee:	Jason Guiditta
QA Contact:	wes hayutin
Docs Contact:
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+	depends on / blocked

Reported:	2011-10-13 10:34 UTC by Aziza Karol
Modified:	2012-05-15 19:44 UTC (History)
CC List:	6 users (show)
Fixed In Version:
Doc Type:	Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed:	2012-05-15 19:44:10 UTC

Attachments	(Terms of Use)
Add an attachment (proposed patch, testcase, etc.)

Links
System	ID	Private	Priority	Status	Summary	Last Updated
Red Hat Product Errata	RHEA-2012:0592	0	normal	SHIPPED_LIVE	new packages: rubygem-aeolus-image	2012-05-15 22:31:14 UTC

Description Aziza Karol 2011-10-13 10:34:59 UTC

Description of problem:


Version-Release number of selected component (if applicable):


How reproducible:


Steps to Reproduce:
# aeolus-image build --target vsphere  --template  fedora15_template.tpl
3600
2011-10-13 05:48:09,903 DEBUG oz.Guest.FedoraGuest pid(14699) Message: Waiting for Fedora 15-b0eb2b45-5bdc-4a8e-9277-c5074ad953f6 to finish installing, 3300/3600
2011-10-13 05:48:12,858 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(14699) Message: Exception caught in ImageFactory
2011-10-13 05:48:12,986 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(14699) Message: Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/imgfac/builders/Fedora_vsphere_Builder.py", line 101, in build_upload
    libvirt_xml = guest.install(self.app_config["timeout"])
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 1427, in install
    return self._do_install(timeout, force, 0)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 1409, in _do_install
    self._wait_for_install_finish(dom, timeout)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 536, in _wait_for_install_finish
    raise oz.OzException.OzException(exc_str)
OzException: No disk activity in 300 seconds, failing.  Check screenshot at /Fedora 15-b0eb2b45-5bdc-4a8e-9277-c5074ad953f6-1318499290.17.png for more detail

2011-10-13 05:48:12,986 INFO oz.Guest.FedoraGuest pid(14699) Message: Cleaning up guest named Fedora 15-b0eb2b45-5bdc-4a8e-9277-c5074ad953f6
2011-10-13 05:48:17,099 DEBUG imgfac.BuildJob.BuildAdaptor pid(14699) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to FAILED
2011-10-13 05:48:17,332 INFO oz.Guest.FedoraGuest pid(14699) Message: Cleaning up after install
2011-10-13 05:48:17,423 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(14699) Message: Exception caught in ImageFactory
2011-10-13 05:48:17,424 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(14699) Message: Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/imgfac/builders/Fedora_vsphere_Builder.py", line 49, in build_image
    self.build_upload(build_id)
  File "/usr/lib/python2.7/site-packages/imgfac/builders/Fedora_vsphere_Builder.py", line 101, in build_upload
    libvirt_xml = guest.install(self.app_config["timeout"])
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 1427, in install
    return self._do_install(timeout, force, 0)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 1409, in _do_install
    self._wait_for_install_finish(dom, timeout)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 536, in _wait_for_install_finish
    raise oz.OzException.OzException(exc_str)
OzException: No disk activity in 300 seconds, failing.  Check screenshot at /Fedora 15-b0eb2b45-5bdc-4a8e-9277-c5074ad953f6-1318499290.17.png for more detail

2011-10-13 05:48:17,424 DEBUG imgfac.BuildJob.BuildAdaptor pid(14699) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from FAILED to FAILED



# aeolus-image build --target rhevm --template  fedora15_template.tpl

2011-10-13 05:54:47,116 DEBUG oz.Guest.FedoraGuest pid(14699) Message: Waiting for Fedora 15-58ea25af-9a3b-4320-954c-69e6fba44672 to finish installing, 3350/3600
2011-10-13 05:54:57,150 DEBUG oz.Guest.FedoraGuest pid(14699) Message: Waiting for Fedora 15-58ea25af-9a3b-4320-954c-69e6fba44672 to finish installing, 3340/3600
2011-10-13 05:55:07,203 DEBUG oz.Guest.FedoraGuest pid(14699) Message: Waiting for Fedora 15-58ea25af-9a3b-4320-954c-69e6fba44672 to finish installing, 3330/3600
2011-10-13 05:55:17,242 DEBUG oz.Guest.FedoraGuest pid(14699) Message: Waiting for Fedora 15-58ea25af-9a3b-4320-954c-69e6fba44672 to finish installing, 3320/3600
2011-10-13 05:55:27,281 DEBUG oz.Guest.FedoraGuest pid(14699) Message: Waiting for Fedora 15-58ea25af-9a3b-4320-954c-69e6fba44672 to finish installing, 3310/3600
2011-10-13 05:55:37,318 DEBUG oz.Guest.FedoraGuest pid(14699) Message: Waiting for Fedora 15-58ea25af-9a3b-4320-954c-69e6fba44672 to finish installing, 3300/3600
2011-10-13 05:55:40,366 INFO oz.Guest.FedoraGuest pid(14699) Message: Cleaning up guest named Fedora 15-58ea25af-9a3b-4320-954c-69e6fba44672
2011-10-13 05:55:44,593 INFO oz.Guest.FedoraGuest pid(14699) Message: Cleaning up after install
2011-10-13 05:55:44,614 DEBUG imgfac.builders.BaseBuilder.Fedora_rhevm_Builder pid(14699) Message: Exception caught in ImageFactory
2011-10-13 05:55:44,713 DEBUG imgfac.builders.BaseBuilder.Fedora_rhevm_Builder pid(14699) Message: Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/imgfac/builders/Fedora_rhevm_Builder.py", line 65, in build_image
    self.build_upload(build_id)
  File "/usr/lib/python2.7/site-packages/imgfac/builders/Fedora_rhevm_Builder.py", line 112, in build_upload
    libvirt_xml = guest.install(self.app_config["timeout"])
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 1427, in install
    return self._do_install(timeout, force, 0)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 1409, in _do_install
    self._wait_for_install_finish(dom, timeout)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 536, in _wait_for_install_finish
    raise oz.OzException.OzException(exc_str)
OzException: No disk activity in 300 seconds, failing.  Check screenshot at /Fedora 15-58ea25af-9a3b-4320-954c-69e6fba44672-1318499737.5.png for more detail

2011-10-13 05:55:44,904 DEBUG imgfac.BuildJob.BuildAdaptor pid(14699) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to FAILED



Additional info:
root@dell-pesc430-03 templates]# rpm -qa | egrep 'aeolus|iwhd|factory|oz'
aeolus-conductor-0.5.0-0.20111012134715git383de2f.fc15.noarch
iwhd-0.99.2.ee04.dirty-1.fc15.x86_64
imagefactory-jeosconf-ec2-rhel-0.1.0-1.fc15.noarch
rubygem-imagefactory-console-0.5.0-4.20110824113238gitd9debef.fc15.noarch
aeolus-conductor-doc-0.5.0-0.20111012134715git383de2f.fc15.noarch
imagefactory-jeosconf-ec2-fedora-0.1.0-1.fc15.noarch
aeolus-configure-2.2.0-1.20111007151942git26136f2.fc15.noarch
rubygem-factory_girl-2.0.5-1.fc15.noarch
rubygem-factory_girl_rails-1.1.0-1.fc15.noarch
rubygem-aeolus-image-0.1.0-3.20111003170706git8f23238.fc15.noarch
oz-0.7.0-3.fc15.noarch
aeolus-all-0.5.0-0.20111012134715git383de2f.fc15.noarch
imagefactory-0.7.0-1.fc15.noarch
aeolus-conductor-daemons-0.5.0-0.20111012134715git383de2f.fc15.noarch
rubygem-aeolus-cli-0.1.0-3.20111003133323git9451323.fc15.noarch

Comment 2 wes hayutin 2011-10-13 13:57:11 UTC

Aziza, try a recreate a box that you can confirm you've been able to create a libvirt guest. Also check your iptables or post them in the bug

Comment 3 Chris Lalancette 2011-10-13 14:01:43 UTC

Also get the screenshot that is mentioned in the output:

OzException: No disk activity in 300 seconds, failing.  Check screenshot at
/Fedora 15-b0eb2b45-5bdc-4a8e-9277-c5074ad953f6-1318499290.17.png for more
detail

And attach it to the bug, as that will give us a better idea of what failed.

Comment 4 Aziza Karol 2011-10-14 10:08:23 UTC

This issue is not reproducible.
aeolus-image build successful for vsphere and rhevm.

rhevm:

2011-10-14 02:15:55,263 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: 10485760kB of 10485760kB
2011-10-14 02:15:55,263 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: 10485760kB of 10485760kB
2011-10-14 02:15:55,263 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: 10485760kB of 10485760kB
2011-10-14 02:15:55,264 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: Setting metadata ({'icicle': '58183c51-bdf9-43a2-ab90-237b6597306c', 'uuid': '8cff1eb7-52a6-44f3-8944-6cf279697acd', 'template': '9d1a70b1-2343-4699-868c-dc10c6efa922', 'target_parameters': '<?xml version="1.0"?>\n<domain type="kvm">\n  <name>Fedora 15-8cff1eb7-52a6-44f3-8944-6cf279697acd</name>\n  <memory>1048576</memory>\n  <currentMemory>1048576</currentMemory>\n  <uuid>41c91168-535c-4b93-91b6-04c1cb2f466b</uuid>\n  <clock offset="utc"/>\n  <vcpu>1</vcpu>\n  <features>\n    <acpi/>\n    <apic/>\n    <pae/>\n  </features>\n  <os>\n    <type>hvm</type>\n    <boot dev="hd"/>\n  </os>\n  <on_poweroff>destroy</on_poweroff>\n  <on_reboot>destroy</on_reboot>\n  <on_crash>destroy</on_crash>\n  <devices>\n    <console device="pty"/>\n    <graphics port="-1" type="vnc"/>\n    <interface type="bridge">\n      <source bridge="virbr0"/>\n      <mac address="52:54:00:f6:7e:af"/>\n      <model type="virtio"/>\n    </interface>\n    <input bus="ps2" type="mouse"/>\n    <console type="pty">\n      <target port="0"/>\n    </console>\n    <serial type="tcp">\n      <source mode="bind" host="127.0.0.1" service="5395"/>\n      <protocol type="raw"/>\n      <target port="1"/>\n    </serial>\n    <disk device="disk" type="file">\n      <target dev="vda" bus="virtio"/>\n      <source file="/var/lib/imagefactory/images/rhevm-image-8cff1eb7-52a6-44f3-8944-6cf279697acd.dsk"/>\n    </disk>\n  </devices>\n</domain>\n', 'object_type': 'target_image', 'target': 'rhevm', 'build': 'cf02f88f-a895-40a1-9631-f98e44b4c567'}) for http://localhost:9090/target_images/8cff1eb7-52a6-44f3-8944-6cf279697acd
2011-10-14 02:15:55,281 DEBUG imgfac.builders.BaseBuilder.Fedora_rhevm_Builder pid(3651) Message: Image warehouse storage complete
2011-10-14 02:15:55,282 DEBUG imgfac.BuildJob.BuildAdaptor pid(3651) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 50 to 100
2011-10-14 02:15:55,283 DEBUG imgfac.BuildJob.BuildAdaptor pid(3651) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), 
changed status from BUILDING to COMPLETED



vsphere:

011-10-14 03:14:07,936 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: 354285kB of 354285kB
2011-10-14 03:14:07,943 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: 354285kB of 354285kB
2011-10-14 03:14:07,943 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: 354285kB of 354285kB
2011-10-14 03:14:07,943 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: 354285kB of 354285kB
2011-10-14 03:14:07,944 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: Setting metadata ({'icicle': 'fde71ff1-3b58-4e35-8232-13eafec8d159', 'uuid': 'c80f6f03-660d-420c-89fc-8162e3afa4c7', 'template': 'c9a1a0db-f578-4679-8bf9-61c500f15ebe', 'target_parameters': 'No target parameters for cloud type vsphere', 'object_type': 'target_image', 'target': 'vsphere', 'build': '1389bb35-b446-4e48-bfbc-23465378bf64'}) for http://localhost:9090/target_images/c80f6f03-660d-420c-89fc-8162e3afa4c7
2011-10-14 03:14:07,963 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(3651) Message: Image warehouse storage complete
2011-10-14 03:14:07,963 DEBUG imgfac.BuildJob.BuildAdaptor pid(3651) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 50 to 100
2011-10-14 03:14:07,965 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: Getting metadata (['latest_unpushed']) from http://localhost:9090/images/142afa18-7cb8-479d-bbad-adc32c623232
2011-10-14 03:14:07,967 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: Getting metadata (['latest_build']) from http://localhost:9090/images/142afa18-7cb8-479d-bbad-adc32c623232
2011-10-14 03:14:07,971 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(3651) Message: Setting metadata ({'latest_unpushed': '1389bb35-b446-4e48-bfbc-23465378bf64'}) for http://localhost:9090/images/142afa18-7cb8-479d-bbad-adc32c623232
2011-10-14 03:14:07,974 DEBUG imgfac.BuildJob.BuildAdaptor pid(3651) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to COMPLETED



[root@ibm-x3655-01 templates]# rpm -qa | egrep 'aeolus|iwhd|factory'
aeolus-conductor-doc-0.5.0-0.20111012134715git383de2f.fc15.noarch
aeolus-all-0.5.0-0.20111012134715git383de2f.fc15.noarch
rubygem-imagefactory-console-0.5.0-4.20110824113238gitd9debef.fc15.noarch
rubygem-aeolus-image-0.1.0-3.20111003170706git8f23238.fc15.noarch
rubygem-aeolus-cli-0.1.0-3.20111003133323git9451323.fc15.noarch
aeolus-configure-2.2.0-1.20111007151942git26136f2.fc15.noarch
imagefactory-0.7.0-1.fc15.noarch
aeolus-conductor-0.5.0-0.20111012134715git383de2f.fc15.noarch
aeolus-conductor-daemons-0.5.0-0.20111012134715git383de2f.fc15.noarch
imagefactory-jeosconf-ec2-fedora-0.1.0-1.fc15.noarch
iwhd-0.99.2.ee04.dirty-1.fc15.x86_64
imagefactory-jeosconf-ec2-rhel-0.1.0-1.fc15.noarch

Comment 6 errata-xmlrpc 2012-05-15 19:44:10 UTC

Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory, and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

http://rhn.redhat.com/errata/RHEA-2012-0592.html

Note You need to log in before you can comment on or make changes to this bug.