Bug 743528 - 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
Version: 1.0.0
Hardware: Unspecified
OS: Unspecified
unspecified
unspecified
Target Milestone: rc
Assignee: Chris Lalancette
QA Contact: wes hayutin
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2011-10-05 10:08 UTC by Aziza Karol
Modified: 2012-05-15 19:43 UTC (History)
9 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2012-05-15 19:43:49 UTC

Attachments (Terms of Use)
ss1 (4.55 KB, image/png)
2011-10-05 14:02 UTC, wes hayutin 		no flags Details
ss2 (4.55 KB, image/png)
2011-10-05 14:02 UTC, wes hayutin 		no flags Details
View All 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-05 10:08:04 UTC 
Description of problem:


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


How reproducible:
[root@kvm-guest-03 templates]# aeolus-image build --target vsphere  --template  fedora15_template.tpl

imagefactory.log
2011-10-05 09:32:13,127 DEBUG oz.Guest.FedoraGuest pid(1618) Message: Syncing
2011-10-05 09:32:13,271 DEBUG oz.Guest.FedoraGuest pid(1618) Message: Unmounting all
2011-10-05 09:32:13,412 DEBUG oz.Guest.FedoraGuest pid(1618) Message: Killing guestfs subprocess
2011-10-05 09:32:13,432 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(1618) Message: Exception caught in ImageFactory
2011-10-05 09:32:13,452 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(1618) Message: Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/imgfac/builders/Fedora_vsphere_Builder.py", line 105, in build_upload
    self.output_descriptor = guest.customize_and_generate_icicle(libvirt_xml)
  File "/usr/lib/python2.7/site-packages/oz/RedHat.py", line 1032, in customize_and_generate_icicle
    return self._internal_customize(libvirt_xml, True)
  File "/usr/lib/python2.7/site-packages/oz/RedHat.py", line 1005, in _internal_customize
    guestaddr = self._wait_for_guest_boot(libvirt_dom)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 983, in _wait_for_guest_boot
    raise oz.OzException.OzException("Timed out waiting for guest to boot")
OzException: Timed out waiting for guest to boot

2011-10-05 09:32:13,459 INFO oz.Guest.FedoraGuest pid(1618) Message: Cleaning up guest named Fedora 15-3c47a394-6deb-4bdc-b81a-2c41b059162f
2011-10-05 09:32:13,503 DEBUG imgfac.BuildJob.BuildAdaptor pid(1618) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to FAILED
2011-10-05 09:32:13,508 INFO oz.Guest.FedoraGuest pid(1618) Message: Cleaning up after install
2011-10-05 09:32:13,513 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(1618) Message: Exception caught in ImageFactory
2011-10-05 09:32:13,513 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(1618) 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 105, in build_upload
    self.output_descriptor = guest.customize_and_generate_icicle(libvirt_xml)
  File "/usr/lib/python2.7/site-packages/oz/RedHat.py", line 1032, in customize_and_generate_icicle
    return self._internal_customize(libvirt_xml, True)
  File "/usr/lib/python2.7/site-packages/oz/RedHat.py", line 1005, in _internal_customize
    guestaddr = self._wait_for_guest_boot(libvirt_dom)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 983, in _wait_for_guest_boot
    raise oz.OzException.OzException("Timed out waiting for guest to boot")
OzException: Timed out waiting for guest to boot

2011-10-05 09:32:13,518 DEBUG imgfac.BuildJob.BuildAdaptor pid(1618) 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-05 08:20:12,580 DEBUG oz.Guest.FedoraGuest pid(1618) Message: Waiting for Fedora 15-d1f55b9e-408a-42e2-adde-d005c03dbff1 to finish installing, 3300/3600
2011-10-05 08:20:12,855 INFO oz.Guest.FedoraGuest pid(1618) Message: Cleaning up guest named Fedora 15-d1f55b9e-408a-42e2-adde-d005c03dbff1
2011-10-05 08:20:13,445 INFO oz.Guest.FedoraGuest pid(1618) Message: Cleaning up after install
2011-10-05 08:20:13,474 DEBUG imgfac.builders.BaseBuilder.Fedora_rhevm_Builder pid(1618) Message: Exception caught in ImageFactory
2011-10-05 08:20:13,475 DEBUG imgfac.builders.BaseBuilder.Fedora_rhevm_Builder pid(1618) 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 1390, in install
    return self._do_install(timeout, force, 0)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 1372, in _do_install
    self._wait_for_install_finish(dom, timeout)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 515, in _wait_for_install_finish
    raise oz.OzException.OzException(exc_str)
OzException: No disk activity in 300 seconds, failing.  Check screenshot at /Fedora 15-d1f55b9e-408a-42e2-adde-d005c03dbff1-1317817212.61.png for more detail

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





Additional info:
[root@kvm-guest-03 templates]# rpm -qa | egrep 'aeolus|imagefactory|iwhd'
rubygem-aeolus-image-0.1.0-3.20111003170706git8f23238.fc15.noarch
aeolus-conductor-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
aeolus-all-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
rubygem-imagefactory-console-0.5.0-4.20110824113238gitd9debef.fc15.noarch
aeolus-configure-2.0.2-4.20111004160858gitaf7e59a.fc15.noarch
aeolus-conductor-doc-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
aeolus-conductor-daemons-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
rubygem-aeolus-cli-0.1.0-3.20111003133323git9451323.fc15.noarch
iwhd-0.98.15.z3-2.fc15.x86_64
imagefactory-0.7.0-1.fc15.noarch
Comment 2 wes hayutin 2011-10-05 12:27:00 UTC 
CRAP.. recreated..


2011-10-05 08:14:33,579 DEBUG imgfac.qmfagent.ImageFactoryAgent.ImageFactoryAgent pid(5926) Message: Method called: name = build_image 
 args = {'image': '', 'build': '', 'template': ' <template>\n   <name>RHEL_VMWareTools</name>\n   <os>    \n    <name>RHEL-6</name>    \n     <version>1</version>\n     <arch>x86_64</arch>\n     <rootpw>customPassword</rootpw>\n     <install type="url">\n       <url>http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/</url>\n     </install>\n   </os>\n   <description>RHEL61 x86_64 and VMWareTools</description>\n  <repositories>\n     <repository name=\'vmwareTools\'>\n       <url>http://packages.vmware.com/tools/esx/4.1u1/rhel6/x86_64/</url>\n      <signed>False</signed>\n     </repository>\n  </repositories>\n   <packages>\n     <package name=\'vmware-open-vm-tools\'/> \n   </packages>\n </template>\n', 'targets': ['vsphere']} 
 handle = <cqmf2.AgentEvent; proxy of <Swig Object of type 'qmf::AgentEvent *' at 0x2b6c870> > 
 addr = redhat.com:imagefactory:9b8f2737-ce41-4392-903d-238c8ce47692:image_factory 
 subtypes = {} 
 userId = anonymous
2011-10-05 08:14:33,579 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5926) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-05 08:14:33,581 INFO imgfac.ImageWarehouse.ImageWarehouse pid(5926) Message: Creating a bucket returned status 500.
2011-10-05 08:14:33,584 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5926) Message: Setting metadata ({'object_type': 'image', 'uuid': 'ac8401a9-f1d9-42ef-bd52-5b794d9f03f7'}) for http://localhost:9090/images/ac8401a9-f1d9-42ef-bd52-5b794d9f03f7
2011-10-05 08:14:33,588 INFO imgfac.ImageWarehouse.ImageWarehouse pid(5926) Message: Creating a bucket returned status 500.
2011-10-05 08:14:33,590 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5926) Message: Setting metadata ({'image': 'ac8401a9-f1d9-42ef-bd52-5b794d9f03f7', 'object_type': 'build', 'uuid': 'f10c19bf-3d04-442c-a3ed-da46989d96c3'}) for http://localhost:9090/builds/f10c19bf-3d04-442c-a3ed-da46989d96c3
2011-10-05 08:14:33,595 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5926) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-05 08:14:33,595 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(5926) Message: build_upload() called on Fedora_vsphere_Builder...
2011-10-05 08:14:33,596 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(5926) Message: Building for target vsphere with warehouse config http://localhost:9090/
2011-10-05 08:14:33,596 DEBUG imgfac.BuildJob.BuildAdaptor pid(5926) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from NEW to BUILDING
2011-10-05 08:14:33,598 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(5926) Message: Found neither a call-time config nor a config file - doing nothing
2011-10-05 08:14:33,612 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: libvirt bridge name is virbr0
2011-10-05 08:14:33,771 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Libvirt type is kvm
2011-10-05 08:14:34,312 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Original URL http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/ resolved to http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/
2011-10-05 08:14:34,313 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Name: RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a, UUID: 67dfaa37-8cbc-4367-99bf-a43a55ea2ad7
2011-10-05 08:14:34,313 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: MAC: 52:54:00:43:52:06, distro: RHEL-6
2011-10-05 08:14:34,313 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: update: 1, arch: x86_64, diskimage: /var/lib/imagefactory/images/RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a.dsk
2011-10-05 08:14:34,313 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: nicmodel: virtio, clockoffset: utc
2011-10-05 08:14:34,313 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: mousetype: ps2, disk_bus: virtio, disk_dev: vda
2011-10-05 08:14:34,314 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: icicletmp: /var/lib/oz/icicletmp/RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a, listen_port: 40130
2011-10-05 08:14:34,314 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Original ISO path: /var/lib/oz/isos/RHEL-61x86_64-url.iso
2011-10-05 08:14:34,314 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Modified ISO cache: /var/lib/oz/isos/RHEL-61x86_64-url-oz.iso
2011-10-05 08:14:34,314 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Output ISO path: /var/lib/imagefactory/images/RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a-url-oz.iso
2011-10-05 08:14:34,314 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: ISO content path: /var/lib/oz/isocontent/RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a-url
2011-10-05 08:14:34,315 INFO oz.Guest.RHEL6Guest pid(5926) Message: Cleaning up guest named RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a
2011-10-05 08:14:34,323 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Installtype is URL, trying to do direct kernel boot
2011-10-05 08:14:34,324 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Going to write treeinfo to /var/lib/oz/icicletmp/RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a/treeinfo
2011-10-05 08:14:34,324 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Trying to get treeinfo from http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os//.treeinfo
2011-10-05 08:14:34,900 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Got treeinfo, parsing
2011-10-05 08:14:34,902 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Returning kernel images/pxeboot/vmlinuz and initrd images/pxeboot/initrd.img
2011-10-05 08:14:34,902 INFO oz.Guest.RHEL6Guest pid(5926) Message: Fetching the original media
2011-10-05 08:14:34,903 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Attempting to get the lock for /var/lib/oz/kernels/RHEL-61x86_64-kernel
2011-10-05 08:14:34,903 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Got the lock, doing the download
2011-10-05 08:14:35,332 INFO oz.Guest.RHEL6Guest pid(5926) Message: Fetching the original install media from http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/images/pxeboot/vmlinuz
2011-10-05 08:14:35,747 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 0kB of 3790kB
2011-10-05 08:14:44,484 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 3790kB of 3790kB
2011-10-05 08:14:44,484 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 3790kB of 3790kB
2011-10-05 08:14:44,484 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 3790kB of 3790kB
2011-10-05 08:14:44,484 INFO oz.Guest.RHEL6Guest pid(5926) Message: Fetching the original media
2011-10-05 08:14:44,484 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Attempting to get the lock for /var/lib/oz/kernels/RHEL-61x86_64-ramdisk
2011-10-05 08:14:44,485 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Got the lock, doing the download
2011-10-05 08:14:44,852 INFO oz.Guest.RHEL6Guest pid(5926) Message: Fetching the original install media from http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/images/pxeboot/initrd.img
2011-10-05 08:14:45,267 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 0kB of 34673kB
2011-10-05 08:16:06,168 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 10240kB of 34673kB
2011-10-05 08:17:11,072 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 20480kB of 34673kB
2011-10-05 08:18:10,019 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 30722kB of 34673kB
2011-10-05 08:18:21,722 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 34673kB of 34673kB
2011-10-05 08:18:21,722 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 34673kB of 34673kB
2011-10-05 08:18:21,723 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: 34673kB of 34673kB
2011-10-05 08:18:21,737 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Putting the kickstart in place
2011-10-05 08:18:21,743 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Writing cpio to /var/lib/oz/icicletmp/RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a/extra.cpio
2011-10-05 08:18:21,899 DEBUG imgfac.BuildJob.BuildAdaptor pid(5926) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 0 to 10
2011-10-05 08:18:21,900 INFO oz.Guest.RHEL6Guest pid(5926) Message: Generating 10GB diskimage for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a
2011-10-05 08:18:21,900 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(5926) Message: Doing base install via Oz
2011-10-05 08:18:21,900 INFO oz.Guest.RHEL6Guest pid(5926) Message: Running install for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a
2011-10-05 08:18:21,900 INFO oz.Guest.RHEL6Guest pid(5926) Message: Generate XML for guest RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a with bootdev None
2011-10-05 08:18:21,901 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Generated XML:
<?xml version="1.0"?>
<domain type="kvm">
  <name>RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a</name>
  <memory>1048576</memory>
  <currentMemory>1048576</currentMemory>
  <uuid>67dfaa37-8cbc-4367-99bf-a43a55ea2ad7</uuid>
  <clock offset="utc"/>
  <vcpu>1</vcpu>
  <features>
    <acpi/>
    <apic/>
    <pae/>
  </features>
  <os>
    <type>hvm</type>
    <kernel>/var/lib/imagefactory/images/RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a-kernel</kernel>
    <initrd>/var/lib/imagefactory/images/RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a-ramdisk</initrd>
    <cmdline>method=http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/ ks=file:/ks.cfg</cmdline>
  </os>
  <on_poweroff>destroy</on_poweroff>
  <on_reboot>destroy</on_reboot>
  <on_crash>destroy</on_crash>
  <devices>
    <console device="pty"/>
    <graphics port="-1" type="vnc"/>
    <interface type="bridge">
      <source bridge="virbr0"/>
      <mac address="52:54:00:43:52:06"/>
      <model type="virtio"/>
    </interface>
    <input bus="ps2" type="mouse"/>
    <console type="pty">
      <target port="0"/>
    </console>
    <serial type="tcp">
      <source mode="bind" host="127.0.0.1" service="40130"/>
      <protocol type="raw"/>
      <target port="1"/>
    </serial>
    <disk device="disk" type="file">
      <target dev="vda" bus="virtio"/>
      <source file="/var/lib/imagefactory/images/base-image-78b78f9f-3350-4607-baac-a21482e2a40a.dsk"/>
    </disk>
  </devices>
</domain>

2011-10-05 08:18:22,869 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3600/3600
2011-10-05 08:18:32,897 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3590/3600
2011-10-05 08:18:42,938 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3580/3600
2011-10-05 08:18:52,974 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3570/3600
2011-10-05 08:19:03,001 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3560/3600
2011-10-05 08:19:13,028 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3550/3600
2011-10-05 08:19:23,056 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3540/3600
2011-10-05 08:19:33,084 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3530/3600
2011-10-05 08:19:43,111 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3520/3600
2011-10-05 08:19:53,139 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3510/3600
2011-10-05 08:20:03,165 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3500/3600
2011-10-05 08:20:13,193 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3490/3600
2011-10-05 08:20:23,221 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3480/3600
2011-10-05 08:20:33,249 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3470/3600
2011-10-05 08:20:43,276 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3460/3600
2011-10-05 08:20:53,300 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3450/3600
2011-10-05 08:21:03,326 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3440/3600
2011-10-05 08:21:13,354 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3430/3600
2011-10-05 08:21:23,380 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3420/3600
2011-10-05 08:21:33,405 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3410/3600
2011-10-05 08:21:43,433 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3400/3600
2011-10-05 08:21:53,458 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3390/3600
2011-10-05 08:22:03,486 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3380/3600
2011-10-05 08:22:13,512 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3370/3600
2011-10-05 08:22:23,538 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3360/3600
2011-10-05 08:22:33,563 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3350/3600
2011-10-05 08:22:43,589 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3340/3600
2011-10-05 08:22:53,617 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3330/3600
2011-10-05 08:23:03,645 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3320/3600
2011-10-05 08:23:13,670 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3310/3600
2011-10-05 08:23:23,697 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3300/3600
2011-10-05 08:23:33,724 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3290/3600
2011-10-05 08:23:43,750 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3280/3600
2011-10-05 08:23:53,777 DEBUG oz.Guest.RHEL6Guest pid(5926) Message: Waiting for RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a to finish installing, 3270/3600
2011-10-05 08:24:03,546 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(5926) Message: Exception caught in ImageFactory
2011-10-05 08:24:03,561 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(5926) 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 1390, in install
    return self._do_install(timeout, force, 0)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 1372, in _do_install
    self._wait_for_install_finish(dom, timeout)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 515, in _wait_for_install_finish
    raise oz.OzException.OzException(exc_str)
OzException: No disk activity in 300 seconds, failing.  Check screenshot at /RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a-1317817442.86.png for more detail

2011-10-05 08:24:03,562 INFO oz.Guest.RHEL6Guest pid(5926) Message: Cleaning up guest named RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a
2011-10-05 08:24:03,895 DEBUG imgfac.BuildJob.BuildAdaptor pid(5926) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to FAILED
2011-10-05 08:24:03,898 INFO oz.Guest.RHEL6Guest pid(5926) Message: Cleaning up after install
2011-10-05 08:24:03,930 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(5926) Message: Exception caught in ImageFactory
2011-10-05 08:24:03,931 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(5926) 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 1390, in install
    return self._do_install(timeout, force, 0)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 1372, in _do_install
    self._wait_for_install_finish(dom, timeout)
  File "/usr/lib/python2.7/site-packages/oz/Guest.py", line 515, in _wait_for_install_finish
    raise oz.OzException.OzException(exc_str)
OzException: No disk activity in 300 seconds, failing.  Check screenshot at /RHEL_VMWareTools-78b78f9f-3350-4607-baac-a21482e2a40a-1317817442.86.png for more detail

2011-10-05 08:24:03,931 DEBUG imgfac.BuildJob.BuildAdaptor pid(5926) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from FAILED to FAILED


[root@dell-pe1950-03 ~]# rpm -qa | egrep 'aeolus|iwhd|factory'
aeolus-conductor-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
rubygem-aeolus-image-0.1.0-3.20111003170706git8f23238.fc15.noarch
rubygem-aeolus-cli-0.1.0-3.20111003133323git9451323.fc15.noarch
aeolus-conductor-daemons-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
iwhd-0.98.15.z3-2.fc15.x86_64
imagefactory-0.7.0-1.fc15.noarch
aeolus-all-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
aeolus-configure-2.0.2-4.20111004160858gitaf7e59a.fc15.noarch
aeolus-conductor-doc-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
rubygem-imagefactory-console-0.5.0-4.20110824113238gitd9debef.fc15.noarch
Comment 3 wes hayutin 2011-10-05 12:36:39 UTC 
ok.. weird.. build works on a different box at the same level of rpms.. not this box was upgraded and not a fresh install. I'll need Chris to look at both boxes to try and figure this out...


This build works.. same exact template..


2011-10-05 08:20:54,240 DEBUG imgfac.qmfagent.ImageFactoryAgent.ImageFactoryAgent pid(2871) Message: Method called: name = build_image 
 args = {'image': '', 'build': '', 'template': ' <template>\n   <name>RHEL_VMWareTools</name>\n   <os>    \n     <name>RHEL-6</name>    \n     <version>1</version>\n    <arch>x86_64</arch>\n     <rootpw>customPassword</rootpw>\n    <install type="url">\n      <url>http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/</url>\n     </install>\n   </os>\n   <description>RHEL61 x86_64 and VMWareTools</description>\n  <repositories>\n     <repository name=\'vmwareTools\'>\n       <url>http://packages.vmware.com/tools/esx/4.1u1/rhel6/x86_64/</url>\n       <signed>False</signed>\n     </repository>\n  </repositories>\n   <packages>\n     <package name=\'vmware-open-vm-tools\'/> \n   </packages>\n </template>\n', 'targets': ['vsphere']} 
 handle = <cqmf2.AgentEvent; proxy of <Swig Object of type 'qmf::AgentEvent *' at 0x2596690> > 
 addr = redhat.com:imagefactory:27f1d381-253c-4d54-b94d-42757d27cdde:image_factory 
 subtypes = {} 
 userId = anonymous
2011-10-05 08:20:54,241 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-05 08:20:54,241 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-05 08:20:54,264 INFO imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Creating a bucket returned status 500.
2011-10-05 08:20:54,332 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Setting metadata ({'object_type': 'image', 'uuid': '6f234adc-76cd-409a-8825-e5491adf6091'}) for http://localhost:9090/images/6f234adc-76cd-409a-8825-e5491adf6091
2011-10-05 08:20:54,341 INFO imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Creating a bucket returned status 500.
2011-10-05 08:20:54,347 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Setting metadata ({'image': '6f234adc-76cd-409a-8825-e5491adf6091', 'object_type': 'build', 'uuid': '9412c8ec-9362-4699-b6b4-a6c1d333e32c'}) for http://localhost:9090/builds/9412c8ec-9362-4699-b6b4-a6c1d333e32c
2011-10-05 08:20:55,754 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-05 08:20:55,755 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: build_upload() called on Fedora_vsphere_Builder...
2011-10-05 08:20:55,756 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Building for target vsphere with warehouse config http://localhost:9090/
2011-10-05 08:20:55,757 DEBUG imgfac.BuildJob.BuildAdaptor pid(2871) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from NEW to BUILDING
2011-10-05 08:20:55,761 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Found neither a call-time config nor a config file - doing nothing
2011-10-05 08:20:55,842 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: libvirt bridge name is virbr0
2011-10-05 08:20:55,974 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Libvirt type is kvm
2011-10-05 08:20:56,115 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Original URL http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/ resolved to http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/
2011-10-05 08:20:56,116 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Name: RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b, UUID: 25843b56-efd6-4e58-81b9-c652bb91dca4
2011-10-05 08:20:56,116 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: MAC: 52:54:00:22:8c:3a, distro: RHEL-6
2011-10-05 08:20:56,116 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: update: 1, arch: x86_64, diskimage: /var/lib/imagefactory/images/RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b.dsk
2011-10-05 08:20:56,117 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: nicmodel: virtio, clockoffset: utc
2011-10-05 08:20:56,117 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: mousetype: ps2, disk_bus: virtio, disk_dev: vda
2011-10-05 08:20:56,117 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: icicletmp: /var/lib/oz/icicletmp/RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b, listen_port: 28774
2011-10-05 08:20:56,117 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Original ISO path: /var/lib/oz/isos/RHEL-61x86_64-url.iso
2011-10-05 08:20:56,118 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Modified ISO cache: /var/lib/oz/isos/RHEL-61x86_64-url-oz.iso
2011-10-05 08:20:56,118 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Output ISO path: /var/lib/imagefactory/images/RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b-url-oz.iso
2011-10-05 08:20:56,118 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: ISO content path: /var/lib/oz/isocontent/RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b-url
2011-10-05 08:20:56,118 INFO oz.Guest.RHEL6Guest pid(2871) Message: Cleaning up guest named RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b
2011-10-05 08:20:56,130 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Installtype is URL, trying to do direct kernel boot
2011-10-05 08:20:56,136 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Going to write treeinfo to /var/lib/oz/icicletmp/RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b/treeinfo
2011-10-05 08:20:56,137 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Trying to get treeinfo from http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os//.treeinfo
2011-10-05 08:20:56,311 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Got treeinfo, parsing
2011-10-05 08:20:56,315 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Returning kernel images/pxeboot/vmlinuz and initrd images/pxeboot/initrd.img
2011-10-05 08:20:56,315 INFO oz.Guest.RHEL6Guest pid(2871) Message: Fetching the original media
2011-10-05 08:20:56,316 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Attempting to get the lock for /var/lib/oz/kernels/RHEL-61x86_64-kernel
2011-10-05 08:20:56,316 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Got the lock, doing the download
2011-10-05 08:20:56,460 INFO oz.Guest.RHEL6Guest pid(2871) Message: Original install media available, using cached version
2011-10-05 08:20:56,461 INFO oz.Guest.RHEL6Guest pid(2871) Message: Fetching the original media
2011-10-05 08:20:56,461 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Attempting to get the lock for /var/lib/oz/kernels/RHEL-61x86_64-ramdisk
2011-10-05 08:20:56,461 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Got the lock, doing the download
2011-10-05 08:20:56,600 INFO oz.Guest.RHEL6Guest pid(2871) Message: Original install media available, using cached version
2011-10-05 08:20:56,671 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Putting the kickstart in place
2011-10-05 08:20:56,689 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Writing cpio to /var/lib/oz/icicletmp/RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b/extra.cpio
2011-10-05 08:20:57,181 DEBUG imgfac.BuildJob.BuildAdaptor pid(2871) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 0 to 10
2011-10-05 08:20:57,184 INFO oz.Guest.RHEL6Guest pid(2871) Message: Generating 10GB diskimage for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b
2011-10-05 08:20:57,185 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Doing base install via Oz
2011-10-05 08:20:57,185 INFO oz.Guest.RHEL6Guest pid(2871) Message: Running install for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b
2011-10-05 08:20:57,185 INFO oz.Guest.RHEL6Guest pid(2871) Message: Generate XML for guest RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b with bootdev None
2011-10-05 08:20:57,187 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Generated XML:
<?xml version="1.0"?>
<domain type="kvm">
  <name>RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b</name>
  <memory>1048576</memory>
  <currentMemory>1048576</currentMemory>
  <uuid>25843b56-efd6-4e58-81b9-c652bb91dca4</uuid>
  <clock offset="utc"/>
  <vcpu>1</vcpu>
  <features>
    <acpi/>
    <apic/>
    <pae/>
  </features>
  <os>
    <type>hvm</type>
    <kernel>/var/lib/imagefactory/images/RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b-kernel</kernel>
    <initrd>/var/lib/imagefactory/images/RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b-ramdisk</initrd>
    <cmdline>method=http://download.devel.redhat.com/released/RHEL-6-Server/6.1/x86_64/os/ ks=file:/ks.cfg</cmdline>
  </os>
  <on_poweroff>destroy</on_poweroff>
  <on_reboot>destroy</on_reboot>
  <on_crash>destroy</on_crash>
  <devices>
    <console device="pty"/>
    <graphics port="-1" type="vnc"/>
    <interface type="bridge">
      <source bridge="virbr0"/>
      <mac address="52:54:00:22:8c:3a"/>
      <model type="virtio"/>
    </interface>
    <input bus="ps2" type="mouse"/>
    <console type="pty">
      <target port="0"/>
    </console>
    <serial type="tcp">
      <source mode="bind" host="127.0.0.1" service="28774"/>
      <protocol type="raw"/>
      <target port="1"/>
    </serial>
    <disk device="disk" type="file">
      <target dev="vda" bus="virtio"/>
      <source file="/var/lib/imagefactory/images/base-image-5d2283e2-be10-4e85-816c-b69e1d02a25b.dsk"/>
    </disk>
  </devices>
</domain>

2011-10-05 08:20:57,874 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3600/3600
2011-10-05 08:21:07,919 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3590/3600
2011-10-05 08:21:17,964 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3580/3600
2011-10-05 08:21:28,006 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3570/3600
2011-10-05 08:21:38,048 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3560/3600
2011-10-05 08:21:48,095 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3550/3600
2011-10-05 08:21:58,137 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3540/3600
2011-10-05 08:22:08,180 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3530/3600
2011-10-05 08:22:18,220 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3520/3600
2011-10-05 08:22:28,264 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3510/3600
2011-10-05 08:22:38,307 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3500/3600
2011-10-05 08:22:48,349 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3490/3600
2011-10-05 08:22:58,394 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3480/3600
2011-10-05 08:23:08,438 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3470/3600
2011-10-05 08:23:18,481 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3460/3600
2011-10-05 08:23:28,523 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3450/3600
2011-10-05 08:23:38,566 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3440/3600
2011-10-05 08:23:48,611 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3430/3600
2011-10-05 08:23:58,654 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3420/3600
2011-10-05 08:24:08,697 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3410/3600
2011-10-05 08:24:18,739 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3400/3600
2011-10-05 08:24:28,783 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3390/3600
2011-10-05 08:24:38,825 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3380/3600
2011-10-05 08:24:48,865 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3370/3600
2011-10-05 08:24:58,908 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3360/3600
2011-10-05 08:25:08,950 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3350/3600
2011-10-05 08:25:18,992 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3340/3600
2011-10-05 08:25:29,037 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3330/3600
2011-10-05 08:25:39,080 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3320/3600
2011-10-05 08:25:49,123 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3310/3600
2011-10-05 08:25:59,165 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3300/3600
2011-10-05 08:26:09,208 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3290/3600
2011-10-05 08:26:19,249 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3280/3600
2011-10-05 08:26:29,290 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3270/3600
2011-10-05 08:26:39,332 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3260/3600
2011-10-05 08:26:49,376 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3250/3600
2011-10-05 08:26:59,417 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3240/3600
2011-10-05 08:27:09,461 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3230/3600
2011-10-05 08:27:19,504 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3220/3600
2011-10-05 08:27:29,547 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3210/3600
2011-10-05 08:27:39,588 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3200/3600
2011-10-05 08:27:49,629 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3190/3600
2011-10-05 08:27:59,671 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3180/3600
2011-10-05 08:28:09,714 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3170/3600
2011-10-05 08:28:19,754 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3160/3600
2011-10-05 08:28:29,794 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3150/3600
2011-10-05 08:28:39,833 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3140/3600
2011-10-05 08:28:49,873 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3130/3600
2011-10-05 08:28:59,915 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3120/3600
2011-10-05 08:29:09,958 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3110/3600
2011-10-05 08:29:19,997 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3100/3600
2011-10-05 08:29:30,040 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3090/3600
2011-10-05 08:29:40,081 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3080/3600








2011-10-05 08:29:50,123 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3070/3600
2011-10-05 08:30:00,164 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3060/3600
2011-10-05 08:30:10,205 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3050/3600
2011-10-05 08:30:20,245 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to finish installing, 3040/3600
2011-10-05 08:30:28,587 INFO oz.Guest.RHEL6Guest pid(2871) Message: Install of RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b succeeded
2011-10-05 08:30:28,587 INFO oz.Guest.RHEL6Guest pid(2871) Message: Generate XML for guest RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b with bootdev hd
2011-10-05 08:30:28,589 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Generated XML:
<?xml version="1.0"?>
<domain type="kvm">
  <name>RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b</name>
  <memory>1048576</memory>
  <currentMemory>1048576</currentMemory>
  <uuid>25843b56-efd6-4e58-81b9-c652bb91dca4</uuid>
  <clock offset="utc"/>
  <vcpu>1</vcpu>
  <features>
    <acpi/>
    <apic/>
    <pae/>
  </features>
  <os>
    <type>hvm</type>
    <boot dev="hd"/>
  </os>
  <on_poweroff>destroy</on_poweroff>
  <on_reboot>destroy</on_reboot>
  <on_crash>destroy</on_crash>
  <devices>
    <console device="pty"/>
    <graphics port="-1" type="vnc"/>
    <interface type="bridge">
      <source bridge="virbr0"/>
      <mac address="52:54:00:22:8c:3a"/>
      <model type="virtio"/>
    </interface>
    <input bus="ps2" type="mouse"/>
    <console type="pty">
      <target port="0"/>
    </console>
    <serial type="tcp">
      <source mode="bind" host="127.0.0.1" service="28774"/>
      <protocol type="raw"/>
      <target port="1"/>
    </serial>
    <disk device="disk" type="file">
      <target dev="vda" bus="virtio"/>
      <source file="/var/lib/imagefactory/images/base-image-5d2283e2-be10-4e85-816c-b69e1d02a25b.dsk"/>
    </disk>
  </devices>
</domain>

2011-10-05 08:30:28,589 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Base install complete - Doing customization and ICICLE generation
2011-10-05 08:30:28,589 DEBUG imgfac.BuildJob.BuildAdaptor pid(2871) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 10 to 30
2011-10-05 08:30:28,590 INFO oz.Guest.RHEL6Guest pid(2871) Message: Customizing image
2011-10-05 08:30:28,590 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Generated XML:
<?xml version="1.0"?>
<domain type="kvm">
  <name>RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b</name>
  <memory>1048576</memory>
  <currentMemory>1048576</currentMemory>
  <uuid>25843b56-efd6-4e58-81b9-c652bb91dca4</uuid>
  <clock offset="utc"/>
  <vcpu>1</vcpu>
  <features>
    <acpi/>
    <apic/>
    <pae/>
  </features>
  <os>
    <type>hvm</type>
    <boot dev="hd"/>
  </os>
  <on_poweroff>destroy</on_poweroff>
  <on_reboot>destroy</on_reboot>
  <on_crash>destroy</on_crash>
  <devices>
    <console device="pty"/>
    <graphics port="-1" type="vnc"/>
    <interface type="bridge">
      <source bridge="virbr0"/>
      <mac address="52:54:00:22:8c:3a"/>
      <model type="virtio"/>
    </interface>
    <input bus="ps2" type="mouse"/>
    <console type="pty">
      <target port="0"/>
    </console>
    
    <disk device="disk" type="file">
      <target dev="vda" bus="virtio"/>
      <source file="/var/lib/imagefactory/images/base-image-5d2283e2-be10-4e85-816c-b69e1d02a25b.dsk"/>
    </disk>
  <serial type="tcp"><source mode="bind" host="127.0.0.1" service="28774"/><protocol type="raw"/><target port="1"/></serial></devices>
</domain>

2011-10-05 08:30:28,590 INFO oz.Guest.RHEL6Guest pid(2871) Message: Collection Setup
2011-10-05 08:30:28,592 INFO oz.Guest.RHEL6Guest pid(2871) Message: Setting up guestfs handle for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b
2011-10-05 08:30:28,592 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Adding disk image /var/lib/imagefactory/images/base-image-5d2283e2-be10-4e85-816c-b69e1d02a25b.dsk
2011-10-05 08:30:28,600 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Launching guestfs
2011-10-05 08:31:06,580 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Inspecting guest OS
2011-10-05 08:31:08,125 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Getting mountpoints
2011-10-05 08:31:08,126 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Root device: /dev/VolGroup00/LogVol00
2011-10-05 08:31:08,300 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Step 1: Uploading ssh keys
2011-10-05 08:31:08,341 INFO oz.Guest.RHEL6Guest pid(2871) Message: Generating new openssh key
2011-10-05 08:31:08,349 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Step 2: setup sshd
2011-10-05 08:31:08,448 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Step 3: Open up the firewall
2011-10-05 08:31:08,450 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Step 4: Guest announcement
2011-10-05 08:31:08,487 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Step 5: Set SELinux to permissive mode
2011-10-05 08:31:08,503 INFO oz.Guest.RHEL6Guest pid(2871) Message: Cleaning up guestfs handle for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b
2011-10-05 08:31:08,504 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Syncing
2011-10-05 08:31:08,673 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Unmounting all
2011-10-05 08:31:08,711 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Killing guestfs subprocess
2011-10-05 08:31:09,322 INFO oz.Guest.RHEL6Guest pid(2871) Message: Waiting for guest RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to boot
2011-10-05 08:31:09,332 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for guest RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to boot, 300/300
2011-10-05 08:31:19,367 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for guest RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to boot, 290/300
2011-10-05 08:31:30,514 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for guest RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to boot, 280/300
2011-10-05 08:31:41,501 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for guest RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to boot, 270/300
2011-10-05 08:31:51,543 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for guest RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to boot, 260/300
2011-10-05 08:32:01,572 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for guest RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to boot, 250/300
2011-10-05 08:32:03,960 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: IP address of guest is 192.168.122.116
2011-10-05 08:32:03,961 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Installing additional repository files
2011-10-05 08:32:05,470 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Installing custom packages
2011-10-05 08:32:22,834 INFO oz.Guest.RHEL6Guest pid(2871) Message: Uploading custom files
2011-10-05 08:32:22,835 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Running custom commands
2011-10-05 08:32:22,835 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Syncing
2011-10-05 08:32:24,953 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Generating ICICLE
2011-10-05 08:32:25,846 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Waiting for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b to shutdown, 60/60
2011-10-05 08:32:33,873 INFO oz.Guest.RHEL6Guest pid(2871) Message: Collection Teardown
2011-10-05 08:32:33,876 INFO oz.Guest.RHEL6Guest pid(2871) Message: Setting up guestfs handle for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b
2011-10-05 08:32:33,876 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Adding disk image /var/lib/imagefactory/images/base-image-5d2283e2-be10-4e85-816c-b69e1d02a25b.dsk
2011-10-05 08:32:33,877 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Launching guestfs
2011-10-05 08:32:38,543 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Inspecting guest OS
2011-10-05 08:32:40,146 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Getting mountpoints
2011-10-05 08:32:40,147 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Root device: /dev/VolGroup00/LogVol00
2011-10-05 08:32:40,317 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Teardown step 1
2011-10-05 08:32:40,317 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Resetting authorized_keys
2011-10-05 08:32:40,363 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Teardown step 2
2011-10-05 08:32:40,363 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Resetting sshd_config
2011-10-05 08:32:40,405 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Resetting sshd service
2011-10-05 08:32:40,465 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Teardown step 3
2011-10-05 08:32:40,465 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Resetting iptables rules
2011-10-05 08:32:40,467 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Teardown step 4
2011-10-05 08:32:40,468 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Resetting announcement to host
2011-10-05 08:32:40,470 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Removing reportip
2011-10-05 08:32:40,472 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Resetting crond service
2011-10-05 08:32:40,488 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Teardown step 5
2011-10-05 08:32:40,506 INFO oz.Guest.RHEL6Guest pid(2871) Message: Cleaning up guestfs handle for RHEL_VMWareTools-5d2283e2-be10-4e85-816c-b69e1d02a25b
2011-10-05 08:32:40,507 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Syncing
2011-10-05 08:32:40,620 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Unmounting all
2011-10-05 08:32:40,656 DEBUG oz.Guest.RHEL6Guest pid(2871) Message: Killing guestfs subprocess
2011-10-05 08:32:40,755 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Customization and ICICLE generation complete
2011-10-05 08:32:40,755 DEBUG imgfac.BuildJob.BuildAdaptor pid(2871) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 30 to 50
2011-10-05 08:32:40,756 INFO oz.Guest.RHEL6Guest pid(2871) Message: Cleaning up after install
2011-10-05 08:32:40,774 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Generated disk image (/var/lib/imagefactory/images/base-image-5d2283e2-be10-4e85-816c-b69e1d02a25b.dsk)
2011-10-05 08:32:40,774 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Doing further Factory specific modification of Oz image
2011-10-05 08:32:40,774 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: init guestfs
2011-10-05 08:32:40,774 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: add input image
2011-10-05 08:32:40,774 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: launch guestfs
2011-10-05 08:32:45,591 INFO imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Creating cloud-info file indicating target (vsphere)
2011-10-05 08:32:45,617 INFO imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Updating rc.local with Audrey conditional
2011-10-05 08:32:47,902 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Removed HWADDR from image's /etc/sysconfig/network-scripts/ifcfg-eth0
2011-10-05 08:32:48,432 INFO imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Transforming image for use on VMWare
2011-10-05 08:32:48,432 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Converting raw kvm image (/var/lib/imagefactory/images/base-image-5d2283e2-be10-4e85-816c-b69e1d02a25b.dsk) to vmware stream-optimized image (/var/lib/imagefactory/images/vmware-image-5d2283e2-be10-4e85-816c-b69e1d02a25b.vmdk)
2011-10-05 08:34:03,419 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: VMWare stream conversion complete
2011-10-05 08:34:03,420 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Storing Fedora image at http://localhost:9090/...
2011-10-05 08:34:03,423 INFO imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Creating a bucket returned status 500.
2011-10-05 08:34:03,437 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Setting metadata ({'object_type': 'template', 'uuid': 'bdab9200-8a75-4a68-ac38-43e822b3e13e'}) for http://localhost:9090/templates/bdab9200-8a75-4a68-ac38-43e822b3e13e
2011-10-05 08:34:03,445 INFO imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Creating a bucket returned status 500.
2011-10-05 08:34:03,453 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Setting metadata ({'object_type': 'icicle', 'uuid': 'ca078b3a-0530-470f-8a34-a10addd3ce59'}) for http://localhost:9090/icicles/ca078b3a-0530-470f-8a34-a10addd3ce59
2011-10-05 08:34:03,461 INFO imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Creating a bucket returned status 500.
2011-10-05 08:34:03,463 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 0kB of 305730kB
2011-10-05 08:34:03,629 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 10240kB of 305730kB
2011-10-05 08:34:03,799 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 20480kB of 305730kB
2011-10-05 08:34:03,970 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 30720kB of 305730kB
2011-10-05 08:34:04,141 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 40960kB of 305730kB
2011-10-05 08:34:04,311 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 51200kB of 305730kB
2011-10-05 08:34:04,481 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 61440kB of 305730kB
2011-10-05 08:34:04,651 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 71680kB of 305730kB
2011-10-05 08:34:04,821 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 81920kB of 305730kB
2011-10-05 08:34:04,994 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 92160kB of 305730kB
2011-10-05 08:34:05,168 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 102400kB of 305730kB
2011-10-05 08:34:05,343 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 112640kB of 305730kB
2011-10-05 08:34:05,514 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 122880kB of 305730kB
2011-10-05 08:34:05,686 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 133120kB of 305730kB
2011-10-05 08:34:05,859 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 143360kB of 305730kB
2011-10-05 08:34:06,034 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 153600kB of 305730kB
2011-10-05 08:34:06,210 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 163840kB of 305730kB
2011-10-05 08:34:06,384 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 174080kB of 305730kB
2011-10-05 08:34:06,554 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 184320kB of 305730kB
2011-10-05 08:34:06,724 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 194560kB of 305730kB
2011-10-05 08:34:06,895 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 204800kB of 305730kB
2011-10-05 08:34:07,070 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 215040kB of 305730kB
2011-10-05 08:34:07,244 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 225280kB of 305730kB
2011-10-05 08:34:07,414 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 235520kB of 305730kB
2011-10-05 08:34:07,585 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 245760kB of 305730kB
2011-10-05 08:34:07,754 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 256000kB of 305730kB
2011-10-05 08:34:07,925 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 266240kB of 305730kB
2011-10-05 08:34:08,095 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 276480kB of 305730kB
2011-10-05 08:34:08,265 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 286720kB of 305730kB
2011-10-05 08:34:08,434 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 296960kB of 305730kB
2011-10-05 08:34:08,579 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 305730kB of 305730kB
2011-10-05 08:34:08,580 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 305730kB of 305730kB
2011-10-05 08:34:08,587 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 305730kB of 305730kB
2011-10-05 08:34:08,587 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 305730kB of 305730kB
2011-10-05 08:34:08,588 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: 305730kB of 305730kB
2011-10-05 08:34:08,588 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Setting metadata ({'icicle': 'ca078b3a-0530-470f-8a34-a10addd3ce59', 'uuid': '5d2283e2-be10-4e85-816c-b69e1d02a25b', 'template': 'bdab9200-8a75-4a68-ac38-43e822b3e13e', 'target_parameters': 'No target parameters for cloud type vsphere', 'object_type': 'target_image', 'target': 'vsphere', 'build': '9412c8ec-9362-4699-b6b4-a6c1d333e32c'}) for http://localhost:9090/target_images/5d2283e2-be10-4e85-816c-b69e1d02a25b
2011-10-05 08:34:08,610 DEBUG imgfac.builders.BaseBuilder.RHEL6_vsphere_Builder pid(2871) Message: Image warehouse storage complete
2011-10-05 08:34:08,611 DEBUG imgfac.BuildJob.BuildAdaptor pid(2871) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 50 to 100
2011-10-05 08:34:08,613 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Getting metadata (['latest_unpushed']) from http://localhost:9090/images/6f234adc-76cd-409a-8825-e5491adf6091
2011-10-05 08:34:08,615 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Getting metadata (['latest_build']) from http://localhost:9090/images/6f234adc-76cd-409a-8825-e5491adf6091
2011-10-05 08:34:08,619 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(2871) Message: Setting metadata ({'latest_unpushed': '9412c8ec-9362-4699-b6b4-a6c1d333e32c'}) for http://localhost:9090/images/6f234adc-76cd-409a-8825-e5491adf6091
2011-10-05 08:34:08,625 DEBUG imgfac.BuildJob.BuildAdaptor pid(2871) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to COMPLETED




[root@qeblade5 ~]# rpm -qa | egrep 'aeolus|iwhd|factory'
rubygem-imagefactory-console-0.5.0-4.20110824113238gitd9debef.fc15.noarch
aeolus-conductor-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
rubygem-aeolus-cli-0.1.0-3.20111003133323git9451323.fc15.noarch
aeolus-conductor-daemons-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
imagefactory-0.7.0-1.fc15.noarch
aeolus-all-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
iwhd-0.98.15.z3-2.fc15.x86_64
rubygem-aeolus-image-0.1.0-3.20111003170706git8f23238.fc15.noarch
aeolus-conductor-doc-0.4.0-0.20111004192348git2cf5ee6.fc15.noarch
aeolus-configure-2.0.2-4.20111004160858gitaf7e59a.fc15.noarch
[root@qeblade5 ~]#
Comment 4 Chris Lalancette 2011-10-05 13:27:51 UTC 
At the moment, I'm thinking this is because one of the boxes is in Boston and one is in Brno.  Since they are both fetching content from Raleigh, I think what is happening is that it is just taking too long to fetch the data across the Atlantic, and Oz is timing out.  Wes is testing out a patch now that monitors network activity along with disk activity to determine if a guest is "dead".
Comment 5 wes hayutin 2011-10-05 14:02:12 UTC 
Created attachment 526499 [details]
ss1

ok.. it looks like OZ is timeing out..
Comment 6 wes hayutin 2011-10-05 14:02:50 UTC 
Created attachment 526500 [details]
ss2

ok.. it looks like OZ is timing out..
Comment 7 wes hayutin 2011-10-05 14:04:34 UTC 
http://people.redhat.com/clalance/oz-0.8.0-0.20111005131726git205bcff.fc14.src.rpm

looks like Chris implemented something to check disk and network activity of the guest.. this fixes the issue..

Need this in the build
Comment 10 wes hayutin 2011-10-05 16:25:47 UTC 
the broken oz version.. I think was.. oz-0.7.0-2.fc15.noarch  see other rpm ver above
Comment 11 Chris Lalancette 2011-10-05 18:15:13 UTC 
I've now pushed the "check network and disk activity" patch to upstream Oz.  I've also built oz-0.7.0-3 into the fedora-aeolus-testing repositories, which also has the same patch, so this should be good-to-go for testing.
Comment 12 Aziza Karol 2011-10-10 09:00:01 UTC 
aeolus-image build successful for vsphere and rhevm

# aeolus-image build --target vsphere  --template 
fedora15_template.tpl
2011-10-10 03:09:33,989 DEBUG imgfac.qmfagent.ImageFactoryAgent.ImageFactoryAgent pid(5293) Message: Method called: name = build_image
 args = {'image': '', 'build': '', 'template': '<?xml version="1.0"?>\n<template>\n  <name>Fedora 15</name>\n  <description>desc</description>\n  <os>\n    <name>Fedora</name>\n    <arch>x86_64</arch>\n    <version>15</version>\n    <install type="url">\n      <url>http://download.fedoraproject.org/pub/fedora/linux/releases/15/Fedora/x86_64/os/</url>\n    </install>\n  </os>\n  <repositories>\n    <repository name="custom">\n      <url>http://repos.fedorapeople.org/repos/aeolus/demo/webapp/</url>\n      <signed>false</signed>\n    </repository>\n  </repositories>\n</template>\n', 'targets': ['vsphere']}
 handle = <cqmf2.AgentEvent; proxy of <Swig Object of type 'qmf::AgentEvent *' at 0x26271e0> >
 addr = redhat.com:imagefactory:39f252b4-bb76-434a-8038-ee7138b1965e:image_factory
 subtypes = {}
 userId = anonymous
2011-10-10 03:09:33,990 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-10 03:09:34,042 INFO imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Creating a bucket returned status 500.
2011-10-10 03:09:34,080 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Setting metadata ({'object_type': 'image', 'uuid': 'a1a22ffd-60ed-49b4-b4fa-246ba3ac6e93'}) for http://localhost:9090/images/a1a22ffd-60ed-49b4-b4fa-246ba3ac6e93
2011-10-10 03:09:34,172 INFO imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Creating a bucket returned status 500.
2011-10-10 03:09:34,175 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Setting metadata ({'image': 'a1a22ffd-60ed-49b4-b4fa-246ba3ac6e93', 'object_type': 'build', 'uuid': '1247818d-2c24-4130-8952-77e44d015d4d'}) for http://localhost:9090/builds/1247818d-2c24-4130-8952-77e44d015d4d
2011-10-10 03:09:35,106 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-10 03:09:35,107 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(5293) Message: build_upload() called on Fedora_vsphere_Builder...
2011-10-10 03:09:35,107 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(5293) Message: Building for target vsphere with warehouse config http://localhost:9090/
2011-10-10 03:09:35,107 DEBUG imgfac.BuildJob.BuildAdaptor pid(5293) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from NEW to BUILDING
.
.
.
2011-10-10 03:27:08,773 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 337920kB of 356208kB
2011-10-10 03:27:08,958 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 348160kB of 356208kB
2011-10-10 03:27:09,112 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 356208kB of 356208kB
2011-10-10 03:27:09,112 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 356208kB of 356208kB
2011-10-10 03:27:09,245 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 356208kB of 356208kB
2011-10-10 03:27:09,996 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 356208kB of 356208kB
2011-10-10 03:27:10,171 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 356208kB of 356208kB
2011-10-10 03:27:10,189 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Setting metadata ({'icicle': 'ead01148-de35-4645-86d9-e0eb332f423b', 'uuid': '8579882f-498d-41e5-a377-55979391bbfc', 'template': '0f7032e9-4e4b-40cc-b1d3-68e76ccd457c', 'target_parameters': 'No target parameters for cloud type vsphere', 'object_type': 'target_image', 'target': 'vsphere', 'build': '1247818d-2c24-4130-8952-77e44d015d4d'}) for http://localhost:9090/target_images/8579882f-498d-41e5-a377-55979391bbfc
2011-10-10 03:27:10,444 DEBUG imgfac.builders.BaseBuilder.Fedora_vsphere_Builder pid(5293) Message: Image warehouse storage complete
2011-10-10 03:27:10,608 DEBUG imgfac.BuildJob.BuildAdaptor pid(5293) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 50 to 100
2011-10-10 03:27:10,694 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Getting metadata (['latest_unpushed']) from http://localhost:9090/images/a1a22ffd-60ed-49b4-b4fa-246ba3ac6e93
2011-10-10 03:27:10,715 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Getting metadata (['latest_build']) from http://localhost:9090/images/a1a22ffd-60ed-49b4-b4fa-246ba3ac6e93
2011-10-10 03:27:10,811 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Setting metadata ({'latest_unpushed': '1247818d-2c24-4130-8952-77e44d015d4d'}) for http://localhost:9090/images/a1a22ffd-60ed-49b4-b4fa-246ba3ac6e93
2011-10-10 03:27:10,899 DEBUG imgfac.BuildJob.BuildAdaptor pid(5293) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to COMPLETED


# aeolus-image build --target rhevm --template 
fedora15_template.tpl
011-10-09 13:44:38,886 INFO root pid(5293) Message: Launched as daemon...
2011-10-09 13:44:39,019 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-09 13:44:39,020 INFO imgfac.qmfagent.ImageFactoryAgent.ImageFactoryAgent pid(5293) Message: image_factory has qmf/qpid address: redhat.com:imagefactory:39f252b4-bb76-434a-8038-ee7138b1965e:image_factory
2011-10-09 23:54:52,543 DEBUG imgfac.qmfagent.ImageFactoryAgent.ImageFactoryAgent pid(5293) Message: Method called: name = build_image
 args = {'image': '', 'build': '', 'template': '<?xml version="1.0"?>\n<template>\n  <name>Fedora 15</name>\n  <description>desc</description>\n  <os>\n    <name>Fedora</name>\n    <arch>x86_64</arch>\n    <version>15</version>\n    <install type="url">\n      <url>http://download.fedoraproject.org/pub/fedora/linux/releases/15/Fedora/x86_64/os/</url>\n    </install>\n  </os>\n  <repositories>\n    <repository name="custom">\n      <url>http://repos.fedorapeople.org/repos/aeolus/demo/webapp/</url>\n      <signed>false</signed>\n    </repository>\n  </repositories>\n</template>\n', 'targets': ['rhevm']}
 handle = <cqmf2.AgentEvent; proxy of <Swig Object of type 'qmf::AgentEvent *' at 0x26271e0> >
 addr = redhat.com:imagefactory:39f252b4-bb76-434a-8038-ee7138b1965e:image_factory
 subtypes = {}
 userId = anonymous
2011-10-09 23:54:52,544 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-09 23:54:52,544 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-09 23:54:52,644 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Setting metadata ({'object_type': 'image', 'uuid': '6d49718b-c3a8-41cb-aa73-c7f3f1451bdc'}) for http://localhost:9090/images/6d49718b-c3a8-41cb-aa73-c7f3f1451bdc
2011-10-09 23:54:52,746 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Setting metadata ({'image': '6d49718b-c3a8-41cb-aa73-c7f3f1451bdc', 'object_type': 'build', 'uuid': 'abbc08ba-8d3d-4cfd-bbc3-2b2e38c3f6aa'}) for http://localhost:9090/builds/abbc08ba-8d3d-4cfd-bbc3-2b2e38c3f6aa
2011-10-09 23:54:53,654 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Created Image Warehouse instance http://localhost:9090 - buckets(target_images, templates, icicles, provider_images)
2011-10-09 23:54:53,655 DEBUG imgfac.builders.BaseBuilder.Fedora_rhevm_Builder pid(5293) Message: build_upload() called on Fedora_rhevm_Builder...
2011-10-09 23:54:53,655 DEBUG imgfac.builders.BaseBuilder.Fedora_rhevm_Builder pid(5293) Message: Building for target rhevm with warehouse config http://localhost:9090/
2011-10-09 23:54:53,655 DEBUG imgfac.BuildJob.BuildAdaptor pid(5293) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from NEW to BUILDING
2011-10-09 23:54:53,658 DEBUG imgfac.builders.BaseBuilder.Fedora_rhevm_Builder pid(5293) Message: Found neither a call-time config nor a config file - doing nothing
2011-10-09 23:54:53,719 DEBUG oz.Guest.FedoraGuest pid(5293) Message: libvirt bridge name is virbr0
2011-10-09 23:54:53,962 DEBUG oz.Guest.FedoraGuest pid(5293) Message: Libvirt type is kvm
.
.
.
.
.
2011-10-10 00:13:11,063 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10424320kB of 10485760kB
2011-10-10 00:13:11,197 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10434560kB of 10485760kB
2011-10-10 00:13:11,328 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10444800kB of 10485760kB
2011-10-10 00:13:11,460 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10455040kB of 10485760kB
2011-10-10 00:13:11,596 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10465280kB of 10485760kB
2011-10-10 00:13:11,729 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10475520kB of 10485760kB
2011-10-10 00:13:11,947 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10485760kB of 10485760kB
2011-10-10 00:13:11,947 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10485760kB of 10485760kB
2011-10-10 00:13:12,405 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10485760kB of 10485760kB
2011-10-10 00:13:12,406 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10485760kB of 10485760kB
2011-10-10 00:13:12,406 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: 10485760kB of 10485760kB
2011-10-10 00:13:12,442 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Setting metadata ({'icicle': 'f53cb324-b79a-43ca-aa02-ac2b821acef9', 'uuid': '2514f6a4-3a51-4a2d-9b20-c4243aaa9a6a', 'template': '67ff0a78-2eb6-4b64-bc6f-724a82c17fd9', 'target_parameters': '<?xml version="1.0"?>\n<domain type="kvm">\n  <name>Fedora 15-2514f6a4-3a51-4a2d-9b20-c4243aaa9a6a</name>\n  <memory>1048576</memory>\n  <currentMemory>1048576</currentMemory>\n  <uuid>70cf028d-9c2d-44a1-86e1-14bd39ad04c1</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:89:ab:fb"/>\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="18974"/>\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-2514f6a4-3a51-4a2d-9b20-c4243aaa9a6a.dsk"/>\n    </disk>\n  </devices>\n</domain>\n', 'object_type': 'target_image', 'target': 'rhevm', 'build': 'abbc08ba-8d3d-4cfd-bbc3-2b2e38c3f6aa'}) for http://localhost:9090/target_images/2514f6a4-3a51-4a2d-9b20-c4243aaa9a6a
2011-10-10 00:13:12,924 DEBUG imgfac.builders.BaseBuilder.Fedora_rhevm_Builder pid(5293) Message: Image warehouse storage complete
2011-10-10 00:13:12,936 DEBUG imgfac.BuildJob.BuildAdaptor pid(5293) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed percent complete from 50 to 100
2011-10-10 00:13:12,978 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Getting metadata (['latest_unpushed']) from http://localhost:9090/images/6d49718b-c3a8-41cb-aa73-c7f3f1451bdc
2011-10-10 00:13:12,987 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Getting metadata (['latest_build']) from http://localhost:9090/images/6d49718b-c3a8-41cb-aa73-c7f3f1451bdc
2011-10-10 00:13:13,020 DEBUG imgfac.ImageWarehouse.ImageWarehouse pid(5293) Message: Setting metadata ({'latest_unpushed': 'abbc08ba-8d3d-4cfd-bbc3-2b2e38c3f6aa'}) for http://localhost:9090/images/6d49718b-c3a8-41cb-aa73-c7f3f1451bdc
2011-10-10 00:13:13,022 DEBUG imgfac.BuildJob.BuildAdaptor pid(5293) Message: Raising event with agent handler (<ImageFactoryAgent(Thread-1, initial)>), changed status from BUILDING to COMPLETED


[root@dell-pe1950-02 log]# rpm -qa | egrep 'aeolus|iwhd|factory|oz'
aeolus-conductor-0.5.0-0.20111007200330gite77b251.el6.noarch
imagefactory-jeosconf-ec2-rhel-0.1.0-1.el6.noarch
rubygem-activerecord-3.0.9-1.aeolus.el6.noarch
rubygem-ZenTest-4.3.3-2.aeolus.el6.noarch
rubygem-actionmailer-3.0.9-1.aeolus.el6.noarch
aeolus-conductor-doc-0.5.0-0.20111007200330gite77b251.el6.noarch
imagefactory-jeosconf-ec2-fedora-0.1.0-1.el6.noarch
rubygem-activesupport-3.0.9-1.aeolus.el6.noarch
rubygem-arel-2.0.10-0.aeolus.el6.noarch
aeolus-configure-2.2.0-1.20111007151942git26136f2.el6.noarch
iwhd-0.98.15.z3-2.el6.x86_64
rubygem-imagefactory-console-0.5.0-4.20110824113238gitd9debef.el6.noarch
rubygem-aeolus-cli-0.1.0-3.20111003133323git9451323.el6.noarch
oz-0.7.0-3.el6.noarch
aeolus-all-0.5.0-0.20111007200330gite77b251.el6.noarch
rubygem-aeolus-image-0.1.0-3.20111003170706git8f23238.el6.noarch
aeolus-conductor-daemons-0.5.0-0.20111007200330gite77b251.el6.noarch
imagefactory-0.7.0-1.el6.noarch
rubygem-rack-mount-0.7.1-3.aeolus.el6.noarch
Comment 15 errata-xmlrpc 2012-05-15 19:43:49 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.